{"level":"warn","ts":"2026-03-10T13:43:10.670653Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-10T13:43:10.670737Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-03-10T13:43:10.670798Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-10T13:43:10.67081Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-03-10T13:43:10.670826Z","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-03-10T13:43:10.671464Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-03-10T13:43:10.671533Z","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":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-03-10T13:43:10.674959Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.231083ms"} {"level":"info","ts":"2026-03-10T13:43:10.684563Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-03-10T13:43:10.684636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-03-10T13:43:10.684659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-03-10T13:43:10.684669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-10T13:43:10.684681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-03-10T13:43:10.684716Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-03-10T13:43:10.691207Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-10T13:43:10.692367Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-10T13:43:10.693325Z","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-03-10T13:43:10.695619Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-10T13:43:10.695894Z","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-03-10T13:43:10.695973Z","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-03-10T13:43:10.695984Z","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-03-10T13:43:10.696114Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-10T13:43:10.698156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-03-10T13:43:10.698263Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-03-10T13:43:10.703594Z","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-03-10T13:43:10.70377Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-03-10T13:43:10.703896Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-10T13:43:10.703929Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-10T13:43:10.703923Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-03-10T13:43:10.885879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-03-10T13:43:10.88618Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-03-10T13:43:10.886374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-03-10T13:43:10.886554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-03-10T13:43:10.886651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-03-10T13:43:10.886846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-03-10T13:43:10.886985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-03-10T13:43:10.888484Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-03-10T13:43:10.88858Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-10T13:43:10.888524Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-10T13:43:10.888578Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-10T13:43:10.889078Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-10T13:43:10.889263Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-10T13:43:10.890153Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-03-10T13:43:10.890374Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-10T13:43:10.890529Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-10T13:43:10.892617Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-10T13:43:10.894641Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-03-10T13:43:12.7006Z","caller":"traceutil/trace.go:171","msg":"trace[1349981017] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"167.788087ms","start":"2026-03-10T13:43:12.532776Z","end":"2026-03-10T13:43:12.700564Z","steps":["trace[1349981017] 'read index received' (duration: 167.448629ms)","trace[1349981017] 'applied index is now lower than readState.Index' (duration: 337.838µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:43:12.700818Z","caller":"traceutil/trace.go:171","msg":"trace[1412690785] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"168.35969ms","start":"2026-03-10T13:43:12.532322Z","end":"2026-03-10T13:43:12.700681Z","steps":["trace[1412690785] 'process raft request' (duration: 167.886409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:12.700963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.072834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-10T13:43:12.701082Z","caller":"traceutil/trace.go:171","msg":"trace[548718836] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:2; }","duration":"168.276908ms","start":"2026-03-10T13:43:12.532762Z","end":"2026-03-10T13:43:12.701039Z","steps":["trace[548718836] 'agreement among raft nodes before linearized reading' (duration: 167.981801ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:13.20181Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605827204917779583,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-10T13:43:13.702519Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605827204917779583,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-10T13:43:13.755025Z","caller":"traceutil/trace.go:171","msg":"trace[1826600605] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.222218168s","start":"2026-03-10T13:43:12.532769Z","end":"2026-03-10T13:43:13.754987Z","steps":["trace[1826600605] 'process raft request' (duration: 1.203089533s)","trace[1826600605] 'compare' (duration: 18.835509ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:43:13.755095Z","caller":"traceutil/trace.go:171","msg":"trace[2054166210] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.222169208s","start":"2026-03-10T13:43:12.53287Z","end":"2026-03-10T13:43:13.755039Z","steps":["trace[2054166210] 'process raft request' (duration: 1.222043535s)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:43:13.755145Z","caller":"traceutil/trace.go:171","msg":"trace[873484100] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.222150446s","start":"2026-03-10T13:43:12.532932Z","end":"2026-03-10T13:43:13.755083Z","steps":["trace[873484100] 'process raft request' (duration: 1.222019254s)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:43:13.755299Z","caller":"traceutil/trace.go:171","msg":"trace[1548352065] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.222272059s","start":"2026-03-10T13:43:12.533017Z","end":"2026-03-10T13:43:13.755289Z","steps":["trace[1548352065] 'process raft request' (duration: 1.222012603s)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:43:13.755538Z","caller":"traceutil/trace.go:171","msg":"trace[256083023] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.222270459s","start":"2026-03-10T13:43:12.53298Z","end":"2026-03-10T13:43:13.755251Z","steps":["trace[256083023] 'process raft request' (duration: 1.222017474s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:13.755782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:12.532972Z","time spent":"1.222700069s","remote":"127.0.0.1:40388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-10T13:43:13.755881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:12.532862Z","time spent":"1.222318991s","remote":"127.0.0.1:39970","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:43:13.756015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:12.532927Z","time spent":"1.22230917s","remote":"127.0.0.1:40388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-10T13:43:13.755821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:12.532761Z","time spent":"1.222348921s","remote":"127.0.0.1:39974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:43:13.758041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:12.532996Z","time spent":"1.222343121s","remote":"127.0.0.1:40388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-10T13:43:13.75926Z","caller":"traceutil/trace.go:171","msg":"trace[963746935] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.137380749s","start":"2026-03-10T13:43:12.62186Z","end":"2026-03-10T13:43:13.759241Z","steps":["trace[963746935] 'process raft request' (duration: 1.137340998s)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:43:13.759631Z","caller":"traceutil/trace.go:171","msg":"trace[1395554735] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.151801087s","start":"2026-03-10T13:43:12.607814Z","end":"2026-03-10T13:43:13.759615Z","steps":["trace[1395554735] 'process raft request' (duration: 1.151287205s)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:43:13.761074Z","caller":"traceutil/trace.go:171","msg":"trace[416883150] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.224710324s","start":"2026-03-10T13:43:12.534986Z","end":"2026-03-10T13:43:13.759696Z","steps":["trace[416883150] 'process raft request' (duration: 1.220068739s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:13.761143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:12.60772Z","time spent":"1.15195179s","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-10T13:43:13.763996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:12.534967Z","time spent":"1.228985222s","remote":"127.0.0.1:40388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-10T13:43:13.761273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:12.621828Z","time spent":"1.137530773s","remote":"127.0.0.1:40062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-10T13:43:13.761352Z","caller":"traceutil/trace.go:171","msg":"trace[1403350044] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.151810497s","start":"2026-03-10T13:43:12.609356Z","end":"2026-03-10T13:43:13.761166Z","steps":["trace[1403350044] 'process raft request' (duration: 1.149809381s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:13.764462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:12.609341Z","time spent":"1.154900517s","remote":"127.0.0.1:40134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-10T13:43:14.275249Z","caller":"traceutil/trace.go:171","msg":"trace[743668038] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"1.569964706s","start":"2026-03-10T13:43:12.705259Z","end":"2026-03-10T13:43:14.275223Z","steps":["trace[743668038] 'process raft request' (duration: 1.564275807s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:14.275374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:12.70524Z","time spent":"1.57007632s","remote":"127.0.0.1:40388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-10T13:43:14.275372Z","caller":"traceutil/trace.go:171","msg":"trace[1339634500] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:5; }","duration":"1.574559361s","start":"2026-03-10T13:43:12.700709Z","end":"2026-03-10T13:43:14.275268Z","steps":["trace[1339634500] 'read index received' (duration: 1.035257666s)","trace[1339634500] 'applied index is now lower than readState.Index' (duration: 539.288865ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:43:14.275477Z","caller":"traceutil/trace.go:171","msg":"trace[1633674937] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"509.14646ms","start":"2026-03-10T13:43:13.766323Z","end":"2026-03-10T13:43:14.27547Z","steps":["trace[1633674937] 'process raft request' (duration: 508.909145ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:14.275511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:13.766293Z","time spent":"509.202951ms","remote":"127.0.0.1:40388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-10T13:43:14.275475Z","caller":"traceutil/trace.go:171","msg":"trace[1966120806] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"481.752917ms","start":"2026-03-10T13:43:13.793697Z","end":"2026-03-10T13:43:14.27545Z","steps":["trace[1966120806] 'process raft request' (duration: 481.697345ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:43:14.275552Z","caller":"traceutil/trace.go:171","msg":"trace[1164997201] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"507.277438ms","start":"2026-03-10T13:43:13.768218Z","end":"2026-03-10T13:43:14.275495Z","steps":["trace[1164997201] 'process raft request' (duration: 507.075723ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:43:14.275644Z","caller":"traceutil/trace.go:171","msg":"trace[1590926761] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"509.990359ms","start":"2026-03-10T13:43:13.765647Z","end":"2026-03-10T13:43:14.275638Z","steps":["trace[1590926761] 'process raft request' (duration: 509.548129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:14.275646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:13.793672Z","time spent":"481.90124ms","remote":"127.0.0.1:40062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:43:14.275683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:13.765622Z","time spent":"510.03999ms","remote":"127.0.0.1:40388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-10T13:43:14.275733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:13.768207Z","time spent":"507.423121ms","remote":"127.0.0.1:40388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-10T13:43:14.275733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.617970969s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-10T13:43:14.275739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.694469039s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-10T13:43:14.275821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.742835649s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-10T13:43:14.275842Z","caller":"traceutil/trace.go:171","msg":"trace[777539766] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:18; }","duration":"1.74288056s","start":"2026-03-10T13:43:12.532955Z","end":"2026-03-10T13:43:14.275835Z","steps":["trace[777539766] 'agreement among raft nodes before linearized reading' (duration: 1.7428464s)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:43:14.27583Z","caller":"traceutil/trace.go:171","msg":"trace[60609605] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"1.694571381s","start":"2026-03-10T13:43:12.581236Z","end":"2026-03-10T13:43:14.275808Z","steps":["trace[60609605] 'agreement among raft nodes before linearized reading' (duration: 1.694341495s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:14.275882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:12.532939Z","time spent":"1.742936501s","remote":"127.0.0.1:40284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-03-10T13:43:14.275892Z","caller":"traceutil/trace.go:171","msg":"trace[1910742574] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"509.364494ms","start":"2026-03-10T13:43:13.766521Z","end":"2026-03-10T13:43:14.275885Z","steps":["trace[1910742574] 'process raft request' (duration: 508.74922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:14.275899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:12.581203Z","time spent":"1.694686524s","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-10T13:43:14.275928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:13.766487Z","time spent":"509.426546ms","remote":"127.0.0.1:40388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-10T13:43:14.276018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.511649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-10T13:43:14.276039Z","caller":"traceutil/trace.go:171","msg":"trace[823776292] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:18; }","duration":"772.571991ms","start":"2026-03-10T13:43:13.503461Z","end":"2026-03-10T13:43:14.276033Z","steps":["trace[823776292] 'agreement among raft nodes before linearized reading' (duration: 772.52525ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:14.276057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:13.503429Z","time spent":"772.624092ms","remote":"127.0.0.1:40232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-03-10T13:43:14.276059Z","caller":"traceutil/trace.go:171","msg":"trace[2111787512] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"507.782139ms","start":"2026-03-10T13:43:13.768265Z","end":"2026-03-10T13:43:14.276047Z","steps":["trace[2111787512] 'process raft request' (duration: 507.083343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:14.276086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.668007978s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-10T13:43:14.276114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:13.768248Z","time spent":"507.83998ms","remote":"127.0.0.1:40316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-10T13:43:14.27612Z","caller":"traceutil/trace.go:171","msg":"trace[1582404280] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:18; }","duration":"1.668073669s","start":"2026-03-10T13:43:12.608035Z","end":"2026-03-10T13:43:14.276108Z","steps":["trace[1582404280] 'agreement among raft nodes before linearized reading' (duration: 1.668016197s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:14.276148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:12.608024Z","time spent":"1.668118159s","remote":"127.0.0.1:39984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-03-10T13:43:14.276226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.002938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-tqb89\" ","response":"range_response_count:1 size:857"} {"level":"info","ts":"2026-03-10T13:43:14.276294Z","caller":"traceutil/trace.go:171","msg":"trace[1453069848] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-tqb89; range_end:; response_count:1; response_revision:18; }","duration":"495.119221ms","start":"2026-03-10T13:43:13.781166Z","end":"2026-03-10T13:43:14.276285Z","steps":["trace[1453069848] 'agreement among raft nodes before linearized reading' (duration: 494.994968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:14.27632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:13.781145Z","time spent":"495.168122ms","remote":"127.0.0.1:40134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":880,"request content":"key:\"/registry/certificatesigningrequests/csr-tqb89\" "} {"level":"warn","ts":"2026-03-10T13:43:14.27634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.429894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-10T13:43:14.276369Z","caller":"traceutil/trace.go:171","msg":"trace[486418245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18; }","duration":"315.484095ms","start":"2026-03-10T13:43:13.960876Z","end":"2026-03-10T13:43:14.27636Z","steps":["trace[486418245] 'agreement among raft nodes before linearized reading' (duration: 315.441554ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:14.276422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:13.96086Z","time spent":"315.557917ms","remote":"127.0.0.1:39912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-10T13:43:14.276594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.030061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-10T13:43:14.276627Z","caller":"traceutil/trace.go:171","msg":"trace[1812145896] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:18; }","duration":"773.098013ms","start":"2026-03-10T13:43:13.503521Z","end":"2026-03-10T13:43:14.276619Z","steps":["trace[1812145896] 'agreement among raft nodes before linearized reading' (duration: 773.046372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:14.276645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:13.5035Z","time spent":"773.140954ms","remote":"127.0.0.1:40252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-03-10T13:43:14.27666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.671067377s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-10T13:43:14.275783Z","caller":"traceutil/trace.go:171","msg":"trace[463714188] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"1.618092052s","start":"2026-03-10T13:43:12.657677Z","end":"2026-03-10T13:43:14.275769Z","steps":["trace[463714188] 'agreement among raft nodes before linearized reading' (duration: 1.617882717s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:14.276701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.670699789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-10T13:43:14.276728Z","caller":"traceutil/trace.go:171","msg":"trace[138302298] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"1.67074445s","start":"2026-03-10T13:43:12.605976Z","end":"2026-03-10T13:43:14.27672Z","steps":["trace[138302298] 'agreement among raft nodes before linearized reading' (duration: 1.670671118s)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:43:14.276721Z","caller":"traceutil/trace.go:171","msg":"trace[899966933] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:0; response_revision:18; }","duration":"1.671164209s","start":"2026-03-10T13:43:12.60554Z","end":"2026-03-10T13:43:14.276704Z","steps":["trace[899966933] 'agreement among raft nodes before linearized reading' (duration: 1.671081307s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:14.276749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:12.605966Z","time spent":"1.67077693s","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-10T13:43:14.276767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:12.605523Z","time spent":"1.67123329s","remote":"127.0.0.1:39936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"warn","ts":"2026-03-10T13:43:14.276755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:12.657654Z","time spent":"1.619073064s","remote":"127.0.0.1:40014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-10T13:43:41.357445Z","caller":"traceutil/trace.go:171","msg":"trace[1200428651] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"158.360182ms","start":"2026-03-10T13:43:41.199069Z","end":"2026-03-10T13:43:41.357429Z","steps":["trace[1200428651] 'process raft request' (duration: 158.26332ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:43:42.487784Z","caller":"traceutil/trace.go:171","msg":"trace[485178744] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"395.074637ms","start":"2026-03-10T13:43:42.09269Z","end":"2026-03-10T13:43:42.487764Z","steps":["trace[485178744] 'process raft request' (duration: 394.956765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:42.48791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:42.092669Z","time spent":"395.179049ms","remote":"127.0.0.1:40062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4368,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T13:43:43.256778Z","caller":"traceutil/trace.go:171","msg":"trace[706899745] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"237.131014ms","start":"2026-03-10T13:43:43.019629Z","end":"2026-03-10T13:43:43.25676Z","steps":["trace[706899745] 'read index received' (duration: 236.996581ms)","trace[706899745] 'applied index is now lower than readState.Index' (duration: 133.743µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:43:43.257056Z","caller":"traceutil/trace.go:171","msg":"trace[709712452] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"428.273042ms","start":"2026-03-10T13:43:42.828775Z","end":"2026-03-10T13:43:43.257048Z","steps":["trace[709712452] 'process raft request' (duration: 427.898073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:43.25716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:42.828764Z","time spent":"428.343883ms","remote":"127.0.0.1:40140","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-03-10T13:43:43.257289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.701384ms","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-03-10T13:43:43.257502Z","caller":"traceutil/trace.go:171","msg":"trace[394941017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:472; }","duration":"218.93927ms","start":"2026-03-10T13:43:43.038553Z","end":"2026-03-10T13:43:43.257493Z","steps":["trace[394941017] 'agreement among raft nodes before linearized reading' (duration: 218.660374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:43.257338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.709137ms","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-03-10T13:43:43.257653Z","caller":"traceutil/trace.go:171","msg":"trace[1569648208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:472; }","duration":"238.053976ms","start":"2026-03-10T13:43:43.019592Z","end":"2026-03-10T13:43:43.257646Z","steps":["trace[1569648208] 'agreement among raft nodes before linearized reading' (duration: 237.712828ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:43:43.748926Z","caller":"traceutil/trace.go:171","msg":"trace[543744246] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:487; }","duration":"387.499774ms","start":"2026-03-10T13:43:43.361403Z","end":"2026-03-10T13:43:43.748903Z","steps":["trace[543744246] 'read index received' (duration: 379.967452ms)","trace[543744246] 'applied index is now lower than readState.Index' (duration: 7.531182ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:43:43.74925Z","caller":"traceutil/trace.go:171","msg":"trace[871038988] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"475.725741ms","start":"2026-03-10T13:43:43.273508Z","end":"2026-03-10T13:43:43.749234Z","steps":["trace[871038988] 'process raft request' (duration: 475.349593ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:43.749399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.983615ms","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-03-10T13:43:43.749453Z","caller":"traceutil/trace.go:171","msg":"trace[1663445099] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"388.077607ms","start":"2026-03-10T13:43:43.361361Z","end":"2026-03-10T13:43:43.749439Z","steps":["trace[1663445099] 'agreement among raft nodes before linearized reading' (duration: 387.910083ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:43.749413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:43.27348Z","time spent":"475.863274ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:43:43.749494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:43.361315Z","time spent":"388.171479ms","remote":"127.0.0.1:40140","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":"info","ts":"2026-03-10T13:43:43.749623Z","caller":"traceutil/trace.go:171","msg":"trace[569982050] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"480.131361ms","start":"2026-03-10T13:43:43.268839Z","end":"2026-03-10T13:43:43.748971Z","steps":["trace[569982050] 'process raft request' (duration: 472.533649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:43.749853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:43.26882Z","time spent":"480.96756ms","remote":"127.0.0.1:40140","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-03-10T13:43:43.749959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.801662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:43:43.750694Z","caller":"traceutil/trace.go:171","msg":"trace[390185535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:474; }","duration":"312.597521ms","start":"2026-03-10T13:43:43.438071Z","end":"2026-03-10T13:43:43.750669Z","steps":["trace[390185535] 'agreement among raft nodes before linearized reading' (duration: 311.792123ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:43.750768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:43.438057Z","time spent":"312.694453ms","remote":"127.0.0.1:39912","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-03-10T13:43:50.175044Z","caller":"traceutil/trace.go:171","msg":"trace[2092345141] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"384.125617ms","start":"2026-03-10T13:43:49.790889Z","end":"2026-03-10T13:43:50.175014Z","steps":["trace[2092345141] 'process raft request' (duration: 383.896002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:50.17519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:49.790882Z","time spent":"384.2402ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T13:43:50.175064Z","caller":"traceutil/trace.go:171","msg":"trace[818900838] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"383.508513ms","start":"2026-03-10T13:43:49.791535Z","end":"2026-03-10T13:43:50.175044Z","steps":["trace[818900838] 'process raft request' (duration: 383.405601ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:50.175313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:49.791529Z","time spent":"383.724818ms","remote":"127.0.0.1:40140","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-03-10T13:43:50.354615Z","caller":"traceutil/trace.go:171","msg":"trace[346067196] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"165.31242ms","start":"2026-03-10T13:43:50.189278Z","end":"2026-03-10T13:43:50.35459Z","steps":["trace[346067196] 'process raft request' (duration: 165.114176ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:50.687303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.060167ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-10T13:43:50.687403Z","caller":"traceutil/trace.go:171","msg":"trace[1288779760] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:511; }","duration":"246.544538ms","start":"2026-03-10T13:43:50.440842Z","end":"2026-03-10T13:43:50.687387Z","steps":["trace[1288779760] 'read index received' (duration: 247.606µs)","trace[1288779760] 'applied index is now lower than readState.Index' (duration: 246.295652ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:43:50.687466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.62391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:43:50.687494Z","caller":"traceutil/trace.go:171","msg":"trace[33431104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:495; }","duration":"246.693672ms","start":"2026-03-10T13:43:50.440793Z","end":"2026-03-10T13:43:50.687486Z","steps":["trace[33431104] 'agreement among raft nodes before linearized reading' (duration: 246.63772ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:43:52.285039Z","caller":"traceutil/trace.go:171","msg":"trace[2107466061] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"975.805765ms","start":"2026-03-10T13:43:51.3092Z","end":"2026-03-10T13:43:52.285006Z","steps":["trace[2107466061] 'process raft request' (duration: 975.512188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:52.285225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:51.30918Z","time spent":"975.945489ms","remote":"127.0.0.1:40140","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-03-10T13:43:52.285283Z","caller":"traceutil/trace.go:171","msg":"trace[1375526346] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"485.164686ms","start":"2026-03-10T13:43:51.800085Z","end":"2026-03-10T13:43:52.28525Z","steps":["trace[1375526346] 'read index received' (duration: 484.538681ms)","trace[1375526346] 'applied index is now lower than readState.Index' (duration: 624.635µs)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:43:52.285457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.349871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-10T13:43:52.285494Z","caller":"traceutil/trace.go:171","msg":"trace[1196676879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"485.444433ms","start":"2026-03-10T13:43:51.800037Z","end":"2026-03-10T13:43:52.285482Z","steps":["trace[1196676879] 'agreement among raft nodes before linearized reading' (duration: 485.352021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:52.28553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:51.800013Z","time spent":"485.508194ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-10T13:43:52.285733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.383509ms","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-03-10T13:43:52.285777Z","caller":"traceutil/trace.go:171","msg":"trace[1933593819] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"106.490242ms","start":"2026-03-10T13:43:52.179274Z","end":"2026-03-10T13:43:52.285764Z","steps":["trace[1933593819] 'agreement among raft nodes before linearized reading' (duration: 106.319719ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:52.285954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.018702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-10T13:43:52.285987Z","caller":"traceutil/trace.go:171","msg":"trace[619357079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:496; }","duration":"106.112053ms","start":"2026-03-10T13:43:52.179864Z","end":"2026-03-10T13:43:52.285976Z","steps":["trace[619357079] 'agreement among raft nodes before linearized reading' (duration: 105.97484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:52.940639Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605827204917780543,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-10T13:43:53.298056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.737945ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-10T13:43:53.298501Z","caller":"traceutil/trace.go:171","msg":"trace[1835473974] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"1.008160252s","start":"2026-03-10T13:43:52.290319Z","end":"2026-03-10T13:43:53.29848Z","steps":["trace[1835473974] 'process raft request' (duration: 821.730522ms)","trace[1835473974] 'compare' (duration: 184.456335ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:43:53.298716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:52.290285Z","time spent":"1.008387968s","remote":"127.0.0.1:40140","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-03-10T13:43:53.462165Z","caller":"traceutil/trace.go:171","msg":"trace[301273532] linearizableReadLoop","detail":"{readStateIndex:516; appliedIndex:513; }","duration":"1.022079339s","start":"2026-03-10T13:43:52.440067Z","end":"2026-03-10T13:43:53.462147Z","steps":["trace[301273532] 'read index received' (duration: 671.854043ms)","trace[301273532] 'applied index is now lower than readState.Index' (duration: 350.224646ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:43:53.46225Z","caller":"traceutil/trace.go:171","msg":"trace[2038114177] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.171324574s","start":"2026-03-10T13:43:52.290895Z","end":"2026-03-10T13:43:53.46222Z","steps":["trace[2038114177] 'process raft request' (duration: 1.17115015s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:53.462397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022291394s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:43:53.462477Z","caller":"traceutil/trace.go:171","msg":"trace[993685668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:499; }","duration":"1.022420347s","start":"2026-03-10T13:43:52.440017Z","end":"2026-03-10T13:43:53.462437Z","steps":["trace[993685668] 'agreement among raft nodes before linearized reading' (duration: 1.022288504s)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:43:53.462496Z","caller":"traceutil/trace.go:171","msg":"trace[2045110493] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.171404755s","start":"2026-03-10T13:43:52.291085Z","end":"2026-03-10T13:43:53.46249Z","steps":["trace[2045110493] 'process raft request' (duration: 1.171034927s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:53.462608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:52.291043Z","time spent":"1.171541238s","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:43:53.462519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:52.439991Z","time spent":"1.022518729s","remote":"127.0.0.1:39912","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-03-10T13:43:53.462408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:43:52.290865Z","time spent":"1.171489707s","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:43:54.440169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.919044ms","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-03-10T13:43:54.440278Z","caller":"traceutil/trace.go:171","msg":"trace[1201349850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:503; }","duration":"148.110449ms","start":"2026-03-10T13:43:54.292137Z","end":"2026-03-10T13:43:54.440247Z","steps":["trace[1201349850] 'range keys from in-memory index tree' (duration: 147.71191ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:43:55.41639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.508391ms","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-03-10T13:43:55.4166Z","caller":"traceutil/trace.go:171","msg":"trace[1508065375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"113.769047ms","start":"2026-03-10T13:43:55.302775Z","end":"2026-03-10T13:43:55.416544Z","steps":["trace[1508065375] 'range keys from in-memory index tree' (duration: 113.253786ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:44:05.329468Z","caller":"traceutil/trace.go:171","msg":"trace[779286098] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"242.432094ms","start":"2026-03-10T13:44:05.087016Z","end":"2026-03-10T13:44:05.329448Z","steps":["trace[779286098] 'read index received' (duration: 242.313281ms)","trace[779286098] 'applied index is now lower than readState.Index' (duration: 118.243µs)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:44:05.329608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.589257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-10T13:44:05.329628Z","caller":"traceutil/trace.go:171","msg":"trace[1537378827] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:625; }","duration":"242.661069ms","start":"2026-03-10T13:44:05.086961Z","end":"2026-03-10T13:44:05.329622Z","steps":["trace[1537378827] 'agreement among raft nodes before linearized reading' (duration: 242.567746ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:44:05.32972Z","caller":"traceutil/trace.go:171","msg":"trace[1058274299] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"365.732388ms","start":"2026-03-10T13:44:04.963967Z","end":"2026-03-10T13:44:05.329699Z","steps":["trace[1058274299] 'process raft request' (duration: 365.38485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:05.329841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:04.963947Z","time spent":"365.822071ms","remote":"127.0.0.1:40140","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-03-10T13:44:10.856214Z","caller":"traceutil/trace.go:171","msg":"trace[820978083] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"312.500382ms","start":"2026-03-10T13:44:10.543667Z","end":"2026-03-10T13:44:10.856168Z","steps":["trace[820978083] 'process raft request' (duration: 312.206095ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:10.856425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:10.543639Z","time spent":"312.669636ms","remote":"127.0.0.1:40140","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-03-10T13:44:12.087483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.399634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:44:12.087599Z","caller":"traceutil/trace.go:171","msg":"trace[544130820] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"647.562118ms","start":"2026-03-10T13:44:11.440015Z","end":"2026-03-10T13:44:12.087577Z","steps":["trace[544130820] 'range keys from in-memory index tree' (duration: 647.306002ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:12.087655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:11.439998Z","time spent":"647.64613ms","remote":"127.0.0.1:39912","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-03-10T13:44:12.087685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.648776ms","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-03-10T13:44:12.087753Z","caller":"traceutil/trace.go:171","msg":"trace[1357577026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:681; }","duration":"593.759628ms","start":"2026-03-10T13:44:11.493974Z","end":"2026-03-10T13:44:12.087734Z","steps":["trace[1357577026] 'range keys from in-memory index tree' (duration: 593.507672ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:12.087792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:11.493967Z","time spent":"593.814379ms","remote":"127.0.0.1:40140","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-03-10T13:44:12.087845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.114245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-10T13:44:12.087891Z","caller":"traceutil/trace.go:171","msg":"trace[551490343] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:681; }","duration":"535.191797ms","start":"2026-03-10T13:44:11.552686Z","end":"2026-03-10T13:44:12.087877Z","steps":["trace[551490343] 'range keys from in-memory index tree' (duration: 534.991713ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:12.087904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.652854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-10T13:44:12.087924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:11.55267Z","time spent":"535.245778ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-10T13:44:12.087973Z","caller":"traceutil/trace.go:171","msg":"trace[1936632922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:681; }","duration":"537.766387ms","start":"2026-03-10T13:44:11.550186Z","end":"2026-03-10T13:44:12.087952Z","steps":["trace[1936632922] 'range keys from in-memory index tree' (duration: 537.50094ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:12.088018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:11.550165Z","time spent":"537.846238ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-10T13:44:12.088038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.27122ms","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-03-10T13:44:12.088083Z","caller":"traceutil/trace.go:171","msg":"trace[1473357202] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:681; }","duration":"739.353702ms","start":"2026-03-10T13:44:11.348719Z","end":"2026-03-10T13:44:12.088073Z","steps":["trace[1473357202] 'range keys from in-memory index tree' (duration: 739.153636ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:12.088169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:11.348699Z","time spent":"739.461614ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-10T13:44:12.280971Z","caller":"traceutil/trace.go:171","msg":"trace[1833753119] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"188.306816ms","start":"2026-03-10T13:44:12.092639Z","end":"2026-03-10T13:44:12.280945Z","steps":["trace[1833753119] 'process raft request' (duration: 188.175753ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:13.11251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.232098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-10T13:44:13.112714Z","caller":"traceutil/trace.go:171","msg":"trace[1672268432] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:705; }","duration":"253.313976ms","start":"2026-03-10T13:44:12.859383Z","end":"2026-03-10T13:44:13.112697Z","steps":["trace[1672268432] 'read index received' (duration: 35.792382ms)","trace[1672268432] 'applied index is now lower than readState.Index' (duration: 217.520624ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:44:13.112689Z","caller":"traceutil/trace.go:171","msg":"trace[717323403] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"1.019717465s","start":"2026-03-10T13:44:12.092934Z","end":"2026-03-10T13:44:13.112652Z","steps":["trace[717323403] 'process raft request' (duration: 802.256952ms)","trace[717323403] 'compare' (duration: 217.104055ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:44:13.112814Z","caller":"traceutil/trace.go:171","msg":"trace[1817609258] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"1.003115509s","start":"2026-03-10T13:44:12.109689Z","end":"2026-03-10T13:44:13.112804Z","steps":["trace[1817609258] 'process raft request' (duration: 1.002957875s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:13.112861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:12.092915Z","time spent":"1.019873468s","remote":"127.0.0.1:40140","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-03-10T13:44:13.112892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:12.109679Z","time spent":"1.003176401s","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T13:44:13.112709Z","caller":"traceutil/trace.go:171","msg":"trace[382759159] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"1.004650555s","start":"2026-03-10T13:44:12.10804Z","end":"2026-03-10T13:44:13.11269Z","steps":["trace[382759159] 'process raft request' (duration: 1.004543753s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:13.112979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:12.107932Z","time spent":"1.005001233s","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:44:13.113065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.651156ms","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-03-10T13:44:13.113113Z","caller":"traceutil/trace.go:171","msg":"trace[807008289] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:685; }","duration":"229.704758ms","start":"2026-03-10T13:44:12.883395Z","end":"2026-03-10T13:44:13.1131Z","steps":["trace[807008289] 'agreement among raft nodes before linearized reading' (duration: 229.615945ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:13.113201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.820338ms","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-03-10T13:44:13.113241Z","caller":"traceutil/trace.go:171","msg":"trace[653283681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:685; }","duration":"253.88084ms","start":"2026-03-10T13:44:12.85935Z","end":"2026-03-10T13:44:13.113231Z","steps":["trace[653283681] 'agreement among raft nodes before linearized reading' (duration: 253.711076ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:44:13.429285Z","caller":"traceutil/trace.go:171","msg":"trace[956447741] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:708; }","duration":"212.62861ms","start":"2026-03-10T13:44:13.216636Z","end":"2026-03-10T13:44:13.429265Z","steps":["trace[956447741] 'read index received' (duration: 122.23815ms)","trace[956447741] 'applied index is now lower than readState.Index' (duration: 90.3894ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:44:13.429399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.758014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:44:13.429391Z","caller":"traceutil/trace.go:171","msg":"trace[313198769] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"312.883001ms","start":"2026-03-10T13:44:13.116483Z","end":"2026-03-10T13:44:13.429366Z","steps":["trace[313198769] 'process raft request' (duration: 222.418479ms)","trace[313198769] 'compare' (duration: 90.282208ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:44:13.429421Z","caller":"traceutil/trace.go:171","msg":"trace[1584977012] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:686; }","duration":"212.866176ms","start":"2026-03-10T13:44:13.216548Z","end":"2026-03-10T13:44:13.429414Z","steps":["trace[1584977012] 'agreement among raft nodes before linearized reading' (duration: 212.793694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:13.429514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:13.116464Z","time spent":"312.992643ms","remote":"127.0.0.1:40140","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-03-10T13:44:13.748628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.10268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-10T13:44:13.748719Z","caller":"traceutil/trace.go:171","msg":"trace[208008202] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:686; }","duration":"206.253293ms","start":"2026-03-10T13:44:13.542444Z","end":"2026-03-10T13:44:13.748697Z","steps":["trace[208008202] 'count revisions from in-memory index tree' (duration: 205.957896ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:14.940198Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605827204917780907,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-10T13:44:15.441241Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605827204917780907,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-10T13:44:15.941381Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605827204917780907,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-10T13:44:16.02389Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.734912346s","expected-duration":"1s"} {"level":"info","ts":"2026-03-10T13:44:16.024179Z","caller":"traceutil/trace.go:171","msg":"trace[301235384] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"1.584149212s","start":"2026-03-10T13:44:14.440008Z","end":"2026-03-10T13:44:16.024158Z","steps":["trace[301235384] 'read index received' (duration: 1.584001209s)","trace[301235384] 'applied index is now lower than readState.Index' (duration: 146.913µs)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:44:16.024275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.584267335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:44:16.024267Z","caller":"traceutil/trace.go:171","msg":"trace[1305257622] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"1.735375616s","start":"2026-03-10T13:44:14.288868Z","end":"2026-03-10T13:44:16.024244Z","steps":["trace[1305257622] 'process raft request' (duration: 1.735182771s)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:44:16.024301Z","caller":"traceutil/trace.go:171","msg":"trace[1490539317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:687; }","duration":"1.584371507s","start":"2026-03-10T13:44:14.439923Z","end":"2026-03-10T13:44:16.024294Z","steps":["trace[1490539317] 'agreement among raft nodes before linearized reading' (duration: 1.584315186s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:16.024325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:14.439901Z","time spent":"1.584418379s","remote":"127.0.0.1:39912","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-03-10T13:44:16.024399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:14.288844Z","time spent":"1.735474499s","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:44:16.024407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123994969s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:44:16.024488Z","caller":"traceutil/trace.go:171","msg":"trace[1360148791] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:687; }","duration":"1.124147862s","start":"2026-03-10T13:44:14.900322Z","end":"2026-03-10T13:44:16.02447Z","steps":["trace[1360148791] 'agreement among raft nodes before linearized reading' (duration: 1.123976408s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:16.02454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:14.900302Z","time spent":"1.124228045s","remote":"127.0.0.1:40174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-03-10T13:44:16.024616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.528189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-10T13:44:16.024547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.220819ms","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-03-10T13:44:16.024645Z","caller":"traceutil/trace.go:171","msg":"trace[750454704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:687; }","duration":"907.623991ms","start":"2026-03-10T13:44:15.117012Z","end":"2026-03-10T13:44:16.024636Z","steps":["trace[750454704] 'agreement among raft nodes before linearized reading' (duration: 907.548109ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:16.024651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.745758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-10T13:44:16.024652Z","caller":"traceutil/trace.go:171","msg":"trace[2050959523] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:687; }","duration":"591.343771ms","start":"2026-03-10T13:44:15.433298Z","end":"2026-03-10T13:44:16.024642Z","steps":["trace[2050959523] 'agreement among raft nodes before linearized reading' (duration: 591.217568ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:16.024667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:15.116989Z","time spent":"907.673633ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-10T13:44:16.024677Z","caller":"traceutil/trace.go:171","msg":"trace[234271316] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:687; }","duration":"936.803039ms","start":"2026-03-10T13:44:15.087866Z","end":"2026-03-10T13:44:16.024669Z","steps":["trace[234271316] 'agreement among raft nodes before linearized reading' (duration: 936.729097ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:16.024686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:15.433279Z","time spent":"591.398353ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-10T13:44:16.024699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:15.087847Z","time spent":"936.84693ms","remote":"127.0.0.1:39936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"warn","ts":"2026-03-10T13:44:16.024499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.527359ms","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-03-10T13:44:16.024779Z","caller":"traceutil/trace.go:171","msg":"trace[968046687] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:687; }","duration":"907.839506ms","start":"2026-03-10T13:44:15.116933Z","end":"2026-03-10T13:44:16.024772Z","steps":["trace[968046687] 'agreement among raft nodes before linearized reading' (duration: 907.491478ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:16.024799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:15.116917Z","time spent":"907.877017ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-10T13:44:16.024823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"908.630444ms","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-03-10T13:44:16.024849Z","caller":"traceutil/trace.go:171","msg":"trace[1965339678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:687; }","duration":"908.706937ms","start":"2026-03-10T13:44:15.116136Z","end":"2026-03-10T13:44:16.024843Z","steps":["trace[1965339678] 'agreement among raft nodes before linearized reading' (duration: 908.603415ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:16.024868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:15.116119Z","time spent":"908.744677ms","remote":"127.0.0.1:40140","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-03-10T13:44:36.72792Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605827204917781089,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-10T13:44:36.830753Z","caller":"traceutil/trace.go:171","msg":"trace[423392967] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"709.488987ms","start":"2026-03-10T13:44:36.121238Z","end":"2026-03-10T13:44:36.830727Z","steps":["trace[423392967] 'process raft request' (duration: 709.319293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:36.830895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:36.121222Z","time spent":"709.599989ms","remote":"127.0.0.1:40140","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-03-10T13:44:36.834743Z","caller":"traceutil/trace.go:171","msg":"trace[1224028891] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"713.179343ms","start":"2026-03-10T13:44:36.121541Z","end":"2026-03-10T13:44:36.83472Z","steps":["trace[1224028891] 'process raft request' (duration: 713.018529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:36.834895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:36.12153Z","time spent":"713.283525ms","remote":"127.0.0.1:40140","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-03-10T13:44:36.834907Z","caller":"traceutil/trace.go:171","msg":"trace[1991678149] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:777; }","duration":"607.204441ms","start":"2026-03-10T13:44:36.227684Z","end":"2026-03-10T13:44:36.834888Z","steps":["trace[1991678149] 'read index received' (duration: 602.945602ms)","trace[1991678149] 'applied index is now lower than readState.Index' (duration: 4.257549ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:44:36.83496Z","caller":"traceutil/trace.go:171","msg":"trace[128056844] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"474.932497ms","start":"2026-03-10T13:44:36.360007Z","end":"2026-03-10T13:44:36.83494Z","steps":["trace[128056844] 'process raft request' (duration: 474.676171ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:36.835057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:36.359983Z","time spent":"475.027039ms","remote":"127.0.0.1:40140","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-03-10T13:44:36.83511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.424236ms","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-03-10T13:44:36.835155Z","caller":"traceutil/trace.go:171","msg":"trace[1721123490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:752; }","duration":"607.515568ms","start":"2026-03-10T13:44:36.227629Z","end":"2026-03-10T13:44:36.835144Z","steps":["trace[1721123490] 'agreement among raft nodes before linearized reading' (duration: 607.378384ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:36.83518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.248396ms","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":"warn","ts":"2026-03-10T13:44:36.835194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:36.227604Z","time spent":"607.581279ms","remote":"127.0.0.1:40140","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-03-10T13:44:36.835199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.387093ms","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-03-10T13:44:36.835218Z","caller":"traceutil/trace.go:171","msg":"trace[1922108375] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:752; }","duration":"556.311587ms","start":"2026-03-10T13:44:36.278896Z","end":"2026-03-10T13:44:36.835208Z","steps":["trace[1922108375] 'agreement among raft nodes before linearized reading' (duration: 556.237795ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:44:36.835241Z","caller":"traceutil/trace.go:171","msg":"trace[1737540978] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:752; }","duration":"276.471725ms","start":"2026-03-10T13:44:36.558759Z","end":"2026-03-10T13:44:36.835231Z","steps":["trace[1737540978] 'agreement among raft nodes before linearized reading' (duration: 276.391053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:36.835248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:36.278881Z","time spent":"556.360189ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-10T13:44:36.835109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.963992ms","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-03-10T13:44:36.835291Z","caller":"traceutil/trace.go:171","msg":"trace[1185510558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:752; }","duration":"603.169157ms","start":"2026-03-10T13:44:36.232113Z","end":"2026-03-10T13:44:36.835282Z","steps":["trace[1185510558] 'agreement among raft nodes before linearized reading' (duration: 602.909931ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:36.835334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:36.232103Z","time spent":"603.224008ms","remote":"127.0.0.1:40140","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-03-10T13:44:36.835377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.827905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:44:36.835478Z","caller":"traceutil/trace.go:171","msg":"trace[658397124] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:752; }","duration":"395.02016ms","start":"2026-03-10T13:44:36.440438Z","end":"2026-03-10T13:44:36.835459Z","steps":["trace[658397124] 'agreement among raft nodes before linearized reading' (duration: 394.684842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:36.835545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:36.440417Z","time spent":"395.114412ms","remote":"127.0.0.1:39912","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-03-10T13:44:39.263501Z","caller":"traceutil/trace.go:171","msg":"trace[581130503] linearizableReadLoop","detail":"{readStateIndex:784; appliedIndex:783; }","duration":"429.438459ms","start":"2026-03-10T13:44:38.834041Z","end":"2026-03-10T13:44:39.263479Z","steps":["trace[581130503] 'read index received' (duration: 429.226434ms)","trace[581130503] 'applied index is now lower than readState.Index' (duration: 211.125µs)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:44:39.263793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.750856ms","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-03-10T13:44:39.26384Z","caller":"traceutil/trace.go:171","msg":"trace[1606248617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:756; }","duration":"429.850908ms","start":"2026-03-10T13:44:38.833978Z","end":"2026-03-10T13:44:39.263829Z","steps":["trace[1606248617] 'agreement among raft nodes before linearized reading' (duration: 429.714915ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:44:39.26384Z","caller":"traceutil/trace.go:171","msg":"trace[1107199404] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"490.809236ms","start":"2026-03-10T13:44:38.772985Z","end":"2026-03-10T13:44:39.263794Z","steps":["trace[1107199404] 'process raft request' (duration: 490.363865ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:39.263893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:38.833955Z","time spent":"429.90729ms","remote":"127.0.0.1:40140","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-03-10T13:44:39.263905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.015711ms","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-03-10T13:44:39.263972Z","caller":"traceutil/trace.go:171","msg":"trace[888869749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:756; }","duration":"377.129373ms","start":"2026-03-10T13:44:38.886826Z","end":"2026-03-10T13:44:39.263956Z","steps":["trace[888869749] 'agreement among raft nodes before linearized reading' (duration: 376.97677ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:39.264025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:38.886808Z","time spent":"377.204806ms","remote":"127.0.0.1:40140","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-03-10T13:44:39.264029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:38.772959Z","time spent":"490.970939ms","remote":"127.0.0.1:40140","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-03-10T13:44:39.264062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.16138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-10T13:44:39.264064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.716621ms","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-03-10T13:44:39.264119Z","caller":"traceutil/trace.go:171","msg":"trace[807695696] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:756; }","duration":"425.397845ms","start":"2026-03-10T13:44:38.838705Z","end":"2026-03-10T13:44:39.264103Z","steps":["trace[807695696] 'agreement among raft nodes before linearized reading' (duration: 425.000716ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:44:39.26477Z","caller":"traceutil/trace.go:171","msg":"trace[2095502962] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:756; }","duration":"338.901525ms","start":"2026-03-10T13:44:38.925233Z","end":"2026-03-10T13:44:39.264135Z","steps":["trace[2095502962] 'agreement among raft nodes before linearized reading' (duration: 338.642819ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:39.264862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:38.925211Z","time spent":"339.628182ms","remote":"127.0.0.1:40140","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-03-10T13:44:39.264937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.865957ms","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":"warn","ts":"2026-03-10T13:44:39.264884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:38.838694Z","time spent":"426.124402ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-10T13:44:39.265012Z","caller":"traceutil/trace.go:171","msg":"trace[1694740728] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:756; }","duration":"339.98878ms","start":"2026-03-10T13:44:38.924998Z","end":"2026-03-10T13:44:39.264987Z","steps":["trace[1694740728] 'agreement among raft nodes before linearized reading' (duration: 338.724821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:39.265044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:38.924972Z","time spent":"340.064802ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-10T13:44:40.231094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.337431ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-10T13:44:40.231487Z","caller":"traceutil/trace.go:171","msg":"trace[1736527120] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"962.340942ms","start":"2026-03-10T13:44:39.269047Z","end":"2026-03-10T13:44:40.231388Z","steps":["trace[1736527120] 'process raft request' (duration: 305.628062ms)","trace[1736527120] 'compare' (duration: 656.25085ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:44:40.231801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:39.269027Z","time spent":"962.65523ms","remote":"127.0.0.1:40140","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-03-10T13:44:40.234089Z","caller":"traceutil/trace.go:171","msg":"trace[1105997916] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"964.055433ms","start":"2026-03-10T13:44:39.270019Z","end":"2026-03-10T13:44:40.234075Z","steps":["trace[1105997916] 'process raft request' (duration: 963.875108ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:44:40.234134Z","caller":"traceutil/trace.go:171","msg":"trace[2091454740] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"963.639692ms","start":"2026-03-10T13:44:39.270467Z","end":"2026-03-10T13:44:40.234106Z","steps":["trace[2091454740] 'process raft request' (duration: 963.55899ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:40.23417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:39.27Z","time spent":"964.121554ms","remote":"127.0.0.1:40140","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-03-10T13:44:40.234249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:39.270456Z","time spent":"963.738505ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T13:44:40.234323Z","caller":"traceutil/trace.go:171","msg":"trace[1477059273] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"963.786266ms","start":"2026-03-10T13:44:39.270529Z","end":"2026-03-10T13:44:40.234315Z","steps":["trace[1477059273] 'process raft request' (duration: 963.53645ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:40.234367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:39.270522Z","time spent":"963.823557ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T13:44:40.234552Z","caller":"traceutil/trace.go:171","msg":"trace[145191117] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"963.864238ms","start":"2026-03-10T13:44:39.270655Z","end":"2026-03-10T13:44:40.23452Z","steps":["trace[145191117] 'process raft request' (duration: 963.622732ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:40.234752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:39.270649Z","time spent":"963.995461ms","remote":"127.0.0.1:40140","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-03-10T13:44:40.574086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.891945ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-10T13:44:40.57424Z","caller":"traceutil/trace.go:171","msg":"trace[823203841] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"134.050886ms","start":"2026-03-10T13:44:40.440161Z","end":"2026-03-10T13:44:40.574212Z","steps":["trace[823203841] 'read index received' (duration: 74.742µs)","trace[823203841] 'applied index is now lower than readState.Index' (duration: 133.974393ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:44:40.574344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.174188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:44:40.574391Z","caller":"traceutil/trace.go:171","msg":"trace[662543687] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"134.26449ms","start":"2026-03-10T13:44:40.440111Z","end":"2026-03-10T13:44:40.574376Z","steps":["trace[662543687] 'agreement among raft nodes before linearized reading' (duration: 134.173878ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:50.938029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.752728ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-10T13:44:50.938316Z","caller":"traceutil/trace.go:171","msg":"trace[199482453] linearizableReadLoop","detail":"{readStateIndex:868; appliedIndex:867; }","duration":"628.360542ms","start":"2026-03-10T13:44:50.309934Z","end":"2026-03-10T13:44:50.938295Z","steps":["trace[199482453] 'read index received' (duration: 102.544263ms)","trace[199482453] 'applied index is now lower than readState.Index' (duration: 525.814819ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:44:50.93849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.541616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-10T13:44:50.938561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.677662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:44:50.938568Z","caller":"traceutil/trace.go:171","msg":"trace[30083943] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:835; }","duration":"628.660749ms","start":"2026-03-10T13:44:50.309886Z","end":"2026-03-10T13:44:50.938546Z","steps":["trace[30083943] 'agreement among raft nodes before linearized reading' (duration: 628.458694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:50.938592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.954929ms","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-03-10T13:44:50.938599Z","caller":"traceutil/trace.go:171","msg":"trace[1424437355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:835; }","duration":"490.745203ms","start":"2026-03-10T13:44:50.447841Z","end":"2026-03-10T13:44:50.938586Z","steps":["trace[1424437355] 'agreement among raft nodes before linearized reading' (duration: 490.701802ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:44:50.938623Z","caller":"traceutil/trace.go:171","msg":"trace[1487928835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:835; }","duration":"624.01206ms","start":"2026-03-10T13:44:50.314601Z","end":"2026-03-10T13:44:50.938613Z","steps":["trace[1487928835] 'agreement among raft nodes before linearized reading' (duration: 623.937158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:50.938621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:50.309849Z","time spent":"628.761201ms","remote":"127.0.0.1:40140","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-03-10T13:44:50.938631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:50.447819Z","time spent":"490.805245ms","remote":"127.0.0.1:39912","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-03-10T13:44:50.938653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:50.314586Z","time spent":"624.059652ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-10T13:44:50.938515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"627.103832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-10T13:44:50.938786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.562997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-10T13:44:50.938788Z","caller":"traceutil/trace.go:171","msg":"trace[880986764] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:835; }","duration":"627.386149ms","start":"2026-03-10T13:44:50.311375Z","end":"2026-03-10T13:44:50.938761Z","steps":["trace[880986764] 'agreement among raft nodes before linearized reading' (duration: 627.02404ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:44:50.938812Z","caller":"traceutil/trace.go:171","msg":"trace[1366230329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:835; }","duration":"625.587057ms","start":"2026-03-10T13:44:50.313217Z","end":"2026-03-10T13:44:50.938804Z","steps":["trace[1366230329] 'agreement among raft nodes before linearized reading' (duration: 625.511286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:50.938859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:50.311366Z","time spent":"627.468051ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-10T13:44:50.938516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.002667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-10T13:44:50.938844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:50.313194Z","time spent":"625.643358ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-10T13:44:50.938947Z","caller":"traceutil/trace.go:171","msg":"trace[136024451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:835; }","duration":"623.470408ms","start":"2026-03-10T13:44:50.315465Z","end":"2026-03-10T13:44:50.938935Z","steps":["trace[136024451] 'agreement among raft nodes before linearized reading' (duration: 622.939705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:50.938995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:50.31545Z","time spent":"623.5276ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-10T13:44:53.596982Z","caller":"traceutil/trace.go:171","msg":"trace[31431109] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"603.918774ms","start":"2026-03-10T13:44:52.993041Z","end":"2026-03-10T13:44:53.59696Z","steps":["trace[31431109] 'process raft request' (duration: 603.858273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:53.597119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:52.99303Z","time spent":"604.016926ms","remote":"127.0.0.1:40140","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-03-10T13:44:53.597242Z","caller":"traceutil/trace.go:171","msg":"trace[1848366348] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"604.120719ms","start":"2026-03-10T13:44:52.99303Z","end":"2026-03-10T13:44:53.597151Z","steps":["trace[1848366348] 'process raft request' (duration: 603.75227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:53.597409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:52.993021Z","time spent":"604.303093ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T13:44:53.714038Z","caller":"traceutil/trace.go:171","msg":"trace[1634740514] linearizableReadLoop","detail":"{readStateIndex:878; appliedIndex:873; }","duration":"473.204316ms","start":"2026-03-10T13:44:53.240818Z","end":"2026-03-10T13:44:53.714023Z","steps":["trace[1634740514] 'read index received' (duration: 356.030373ms)","trace[1634740514] 'applied index is now lower than readState.Index' (duration: 117.173413ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:44:53.714314Z","caller":"traceutil/trace.go:171","msg":"trace[1019043507] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"720.843202ms","start":"2026-03-10T13:44:52.993464Z","end":"2026-03-10T13:44:53.714308Z","steps":["trace[1019043507] 'process raft request' (duration: 720.412482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:53.714389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:52.993456Z","time spent":"720.879352ms","remote":"127.0.0.1:40140","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-03-10T13:44:53.714505Z","caller":"traceutil/trace.go:171","msg":"trace[453041144] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"720.423781ms","start":"2026-03-10T13:44:52.994076Z","end":"2026-03-10T13:44:53.7145Z","steps":["trace[453041144] 'process raft request' (duration: 719.907789ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:53.714539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:52.994069Z","time spent":"720.452502ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T13:44:53.71461Z","caller":"traceutil/trace.go:171","msg":"trace[1591581130] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"719.014868ms","start":"2026-03-10T13:44:52.995591Z","end":"2026-03-10T13:44:53.714606Z","steps":["trace[1591581130] 'process raft request' (duration: 718.411904ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:53.71464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:52.995584Z","time spent":"719.040259ms","remote":"127.0.0.1:40140","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-03-10T13:44:53.714714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.902783ms","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-03-10T13:44:53.714735Z","caller":"traceutil/trace.go:171","msg":"trace[465226228] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:845; }","duration":"473.923583ms","start":"2026-03-10T13:44:53.240806Z","end":"2026-03-10T13:44:53.714729Z","steps":["trace[465226228] 'agreement among raft nodes before linearized reading' (duration: 473.890423ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:53.714752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:44:53.240733Z","time spent":"474.015585ms","remote":"127.0.0.1:40268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-10T13:44:53.714833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.267387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:44:53.71485Z","caller":"traceutil/trace.go:171","msg":"trace[1579035853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:845; }","duration":"275.317758ms","start":"2026-03-10T13:44:53.439528Z","end":"2026-03-10T13:44:53.714846Z","steps":["trace[1579035853] 'agreement among raft nodes before linearized reading' (duration: 275.291937ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:44:53.714959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.67478ms","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-03-10T13:44:53.714972Z","caller":"traceutil/trace.go:171","msg":"trace[1500205022] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:845; }","duration":"159.726242ms","start":"2026-03-10T13:44:53.555242Z","end":"2026-03-10T13:44:53.714968Z","steps":["trace[1500205022] 'agreement among raft nodes before linearized reading' (duration: 159.704481ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:44:57.838364Z","caller":"traceutil/trace.go:171","msg":"trace[550748471] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"109.27506ms","start":"2026-03-10T13:44:57.729069Z","end":"2026-03-10T13:44:57.838344Z","steps":["trace[550748471] 'process raft request' (duration: 109.176067ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:44:57.838395Z","caller":"traceutil/trace.go:171","msg":"trace[598223825] linearizableReadLoop","detail":"{readStateIndex:889; appliedIndex:889; }","duration":"108.820959ms","start":"2026-03-10T13:44:57.729544Z","end":"2026-03-10T13:44:57.838365Z","steps":["trace[598223825] 'read index received' (duration: 108.806819ms)","trace[598223825] 'applied index is now lower than readState.Index' (duration: 11.84µs)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:44:57.838538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.977413ms","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-03-10T13:44:57.838582Z","caller":"traceutil/trace.go:171","msg":"trace[1734045712] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:855; }","duration":"109.039404ms","start":"2026-03-10T13:44:57.729532Z","end":"2026-03-10T13:44:57.838571Z","steps":["trace[1734045712] 'agreement among raft nodes before linearized reading' (duration: 108.907781ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:44:57.839003Z","caller":"traceutil/trace.go:171","msg":"trace[497489719] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"105.878651ms","start":"2026-03-10T13:44:57.733114Z","end":"2026-03-10T13:44:57.838993Z","steps":["trace[497489719] 'process raft request' (duration: 105.808949ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:07.936352Z","caller":"traceutil/trace.go:171","msg":"trace[1622768965] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"496.520528ms","start":"2026-03-10T13:45:07.439801Z","end":"2026-03-10T13:45:07.936322Z","steps":["trace[1622768965] 'read index received' (duration: 496.326513ms)","trace[1622768965] 'applied index is now lower than readState.Index' (duration: 193.355µs)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:45:07.936544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.722563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:45:07.936545Z","caller":"traceutil/trace.go:171","msg":"trace[1916527441] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"691.314781ms","start":"2026-03-10T13:45:07.245198Z","end":"2026-03-10T13:45:07.936513Z","steps":["trace[1916527441] 'process raft request' (duration: 690.996043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:07.936685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:07.245177Z","time spent":"691.432763ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:45:07.936666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.560897ms","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-03-10T13:45:07.936573Z","caller":"traceutil/trace.go:171","msg":"trace[587260719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:880; }","duration":"496.807155ms","start":"2026-03-10T13:45:07.439756Z","end":"2026-03-10T13:45:07.936563Z","steps":["trace[587260719] 'agreement among raft nodes before linearized reading' (duration: 496.737693ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:07.936736Z","caller":"traceutil/trace.go:171","msg":"trace[217466086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:880; }","duration":"279.684689ms","start":"2026-03-10T13:45:07.657034Z","end":"2026-03-10T13:45:07.936719Z","steps":["trace[217466086] 'agreement among raft nodes before linearized reading' (duration: 279.505315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:07.936743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.036304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-10T13:45:07.93678Z","caller":"traceutil/trace.go:171","msg":"trace[888301664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:880; }","duration":"279.098926ms","start":"2026-03-10T13:45:07.657672Z","end":"2026-03-10T13:45:07.936771Z","steps":["trace[888301664] 'agreement among raft nodes before linearized reading' (duration: 278.960592ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:07.936771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:07.439736Z","time spent":"497.01502ms","remote":"127.0.0.1:39912","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-03-10T13:45:08.349103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.52539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:45:08.34927Z","caller":"traceutil/trace.go:171","msg":"trace[659401128] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:889; }","duration":"159.738615ms","start":"2026-03-10T13:45:08.189498Z","end":"2026-03-10T13:45:08.349237Z","steps":["trace[659401128] 'range keys from in-memory index tree' (duration: 159.361256ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:10.42219Z","caller":"traceutil/trace.go:171","msg":"trace[608899654] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"175.04949ms","start":"2026-03-10T13:45:10.247119Z","end":"2026-03-10T13:45:10.422168Z","steps":["trace[608899654] 'process raft request' (duration: 174.924337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:10.793282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.226131ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-10T13:45:12.393754Z","caller":"traceutil/trace.go:171","msg":"trace[425967852] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"433.182916ms","start":"2026-03-10T13:45:11.960543Z","end":"2026-03-10T13:45:12.393726Z","steps":["trace[425967852] 'process raft request' (duration: 432.991161ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:12.393907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:11.960527Z","time spent":"433.307188ms","remote":"127.0.0.1:40140","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-03-10T13:45:12.395025Z","caller":"traceutil/trace.go:171","msg":"trace[1701311729] linearizableReadLoop","detail":"{readStateIndex:952; appliedIndex:948; }","duration":"423.214697ms","start":"2026-03-10T13:45:11.971796Z","end":"2026-03-10T13:45:12.395011Z","steps":["trace[1701311729] 'read index received' (duration: 421.759713ms)","trace[1701311729] 'applied index is now lower than readState.Index' (duration: 1.454164ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:45:12.395185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.389441ms","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-03-10T13:45:12.395168Z","caller":"traceutil/trace.go:171","msg":"trace[1195444604] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"434.004116ms","start":"2026-03-10T13:45:11.961137Z","end":"2026-03-10T13:45:12.395141Z","steps":["trace[1195444604] 'process raft request' (duration: 433.680128ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:12.395245Z","caller":"traceutil/trace.go:171","msg":"trace[2050106184] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"432.701244ms","start":"2026-03-10T13:45:11.962536Z","end":"2026-03-10T13:45:12.395237Z","steps":["trace[2050106184] 'process raft request' (duration: 432.362306ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:12.395284Z","caller":"traceutil/trace.go:171","msg":"trace[1884991434] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"432.039078ms","start":"2026-03-10T13:45:11.963215Z","end":"2026-03-10T13:45:12.395254Z","steps":["trace[1884991434] 'process raft request' (duration: 431.764502ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:12.395302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:11.962526Z","time spent":"432.741325ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:45:12.395334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:11.961128Z","time spent":"434.115268ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:45:12.395413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:11.963209Z","time spent":"432.13207ms","remote":"127.0.0.1:40140","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-03-10T13:45:12.395214Z","caller":"traceutil/trace.go:171","msg":"trace[637399954] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:915; }","duration":"423.447223ms","start":"2026-03-10T13:45:11.971758Z","end":"2026-03-10T13:45:12.395205Z","steps":["trace[637399954] 'agreement among raft nodes before linearized reading' (duration: 423.31105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:12.395571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:11.971743Z","time spent":"423.814752ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-10T13:45:12.941102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605827204917781457,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-10T13:45:13.243559Z","caller":"traceutil/trace.go:171","msg":"trace[916046383] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"844.165ms","start":"2026-03-10T13:45:12.399367Z","end":"2026-03-10T13:45:13.243532Z","steps":["trace[916046383] 'process raft request' (duration: 810.034425ms)","trace[916046383] 'compare' (duration: 33.784707ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:45:13.243692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:12.399342Z","time spent":"844.282473ms","remote":"127.0.0.1:40140","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-03-10T13:45:14.197014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.185338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-10T13:45:14.197108Z","caller":"traceutil/trace.go:171","msg":"trace[645466891] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:952; }","duration":"1.756830507s","start":"2026-03-10T13:45:12.440265Z","end":"2026-03-10T13:45:14.197095Z","steps":["trace[645466891] 'read index received' (duration: 769.074108ms)","trace[645466891] 'applied index is now lower than readState.Index' (duration: 987.755519ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:45:14.197172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.756921279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:45:14.197193Z","caller":"traceutil/trace.go:171","msg":"trace[959976525] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:917; }","duration":"1.757006871s","start":"2026-03-10T13:45:12.44018Z","end":"2026-03-10T13:45:14.197187Z","steps":["trace[959976525] 'agreement among raft nodes before linearized reading' (duration: 1.75694655s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:14.197214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:12.44016Z","time spent":"1.757049562s","remote":"127.0.0.1:39912","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-03-10T13:45:14.197346Z","caller":"traceutil/trace.go:171","msg":"trace[323275352] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"1.731949823s","start":"2026-03-10T13:45:12.465386Z","end":"2026-03-10T13:45:14.197336Z","steps":["trace[323275352] 'process raft request' (duration: 1.014392447s)","trace[323275352] 'compare' (duration: 717.078634ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:45:14.197385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:12.465369Z","time spent":"1.732000553s","remote":"127.0.0.1:39982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":891,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-10T13:45:14.675475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.592145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-10T13:45:14.675537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.06906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-10T13:45:14.675552Z","caller":"traceutil/trace.go:171","msg":"trace[29434403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:917; }","duration":"276.716599ms","start":"2026-03-10T13:45:14.398819Z","end":"2026-03-10T13:45:14.675535Z","steps":["trace[29434403] 'range keys from in-memory index tree' (duration: 276.408562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:14.675687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.51358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-10T13:45:14.675708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.793781ms","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-03-10T13:45:14.67574Z","caller":"traceutil/trace.go:171","msg":"trace[1573838407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:917; }","duration":"275.586032ms","start":"2026-03-10T13:45:14.400141Z","end":"2026-03-10T13:45:14.675727Z","steps":["trace[1573838407] 'range keys from in-memory index tree' (duration: 275.422348ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:14.675796Z","caller":"traceutil/trace.go:171","msg":"trace[1405507777] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:917; }","duration":"276.962264ms","start":"2026-03-10T13:45:14.398813Z","end":"2026-03-10T13:45:14.675775Z","steps":["trace[1405507777] 'range keys from in-memory index tree' (duration: 276.600155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:14.676016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.248752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:45:14.676059Z","caller":"traceutil/trace.go:171","msg":"trace[448552745] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:917; }","duration":"120.368975ms","start":"2026-03-10T13:45:14.555678Z","end":"2026-03-10T13:45:14.676047Z","steps":["trace[448552745] 'count revisions from in-memory index tree' (duration: 119.706738ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:14.676335Z","caller":"traceutil/trace.go:171","msg":"trace[1928937843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:917; }","duration":"275.182162ms","start":"2026-03-10T13:45:14.400408Z","end":"2026-03-10T13:45:14.67559Z","steps":["trace[1928937843] 'range keys from in-memory index tree' (duration: 274.903795ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:15.564085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.993757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-10T13:45:15.564209Z","caller":"traceutil/trace.go:171","msg":"trace[1484166869] linearizableReadLoop","detail":"{readStateIndex:960; appliedIndex:959; }","duration":"338.597236ms","start":"2026-03-10T13:45:15.225595Z","end":"2026-03-10T13:45:15.564192Z","steps":["trace[1484166869] 'read index received' (duration: 76.385434ms)","trace[1484166869] 'applied index is now lower than readState.Index' (duration: 262.210062ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:45:15.564313Z","caller":"traceutil/trace.go:171","msg":"trace[972225798] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"400.403703ms","start":"2026-03-10T13:45:15.163899Z","end":"2026-03-10T13:45:15.564302Z","steps":["trace[972225798] 'process raft request' (duration: 138.107268ms)","trace[972225798] 'compare' (duration: 261.810503ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:45:15.564407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:15.163877Z","time spent":"400.472184ms","remote":"127.0.0.1:39936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:45:15.564565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.942505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-10T13:45:15.564559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.836117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:45:15.564631Z","caller":"traceutil/trace.go:171","msg":"trace[11680291] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:922; }","duration":"339.064768ms","start":"2026-03-10T13:45:15.225542Z","end":"2026-03-10T13:45:15.564606Z","steps":["trace[11680291] 'agreement among raft nodes before linearized reading' (duration: 338.861073ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:15.564632Z","caller":"traceutil/trace.go:171","msg":"trace[218732292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:922; }","duration":"123.94611ms","start":"2026-03-10T13:45:15.440669Z","end":"2026-03-10T13:45:15.564616Z","steps":["trace[218732292] 'agreement among raft nodes before linearized reading' (duration: 123.746755ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:15.56462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.79455ms","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":"warn","ts":"2026-03-10T13:45:15.564665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:15.225518Z","time spent":"339.140469ms","remote":"127.0.0.1:39992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-03-10T13:45:15.564687Z","caller":"traceutil/trace.go:171","msg":"trace[1547184266] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:922; }","duration":"317.870921ms","start":"2026-03-10T13:45:15.2468Z","end":"2026-03-10T13:45:15.56467Z","steps":["trace[1547184266] 'agreement among raft nodes before linearized reading' (duration: 317.539373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:15.564737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:15.246748Z","time spent":"317.981534ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-10T13:45:15.883606Z","caller":"traceutil/trace.go:171","msg":"trace[732383055] linearizableReadLoop","detail":"{readStateIndex:961; appliedIndex:960; }","duration":"314.082421ms","start":"2026-03-10T13:45:15.5695Z","end":"2026-03-10T13:45:15.883583Z","steps":["trace[732383055] 'read index received' (duration: 313.834315ms)","trace[732383055] 'applied index is now lower than readState.Index' (duration: 247.236µs)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:45:15.883738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.224154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-10T13:45:15.883812Z","caller":"traceutil/trace.go:171","msg":"trace[524891568] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:923; }","duration":"314.296366ms","start":"2026-03-10T13:45:15.569464Z","end":"2026-03-10T13:45:15.88376Z","steps":["trace[524891568] 'agreement among raft nodes before linearized reading' (duration: 314.212904ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:15.883794Z","caller":"traceutil/trace.go:171","msg":"trace[1865111914] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"315.541106ms","start":"2026-03-10T13:45:15.568175Z","end":"2026-03-10T13:45:15.883716Z","steps":["trace[1865111914] 'process raft request' (duration: 315.217618ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:15.883888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:15.569453Z","time spent":"314.396388ms","remote":"127.0.0.1:40156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-03-10T13:45:15.884208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:15.568165Z","time spent":"315.739571ms","remote":"127.0.0.1:40140","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-03-10T13:45:16.608878Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605827204917781483,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-10T13:45:16.95916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.011443ms","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-03-10T13:45:16.959307Z","caller":"traceutil/trace.go:171","msg":"trace[1847165743] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:923; }","duration":"856.162787ms","start":"2026-03-10T13:45:16.103121Z","end":"2026-03-10T13:45:16.959284Z","steps":["trace[1847165743] 'range keys from in-memory index tree' (duration: 855.997573ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:16.959341Z","caller":"traceutil/trace.go:171","msg":"trace[972420900] linearizableReadLoop","detail":"{readStateIndex:962; appliedIndex:961; }","duration":"850.761099ms","start":"2026-03-10T13:45:16.108555Z","end":"2026-03-10T13:45:16.959316Z","steps":["trace[972420900] 'read index received' (duration: 791.795711ms)","trace[972420900] 'applied index is now lower than readState.Index' (duration: 58.963278ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:45:16.959384Z","caller":"traceutil/trace.go:171","msg":"trace[1871797463] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"1.014777036s","start":"2026-03-10T13:45:15.94458Z","end":"2026-03-10T13:45:16.959357Z","steps":["trace[1871797463] 'process raft request' (duration: 955.822458ms)","trace[1871797463] 'compare' (duration: 57.931633ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:45:16.959521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:15.944546Z","time spent":"1.014903969s","remote":"127.0.0.1:39982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-10T13:45:16.959624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.185994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:45:16.959672Z","caller":"traceutil/trace.go:171","msg":"trace[791932377] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:924; }","duration":"502.260085ms","start":"2026-03-10T13:45:16.457399Z","end":"2026-03-10T13:45:16.959659Z","steps":["trace[791932377] 'agreement among raft nodes before linearized reading' (duration: 502.129442ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:16.959706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:16.457383Z","time spent":"502.315717ms","remote":"127.0.0.1:39912","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-03-10T13:45:16.959854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.285941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-10T13:45:16.959885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.635634ms","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-03-10T13:45:16.959926Z","caller":"traceutil/trace.go:171","msg":"trace[610368707] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:924; }","duration":"851.389974ms","start":"2026-03-10T13:45:16.108524Z","end":"2026-03-10T13:45:16.959914Z","steps":["trace[610368707] 'agreement among raft nodes before linearized reading' (duration: 850.923093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:16.959925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.666465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-10T13:45:16.959964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:16.108508Z","time spent":"851.446905ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-10T13:45:16.960035Z","caller":"traceutil/trace.go:171","msg":"trace[113776905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:924; }","duration":"216.793627ms","start":"2026-03-10T13:45:16.743224Z","end":"2026-03-10T13:45:16.960018Z","steps":["trace[113776905] 'agreement among raft nodes before linearized reading' (duration: 216.48059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:16.960013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.890926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-10T13:45:16.959933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.748326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-10T13:45:16.960103Z","caller":"traceutil/trace.go:171","msg":"trace[666977844] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:924; }","duration":"234.031869ms","start":"2026-03-10T13:45:16.726046Z","end":"2026-03-10T13:45:16.960078Z","steps":["trace[666977844] 'agreement among raft nodes before linearized reading' (duration: 233.739682ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:16.960126Z","caller":"traceutil/trace.go:171","msg":"trace[814893824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:924; }","duration":"216.956872ms","start":"2026-03-10T13:45:16.743152Z","end":"2026-03-10T13:45:16.960109Z","steps":["trace[814893824] 'agreement among raft nodes before linearized reading' (duration: 216.641834ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:16.959958Z","caller":"traceutil/trace.go:171","msg":"trace[1606397869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:924; }","duration":"216.719856ms","start":"2026-03-10T13:45:16.743218Z","end":"2026-03-10T13:45:16.959937Z","steps":["trace[1606397869] 'agreement among raft nodes before linearized reading' (duration: 216.50244ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:17.636366Z","caller":"traceutil/trace.go:171","msg":"trace[1407234863] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"671.473096ms","start":"2026-03-10T13:45:16.964858Z","end":"2026-03-10T13:45:17.636331Z","steps":["trace[1407234863] 'process raft request' (duration: 616.912173ms)","trace[1407234863] 'compare' (duration: 54.053621ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:45:17.636571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:16.964842Z","time spent":"671.638339ms","remote":"127.0.0.1:40140","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-03-10T13:45:18.462957Z","caller":"traceutil/trace.go:171","msg":"trace[789545594] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:962; }","duration":"1.277477808s","start":"2026-03-10T13:45:17.185458Z","end":"2026-03-10T13:45:18.462936Z","steps":["trace[789545594] 'read index received' (duration: 396.17474ms)","trace[789545594] 'applied index is now lower than readState.Index' (duration: 881.302298ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:45:18.463059Z","caller":"traceutil/trace.go:171","msg":"trace[1255041671] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"1.497211156s","start":"2026-03-10T13:45:16.965839Z","end":"2026-03-10T13:45:18.46305Z","steps":["trace[1255041671] 'process raft request' (duration: 1.493360284s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:18.463137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:16.965823Z","time spent":"1.497264088s","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-10T13:45:18.463194Z","caller":"traceutil/trace.go:171","msg":"trace[1358875798] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"1.497075183s","start":"2026-03-10T13:45:16.966082Z","end":"2026-03-10T13:45:18.463157Z","steps":["trace[1358875798] 'process raft request' (duration: 1.496786936s)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:18.463222Z","caller":"traceutil/trace.go:171","msg":"trace[1639058050] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"1.496687183s","start":"2026-03-10T13:45:16.966509Z","end":"2026-03-10T13:45:18.463197Z","steps":["trace[1639058050] 'process raft request' (duration: 1.496391346s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:18.463283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.277815907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:45:18.463336Z","caller":"traceutil/trace.go:171","msg":"trace[1118342125] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:929; }","duration":"1.277906048s","start":"2026-03-10T13:45:17.185418Z","end":"2026-03-10T13:45:18.463324Z","steps":["trace[1118342125] 'agreement among raft nodes before linearized reading' (duration: 1.277815116s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:18.463336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:16.966071Z","time spent":"1.497187986s","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:45:18.463346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:16.966501Z","time spent":"1.496775456s","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:45:18.463369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:17.185376Z","time spent":"1.27798438s","remote":"127.0.0.1:40028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"info","ts":"2026-03-10T13:45:18.463421Z","caller":"traceutil/trace.go:171","msg":"trace[2018507868] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"1.49738824s","start":"2026-03-10T13:45:16.966023Z","end":"2026-03-10T13:45:18.463411Z","steps":["trace[2018507868] 'process raft request' (duration: 1.496788436s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:18.46349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:16.966013Z","time spent":"1.497441381s","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T13:45:18.508661Z","caller":"traceutil/trace.go:171","msg":"trace[1507848373] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"865.882659ms","start":"2026-03-10T13:45:17.642752Z","end":"2026-03-10T13:45:18.508634Z","steps":["trace[1507848373] 'process raft request' (duration: 865.579602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:18.50872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.12002ms","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-03-10T13:45:18.508759Z","caller":"traceutil/trace.go:171","msg":"trace[717061729] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"534.582417ms","start":"2026-03-10T13:45:17.974151Z","end":"2026-03-10T13:45:18.508734Z","steps":["trace[717061729] 'process raft request' (duration: 534.29352ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:18.508775Z","caller":"traceutil/trace.go:171","msg":"trace[276215804] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:931; }","duration":"620.219662ms","start":"2026-03-10T13:45:17.88854Z","end":"2026-03-10T13:45:18.508759Z","steps":["trace[276215804] 'agreement among raft nodes before linearized reading' (duration: 620.007167ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:18.508797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:17.642738Z","time spent":"865.997062ms","remote":"127.0.0.1:39982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-10T13:45:18.508822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:17.888519Z","time spent":"620.291424ms","remote":"127.0.0.1:40140","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-03-10T13:45:18.508866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:17.974124Z","time spent":"534.68114ms","remote":"127.0.0.1:40140","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-03-10T13:45:19.122916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.681283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-10T13:45:19.123187Z","caller":"traceutil/trace.go:171","msg":"trace[1827632527] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"610.321766ms","start":"2026-03-10T13:45:18.512844Z","end":"2026-03-10T13:45:19.123166Z","steps":["trace[1827632527] 'process raft request' (duration: 610.255785ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:19.123301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:18.512832Z","time spent":"610.413468ms","remote":"127.0.0.1:40140","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-03-10T13:45:19.123383Z","caller":"traceutil/trace.go:171","msg":"trace[427719198] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"614.839404ms","start":"2026-03-10T13:45:18.508524Z","end":"2026-03-10T13:45:19.123363Z","steps":["trace[427719198] 'read index received' (duration: 140.627408ms)","trace[427719198] 'applied index is now lower than readState.Index' (duration: 474.209876ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:45:19.12355Z","caller":"traceutil/trace.go:171","msg":"trace[1781789815] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"654.916021ms","start":"2026-03-10T13:45:18.468614Z","end":"2026-03-10T13:45:19.12353Z","steps":["trace[1781789815] 'process raft request' (duration: 180.524891ms)","trace[1781789815] 'compare' (duration: 473.504419ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:45:19.123573Z","caller":"traceutil/trace.go:171","msg":"trace[1167013111] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"611.197917ms","start":"2026-03-10T13:45:18.512359Z","end":"2026-03-10T13:45:19.123557Z","steps":["trace[1167013111] 'process raft request' (duration: 610.666164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:19.123643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:18.512339Z","time spent":"611.269699ms","remote":"127.0.0.1:39982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-10T13:45:19.123648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:18.468602Z","time spent":"655.004433ms","remote":"127.0.0.1:39982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-10T13:45:19.123652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.104656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-10T13:45:19.123692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.5489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"warn","ts":"2026-03-10T13:45:19.123724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.894605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:45:19.123737Z","caller":"traceutil/trace.go:171","msg":"trace[1671223125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"614.219129ms","start":"2026-03-10T13:45:18.509501Z","end":"2026-03-10T13:45:19.12372Z","steps":["trace[1671223125] 'agreement among raft nodes before linearized reading' (duration: 614.083736ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:19.123756Z","caller":"traceutil/trace.go:171","msg":"trace[1284596754] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:934; }","duration":"140.954966ms","start":"2026-03-10T13:45:18.98279Z","end":"2026-03-10T13:45:19.123745Z","steps":["trace[1284596754] 'agreement among raft nodes before linearized reading' (duration: 140.907075ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:19.123744Z","caller":"traceutil/trace.go:171","msg":"trace[1535290310] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:934; }","duration":"656.630032ms","start":"2026-03-10T13:45:18.467099Z","end":"2026-03-10T13:45:19.123729Z","steps":["trace[1535290310] 'agreement among raft nodes before linearized reading' (duration: 656.459548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:19.123784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:18.509487Z","time spent":"614.289461ms","remote":"127.0.0.1:39908","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-03-10T13:45:19.123792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:18.467084Z","time spent":"656.697664ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-10T13:45:19.575368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.909662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:45:19.575462Z","caller":"traceutil/trace.go:171","msg":"trace[976023245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:935; }","duration":"135.081767ms","start":"2026-03-10T13:45:19.440357Z","end":"2026-03-10T13:45:19.575438Z","steps":["trace[976023245] 'range keys from in-memory index tree' (duration: 134.79907ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:20.380322Z","caller":"traceutil/trace.go:171","msg":"trace[29939949] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"535.657113ms","start":"2026-03-10T13:45:19.844637Z","end":"2026-03-10T13:45:20.380294Z","steps":["trace[29939949] 'process raft request' (duration: 535.485219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:20.380715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:19.844607Z","time spent":"535.835437ms","remote":"127.0.0.1:40062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5958,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:45:21.20014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.245807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-10T13:45:21.200374Z","caller":"traceutil/trace.go:171","msg":"trace[2028852168] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"1.109983508s","start":"2026-03-10T13:45:20.090363Z","end":"2026-03-10T13:45:21.200346Z","steps":["trace[2028852168] 'process raft request' (duration: 681.465085ms)","trace[2028852168] 'compare' (duration: 428.025442ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:45:21.200359Z","caller":"traceutil/trace.go:171","msg":"trace[233138297] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"733.147759ms","start":"2026-03-10T13:45:20.46719Z","end":"2026-03-10T13:45:21.200338Z","steps":["trace[233138297] 'read index received' (duration: 304.638756ms)","trace[233138297] 'applied index is now lower than readState.Index' (duration: 428.507003ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:45:21.200674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:20.09033Z","time spent":"1.110204694s","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:45:21.555138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.904026ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-10T13:45:21.555652Z","caller":"traceutil/trace.go:171","msg":"trace[1908142221] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"354.969687ms","start":"2026-03-10T13:45:21.200657Z","end":"2026-03-10T13:45:21.555626Z","steps":["trace[1908142221] 'read index received' (duration: 116.202µs)","trace[1908142221] 'applied index is now lower than readState.Index' (duration: 354.850775ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:45:21.555406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088209859s","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-03-10T13:45:21.556032Z","caller":"traceutil/trace.go:171","msg":"trace[313111360] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:938; }","duration":"1.088860214s","start":"2026-03-10T13:45:20.467143Z","end":"2026-03-10T13:45:21.556003Z","steps":["trace[313111360] 'agreement among raft nodes before linearized reading' (duration: 733.507438ms)","trace[313111360] 'range keys from in-memory index tree' (duration: 354.467385ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:45:21.556122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:20.467124Z","time spent":"1.088970187s","remote":"127.0.0.1:40140","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-03-10T13:45:21.556404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088328301s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-10T13:45:21.556442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.07087ms","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-03-10T13:45:21.556454Z","caller":"traceutil/trace.go:171","msg":"trace[925816954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:938; }","duration":"1.088432194s","start":"2026-03-10T13:45:20.468008Z","end":"2026-03-10T13:45:21.55644Z","steps":["trace[925816954] 'agreement among raft nodes before linearized reading' (duration: 1.08827811s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:21.556438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.904385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-10T13:45:21.556486Z","caller":"traceutil/trace.go:171","msg":"trace[1363748852] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:938; }","duration":"427.168632ms","start":"2026-03-10T13:45:21.129304Z","end":"2026-03-10T13:45:21.556473Z","steps":["trace[1363748852] 'agreement among raft nodes before linearized reading' (duration: 426.991897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:21.556491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:20.46799Z","time spent":"1.088492636s","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-10T13:45:21.556474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08828463s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-10T13:45:21.556506Z","caller":"traceutil/trace.go:171","msg":"trace[734112613] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:938; }","duration":"159.026518ms","start":"2026-03-10T13:45:21.397464Z","end":"2026-03-10T13:45:21.55649Z","steps":["trace[734112613] 'agreement among raft nodes before linearized reading' (duration: 158.873334ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:21.556541Z","caller":"traceutil/trace.go:171","msg":"trace[835129188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:938; }","duration":"1.088390113s","start":"2026-03-10T13:45:20.468135Z","end":"2026-03-10T13:45:21.556525Z","steps":["trace[835129188] 'agreement among raft nodes before linearized reading' (duration: 1.088171018s)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:21.556412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.685243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:45:21.556687Z","caller":"traceutil/trace.go:171","msg":"trace[1552338151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"116.01924ms","start":"2026-03-10T13:45:21.440653Z","end":"2026-03-10T13:45:21.556672Z","steps":["trace[1552338151] 'agreement among raft nodes before linearized reading' (duration: 115.698463ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:21.55656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:21.129251Z","time spent":"427.297655ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-10T13:45:21.556581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:20.468118Z","time spent":"1.088455815s","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-10T13:45:22.381393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.899221ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-10T13:45:22.3816Z","caller":"traceutil/trace.go:171","msg":"trace[790989165] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:977; }","duration":"785.648184ms","start":"2026-03-10T13:45:21.595936Z","end":"2026-03-10T13:45:22.381584Z","steps":["trace[790989165] 'read index received' (duration: 58.106638ms)","trace[790989165] 'applied index is now lower than readState.Index' (duration: 727.540616ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:45:22.381601Z","caller":"traceutil/trace.go:171","msg":"trace[760574403] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"820.236669ms","start":"2026-03-10T13:45:21.561343Z","end":"2026-03-10T13:45:22.381579Z","steps":["trace[760574403] 'process raft request' (duration: 164.902188ms)","trace[760574403] 'compare' (duration: 654.568613ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:45:22.381715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:21.561334Z","time spent":"820.314291ms","remote":"127.0.0.1:40140","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-03-10T13:45:22.381755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.807077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:45:22.381806Z","caller":"traceutil/trace.go:171","msg":"trace[793464585] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:942; }","duration":"785.89203ms","start":"2026-03-10T13:45:21.595901Z","end":"2026-03-10T13:45:22.381793Z","steps":["trace[793464585] 'agreement among raft nodes before linearized reading' (duration: 785.799297ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:22.381849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:21.595888Z","time spent":"785.951621ms","remote":"127.0.0.1:40056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-03-10T13:45:22.381894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.678043ms","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-03-10T13:45:22.381929Z","caller":"traceutil/trace.go:171","msg":"trace[2082833777] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"820.538457ms","start":"2026-03-10T13:45:21.561383Z","end":"2026-03-10T13:45:22.381922Z","steps":["trace[2082833777] 'process raft request' (duration: 820.094036ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:22.38193Z","caller":"traceutil/trace.go:171","msg":"trace[1762583563] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:942; }","duration":"661.758025ms","start":"2026-03-10T13:45:21.720163Z","end":"2026-03-10T13:45:22.381921Z","steps":["trace[1762583563] 'agreement among raft nodes before linearized reading' (duration: 661.628531ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:22.38197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:21.561379Z","time spent":"820.568427ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-10T13:45:22.381969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:21.72014Z","time spent":"661.821877ms","remote":"127.0.0.1:40140","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-03-10T13:45:22.38202Z","caller":"traceutil/trace.go:171","msg":"trace[1448305899] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"819.82067ms","start":"2026-03-10T13:45:21.562189Z","end":"2026-03-10T13:45:22.38201Z","steps":["trace[1448305899] 'process raft request' (duration: 819.361039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:22.382078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:21.562181Z","time spent":"819.863581ms","remote":"127.0.0.1:40140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-10T13:45:24.508411Z","caller":"traceutil/trace.go:171","msg":"trace[2020808575] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:990; }","duration":"122.855163ms","start":"2026-03-10T13:45:24.385511Z","end":"2026-03-10T13:45:24.508367Z","steps":["trace[2020808575] 'read index received' (duration: 122.700649ms)","trace[2020808575] 'applied index is now lower than readState.Index' (duration: 153.684µs)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:45:24.508482Z","caller":"traceutil/trace.go:171","msg":"trace[557358697] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"294.144773ms","start":"2026-03-10T13:45:24.214322Z","end":"2026-03-10T13:45:24.508466Z","steps":["trace[557358697] 'process raft request' (duration: 293.918088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:24.508621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.101018ms","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-03-10T13:45:24.508667Z","caller":"traceutil/trace.go:171","msg":"trace[2023116922] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:952; }","duration":"123.191351ms","start":"2026-03-10T13:45:24.385463Z","end":"2026-03-10T13:45:24.508654Z","steps":["trace[2023116922] 'agreement among raft nodes before linearized reading' (duration: 123.048287ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:24.508667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.700555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-10T13:45:24.508732Z","caller":"traceutil/trace.go:171","msg":"trace[1016610660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:952; }","duration":"121.775047ms","start":"2026-03-10T13:45:24.386938Z","end":"2026-03-10T13:45:24.508713Z","steps":["trace[1016610660] 'agreement among raft nodes before linearized reading' (duration: 121.600783ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:24.508803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.297376ms","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-03-10T13:45:24.50884Z","caller":"traceutil/trace.go:171","msg":"trace[1234992488] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:952; }","duration":"116.359568ms","start":"2026-03-10T13:45:24.39247Z","end":"2026-03-10T13:45:24.508829Z","steps":["trace[1234992488] 'agreement among raft nodes before linearized reading' (duration: 116.279306ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:24.508964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.921991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-10T13:45:24.509037Z","caller":"traceutil/trace.go:171","msg":"trace[2055391131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:952; }","duration":"122.018642ms","start":"2026-03-10T13:45:24.387001Z","end":"2026-03-10T13:45:24.509019Z","steps":["trace[2055391131] 'agreement among raft nodes before linearized reading' (duration: 121.847498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:25.725253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.446076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:45:25.725341Z","caller":"traceutil/trace.go:171","msg":"trace[901453683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"264.581819ms","start":"2026-03-10T13:45:25.460742Z","end":"2026-03-10T13:45:25.725324Z","steps":["trace[901453683] 'range keys from in-memory index tree' (duration: 264.321573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:25.725463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.683632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-10T13:45:25.725523Z","caller":"traceutil/trace.go:171","msg":"trace[1113561777] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:956; }","duration":"635.774784ms","start":"2026-03-10T13:45:25.08973Z","end":"2026-03-10T13:45:25.725505Z","steps":["trace[1113561777] 'range keys from in-memory index tree' (duration: 635.527659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:25.725553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:25.089716Z","time spent":"635.830456ms","remote":"127.0.0.1:39936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"warn","ts":"2026-03-10T13:45:26.080333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.682948ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-10T13:45:26.080585Z","caller":"traceutil/trace.go:171","msg":"trace[1454123758] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"228.20886ms","start":"2026-03-10T13:45:25.852358Z","end":"2026-03-10T13:45:26.080567Z","steps":["trace[1454123758] 'process raft request' (duration: 228.111667ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:26.080645Z","caller":"traceutil/trace.go:171","msg":"trace[102083646] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"351.138586ms","start":"2026-03-10T13:45:25.729479Z","end":"2026-03-10T13:45:26.080618Z","steps":["trace[102083646] 'process raft request' (duration: 213.083779ms)","trace[102083646] 'compare' (duration: 137.479563ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:45:26.080784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-10T13:45:25.729463Z","time spent":"351.250268ms","remote":"127.0.0.1:40140","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-03-10T13:45:28.745073Z","caller":"traceutil/trace.go:171","msg":"trace[1046526723] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"215.132938ms","start":"2026-03-10T13:45:28.529916Z","end":"2026-03-10T13:45:28.745049Z","steps":["trace[1046526723] 'process raft request' (duration: 202.672311ms)","trace[1046526723] 'compare' (duration: 12.217362ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:45:28.745118Z","caller":"traceutil/trace.go:171","msg":"trace[472412829] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"174.337693ms","start":"2026-03-10T13:45:28.570753Z","end":"2026-03-10T13:45:28.745091Z","steps":["trace[472412829] 'process raft request' (duration: 174.233771ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:28.745154Z","caller":"traceutil/trace.go:171","msg":"trace[1091246194] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1030; }","duration":"177.815307ms","start":"2026-03-10T13:45:28.567317Z","end":"2026-03-10T13:45:28.745132Z","steps":["trace[1091246194] 'read index received' (duration: 165.329539ms)","trace[1091246194] 'applied index is now lower than readState.Index' (duration: 12.483588ms)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:45:28.745345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.397782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-10T13:45:28.745411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.650132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-03-10T13:45:28.745423Z","caller":"traceutil/trace.go:171","msg":"trace[1844288119] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:992; }","duration":"204.521975ms","start":"2026-03-10T13:45:28.540887Z","end":"2026-03-10T13:45:28.745409Z","steps":["trace[1844288119] 'agreement among raft nodes before linearized reading' (duration: 204.359401ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:45:28.745444Z","caller":"traceutil/trace.go:171","msg":"trace[373212350] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:992; }","duration":"156.704453ms","start":"2026-03-10T13:45:28.588729Z","end":"2026-03-10T13:45:28.745433Z","steps":["trace[373212350] 'agreement among raft nodes before linearized reading' (duration: 156.574259ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:45:28.745626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.711947ms","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-03-10T13:45:28.74569Z","caller":"traceutil/trace.go:171","msg":"trace[1183133664] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:992; }","duration":"211.832799ms","start":"2026-03-10T13:45:28.533841Z","end":"2026-03-10T13:45:28.745673Z","steps":["trace[1183133664] 'agreement among raft nodes before linearized reading' (duration: 211.654725ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:47:34.25574Z","caller":"traceutil/trace.go:171","msg":"trace[111007546] transaction","detail":"{read_only:false; response_revision:1505; number_of_response:1; }","duration":"252.106018ms","start":"2026-03-10T13:47:34.003608Z","end":"2026-03-10T13:47:34.255714Z","steps":["trace[111007546] 'process raft request' (duration: 251.848352ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:48:27.029147Z","caller":"traceutil/trace.go:171","msg":"trace[1072969577] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"134.746681ms","start":"2026-03-10T13:48:26.894351Z","end":"2026-03-10T13:48:27.029097Z","steps":["trace[1072969577] 'process raft request' (duration: 134.454464ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:48:33.028762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.35825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-10T13:48:33.028795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.642408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-03-10T13:48:33.028871Z","caller":"traceutil/trace.go:171","msg":"trace[462037046] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1773; }","duration":"130.763381ms","start":"2026-03-10T13:48:32.898088Z","end":"2026-03-10T13:48:33.028852Z","steps":["trace[462037046] 'range keys from in-memory index tree' (duration: 130.491734ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:48:33.028829Z","caller":"traceutil/trace.go:171","msg":"trace[228435672] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1773; }","duration":"220.475163ms","start":"2026-03-10T13:48:32.80834Z","end":"2026-03-10T13:48:33.028815Z","steps":["trace[228435672] 'count revisions from in-memory index tree' (duration: 220.258548ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:48:33.697582Z","caller":"traceutil/trace.go:171","msg":"trace[2073012038] transaction","detail":"{read_only:false; response_revision:1777; number_of_response:1; }","duration":"101.278922ms","start":"2026-03-10T13:48:33.596276Z","end":"2026-03-10T13:48:33.697555Z","steps":["trace[2073012038] 'process raft request' (duration: 101.094527ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:48:33.868861Z","caller":"traceutil/trace.go:171","msg":"trace[899416972] transaction","detail":"{read_only:false; response_revision:1780; number_of_response:1; }","duration":"136.66325ms","start":"2026-03-10T13:48:33.732169Z","end":"2026-03-10T13:48:33.868832Z","steps":["trace[899416972] 'process raft request' (duration: 73.71685ms)","trace[899416972] 'compare' (duration: 62.830697ms)"],"step_count":2} {"level":"info","ts":"2026-03-10T13:48:34.565557Z","caller":"traceutil/trace.go:171","msg":"trace[308444781] linearizableReadLoop","detail":"{readStateIndex:1859; appliedIndex:1858; }","duration":"124.246298ms","start":"2026-03-10T13:48:34.441287Z","end":"2026-03-10T13:48:34.565533Z","steps":["trace[308444781] 'read index received' (duration: 124.084084ms)","trace[308444781] 'applied index is now lower than readState.Index' (duration: 160.804µs)"],"step_count":2} {"level":"warn","ts":"2026-03-10T13:48:34.565672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.374912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-10T13:48:34.565723Z","caller":"traceutil/trace.go:171","msg":"trace[943928371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1781; }","duration":"124.457644ms","start":"2026-03-10T13:48:34.441253Z","end":"2026-03-10T13:48:34.565711Z","steps":["trace[943928371] 'agreement among raft nodes before linearized reading' (duration: 124.376982ms)"],"step_count":1} {"level":"info","ts":"2026-03-10T13:48:34.565832Z","caller":"traceutil/trace.go:171","msg":"trace[407836669] transaction","detail":"{read_only:false; response_revision:1781; number_of_response:1; }","duration":"198.521593ms","start":"2026-03-10T13:48:34.367281Z","end":"2026-03-10T13:48:34.565803Z","steps":["trace[407836669] 'process raft request' (duration: 198.104322ms)"],"step_count":1} {"level":"warn","ts":"2026-03-10T13:48:34.815765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.587141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-03-10T13:48:34.815844Z","caller":"traceutil/trace.go:171","msg":"trace[1094435264] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1781; }","duration":"121.726695ms","start":"2026-03-10T13:48:34.694099Z","end":"2026-03-10T13:48:34.815826Z","steps":["trace[1094435264] 'range keys from in-memory index tree' (duration: 121.423298ms)"],"step_count":1}