{"level":"warn","ts":"2026-03-14T07:00:54.491397Z","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-14T07:00:54.49149Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223: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.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-14T07:00:54.491557Z","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-14T07:00:54.491569Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-03-14T07:00:54.49159Z","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-14T07:00:54.49215Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-03-14T07:00:54.49223Z","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.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-14T07:00:54.496176Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.740654ms"} {"level":"info","ts":"2026-03-14T07:00:54.50259Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-03-14T07:00:54.502655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-03-14T07:00:54.502678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-03-14T07:00:54.502694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-14T07:00:54.502704Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-03-14T07:00:54.502743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-03-14T07:00:54.508391Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-14T07:00:54.511671Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-14T07:00:54.514158Z","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-14T07:00:54.516023Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-14T07:00:54.51632Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-14T07:00:54.516419Z","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-14T07:00:54.516695Z","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-14T07:00:54.516705Z","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-14T07:00:54.517345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-03-14T07:00:54.517526Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-03-14T07:00:54.519185Z","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-14T07:00:54.519367Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-03-14T07:00:54.519497Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-14T07:00:54.519442Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-14T07:00:54.519515Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-03-14T07:00:54.612489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-03-14T07:00:54.61253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-14T07:00:54.61255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-03-14T07:00:54.612562Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-03-14T07:00:54.612571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-03-14T07:00:54.61258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-03-14T07:00:54.612588Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-03-14T07:00:54.613923Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-03-14T07:00:54.613942Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T07:00:54.614437Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-14T07:00:54.614657Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:00:54.614921Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-14T07:00:54.614949Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-14T07:00:54.616479Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:00:54.616596Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:00:54.616631Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-14T07:00:54.617442Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-03-14T07:00:54.617993Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-14T07:01:06.840212Z","caller":"traceutil/trace.go:171","msg":"trace[1784520836] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"385.582951ms","start":"2026-03-14T07:01:06.454599Z","end":"2026-03-14T07:01:06.840182Z","steps":["trace[1784520836] 'process raft request' (duration: 385.348866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:06.840947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:06.454581Z","time spent":"385.754815ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:06.909291Z","caller":"traceutil/trace.go:171","msg":"trace[740744704] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:403; }","duration":"287.815145ms","start":"2026-03-14T07:01:06.62145Z","end":"2026-03-14T07:01:06.909265Z","steps":["trace[740744704] 'read index received' (duration: 218.525778ms)","trace[740744704] 'applied index is now lower than readState.Index' (duration: 69.288177ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:06.909346Z","caller":"traceutil/trace.go:171","msg":"trace[2047383256] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"451.380821ms","start":"2026-03-14T07:01:06.457935Z","end":"2026-03-14T07:01:06.909316Z","steps":["trace[2047383256] 'process raft request' (duration: 451.096964ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:06.909374Z","caller":"traceutil/trace.go:171","msg":"trace[668865593] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"402.145193ms","start":"2026-03-14T07:01:06.507203Z","end":"2026-03-14T07:01:06.909348Z","steps":["trace[668865593] 'process raft request' (duration: 402.01072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:06.909459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:06.457916Z","time spent":"451.483762ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:01:06.909445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.96089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-14T07:01:06.90952Z","caller":"traceutil/trace.go:171","msg":"trace[1578447961] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:393; }","duration":"288.089662ms","start":"2026-03-14T07:01:06.621421Z","end":"2026-03-14T07:01:06.90951Z","steps":["trace[1578447961] 'agreement among raft nodes before linearized reading' (duration: 287.951269ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:06.90954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:06.507182Z","time spent":"402.252236ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:12.476716Z","caller":"traceutil/trace.go:171","msg":"trace[1445589506] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"222.795684ms","start":"2026-03-14T07:01:12.253895Z","end":"2026-03-14T07:01:12.476691Z","steps":["trace[1445589506] 'process raft request' (duration: 222.63669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:18.477627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:18.046205Z","time spent":"431.417211ms","remote":"127.0.0.1:43310","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-14T07:01:19.006883Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224805510703589,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-14T07:01:19.136602Z","caller":"traceutil/trace.go:171","msg":"trace[980662383] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"657.614154ms","start":"2026-03-14T07:01:18.478969Z","end":"2026-03-14T07:01:19.136583Z","steps":["trace[980662383] 'process raft request' (duration: 585.701868ms)","trace[980662383] 'compare' (duration: 71.703651ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:19.136786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:18.478948Z","time spent":"657.737866ms","remote":"127.0.0.1:43310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:01:19.137088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.942554ms","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-14T07:01:19.137178Z","caller":"traceutil/trace.go:171","msg":"trace[1410293863] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:459; }","duration":"631.064846ms","start":"2026-03-14T07:01:18.506079Z","end":"2026-03-14T07:01:19.137144Z","steps":["trace[1410293863] 'agreement among raft nodes before linearized reading' (duration: 630.615006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:19.137222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:18.506057Z","time spent":"631.155889ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-14T07:01:19.13743Z","caller":"traceutil/trace.go:171","msg":"trace[884233006] linearizableReadLoop","detail":"{readStateIndex:475; appliedIndex:474; }","duration":"630.393831ms","start":"2026-03-14T07:01:18.506125Z","end":"2026-03-14T07:01:19.136519Z","steps":["trace[884233006] 'read index received' (duration: 558.605068ms)","trace[884233006] 'applied index is now lower than readState.Index' (duration: 71.787603ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:19.137553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.167492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:01:19.137622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.928559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-14T07:01:19.137657Z","caller":"traceutil/trace.go:171","msg":"trace[1012462938] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:459; }","duration":"161.987379ms","start":"2026-03-14T07:01:18.975659Z","end":"2026-03-14T07:01:19.137647Z","steps":["trace[1012462938] 'agreement among raft nodes before linearized reading' (duration: 161.927308ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:19.137946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.081556ms","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-14T07:01:19.137976Z","caller":"traceutil/trace.go:171","msg":"trace[231061089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:459; }","duration":"241.117316ms","start":"2026-03-14T07:01:18.896851Z","end":"2026-03-14T07:01:19.137968Z","steps":["trace[231061089] 'agreement among raft nodes before linearized reading' (duration: 241.051145ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:19.138756Z","caller":"traceutil/trace.go:171","msg":"trace[1828833413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:459; }","duration":"268.40242ms","start":"2026-03-14T07:01:18.870332Z","end":"2026-03-14T07:01:19.138734Z","steps":["trace[1828833413] 'agreement among raft nodes before linearized reading' (duration: 267.183562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:19.643033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224805510703597,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-14T07:01:19.796831Z","caller":"traceutil/trace.go:171","msg":"trace[2025795244] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"655.059556ms","start":"2026-03-14T07:01:19.141741Z","end":"2026-03-14T07:01:19.7968Z","steps":["trace[2025795244] 'process raft request' (duration: 555.607641ms)","trace[2025795244] 'compare' (duration: 99.28677ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:19.797033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:19.141728Z","time spent":"655.20568ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:19.830178Z","caller":"traceutil/trace.go:171","msg":"trace[749437593] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"687.018473ms","start":"2026-03-14T07:01:19.143141Z","end":"2026-03-14T07:01:19.83016Z","steps":["trace[749437593] 'process raft request' (duration: 686.902671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:19.830297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:19.143126Z","time spent":"687.110465ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:19.830369Z","caller":"traceutil/trace.go:171","msg":"trace[1214694219] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:475; }","duration":"687.442223ms","start":"2026-03-14T07:01:19.142895Z","end":"2026-03-14T07:01:19.830337Z","steps":["trace[1214694219] 'read index received' (duration: 554.464646ms)","trace[1214694219] 'applied index is now lower than readState.Index' (duration: 132.976197ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:19.83045Z","caller":"traceutil/trace.go:171","msg":"trace[1004398397] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"686.516613ms","start":"2026-03-14T07:01:19.143911Z","end":"2026-03-14T07:01:19.830427Z","steps":["trace[1004398397] 'process raft request' (duration: 686.225826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:19.830516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.614598ms","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-14T07:01:19.830561Z","caller":"traceutil/trace.go:171","msg":"trace[177271595] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:462; }","duration":"687.70482ms","start":"2026-03-14T07:01:19.142844Z","end":"2026-03-14T07:01:19.830549Z","steps":["trace[177271595] 'agreement among raft nodes before linearized reading' (duration: 687.620698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:19.830563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:19.143897Z","time spent":"686.613275ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:01:19.830594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:19.142802Z","time spent":"687.784562ms","remote":"127.0.0.1:43572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-03-14T07:01:19.830527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.284478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-14T07:01:19.830661Z","caller":"traceutil/trace.go:171","msg":"trace[615513159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:462; }","duration":"589.445343ms","start":"2026-03-14T07:01:19.241189Z","end":"2026-03-14T07:01:19.830634Z","steps":["trace[615513159] 'agreement among raft nodes before linearized reading' (duration: 589.31959ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:19.83073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:19.241165Z","time spent":"589.548424ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-14T07:01:21.996498Z","caller":"traceutil/trace.go:171","msg":"trace[714503908] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"161.162031ms","start":"2026-03-14T07:01:21.835309Z","end":"2026-03-14T07:01:21.996471Z","steps":["trace[714503908] 'read index received' (duration: 160.991577ms)","trace[714503908] 'applied index is now lower than readState.Index' (duration: 169.254µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:21.996828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.837634ms","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-14T07:01:21.996935Z","caller":"traceutil/trace.go:171","msg":"trace[1932752274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:465; }","duration":"160.993938ms","start":"2026-03-14T07:01:21.835927Z","end":"2026-03-14T07:01:21.996921Z","steps":["trace[1932752274] 'agreement among raft nodes before linearized reading' (duration: 160.783283ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:21.996991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.139099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:21.997075Z","caller":"traceutil/trace.go:171","msg":"trace[586214706] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:465; }","duration":"128.257372ms","start":"2026-03-14T07:01:21.868795Z","end":"2026-03-14T07:01:21.997052Z","steps":["trace[586214706] 'agreement among raft nodes before linearized reading' (duration: 128.123349ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:21.997177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.845447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-14T07:01:21.997276Z","caller":"traceutil/trace.go:171","msg":"trace[2047970598] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:465; }","duration":"161.992779ms","start":"2026-03-14T07:01:21.835263Z","end":"2026-03-14T07:01:21.997256Z","steps":["trace[2047970598] 'agreement among raft nodes before linearized reading' (duration: 161.768274ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:21.996824Z","caller":"traceutil/trace.go:171","msg":"trace[298479366] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"190.354637ms","start":"2026-03-14T07:01:21.806428Z","end":"2026-03-14T07:01:21.996782Z","steps":["trace[298479366] 'process raft request' (duration: 189.848246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:21.997444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.973231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-14T07:01:21.997564Z","caller":"traceutil/trace.go:171","msg":"trace[1687803880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:465; }","duration":"147.153176ms","start":"2026-03-14T07:01:21.850397Z","end":"2026-03-14T07:01:21.997551Z","steps":["trace[1687803880] 'agreement among raft nodes before linearized reading' (duration: 146.993542ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:28.334477Z","caller":"traceutil/trace.go:171","msg":"trace[979622405] linearizableReadLoop","detail":"{readStateIndex:530; appliedIndex:529; }","duration":"283.377736ms","start":"2026-03-14T07:01:28.051065Z","end":"2026-03-14T07:01:28.334442Z","steps":["trace[979622405] 'read index received' (duration: 283.214122ms)","trace[979622405] 'applied index is now lower than readState.Index' (duration: 162.144µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:28.334745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.650171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-14T07:01:28.33482Z","caller":"traceutil/trace.go:171","msg":"trace[1257827728] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"283.777165ms","start":"2026-03-14T07:01:28.051024Z","end":"2026-03-14T07:01:28.334801Z","steps":["trace[1257827728] 'agreement among raft nodes before linearized reading' (duration: 283.527339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:28.334869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.748307ms","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-14T07:01:28.334917Z","caller":"traceutil/trace.go:171","msg":"trace[1570362094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:512; }","duration":"252.86638ms","start":"2026-03-14T07:01:28.082036Z","end":"2026-03-14T07:01:28.334902Z","steps":["trace[1570362094] 'agreement among raft nodes before linearized reading' (duration: 252.629015ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:28.335009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.683576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-14T07:01:28.335124Z","caller":"traceutil/trace.go:171","msg":"trace[1562518804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:512; }","duration":"236.869091ms","start":"2026-03-14T07:01:28.098226Z","end":"2026-03-14T07:01:28.335095Z","steps":["trace[1562518804] 'agreement among raft nodes before linearized reading' (duration: 236.501533ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:28.335123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.897301ms","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-14T07:01:28.335229Z","caller":"traceutil/trace.go:171","msg":"trace[302054137] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"237.044424ms","start":"2026-03-14T07:01:28.098156Z","end":"2026-03-14T07:01:28.3352Z","steps":["trace[302054137] 'agreement among raft nodes before linearized reading' (duration: 236.739218ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:28.584237Z","caller":"traceutil/trace.go:171","msg":"trace[1184787015] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"243.539312ms","start":"2026-03-14T07:01:28.340671Z","end":"2026-03-14T07:01:28.58421Z","steps":["trace[1184787015] 'process raft request' (duration: 243.46099ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:28.584295Z","caller":"traceutil/trace.go:171","msg":"trace[1627980222] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"244.253327ms","start":"2026-03-14T07:01:28.340004Z","end":"2026-03-14T07:01:28.584257Z","steps":["trace[1627980222] 'process raft request' (duration: 243.97266ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:28.584515Z","caller":"traceutil/trace.go:171","msg":"trace[1571158544] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"243.781026ms","start":"2026-03-14T07:01:28.34072Z","end":"2026-03-14T07:01:28.584501Z","steps":["trace[1571158544] 'process raft request' (duration: 243.454949ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:28.584634Z","caller":"traceutil/trace.go:171","msg":"trace[1502527138] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"243.417197ms","start":"2026-03-14T07:01:28.341183Z","end":"2026-03-14T07:01:28.5846Z","steps":["trace[1502527138] 'process raft request' (duration: 243.213353ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:28.584619Z","caller":"traceutil/trace.go:171","msg":"trace[348777648] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:531; }","duration":"227.110002ms","start":"2026-03-14T07:01:28.357481Z","end":"2026-03-14T07:01:28.584591Z","steps":["trace[348777648] 'read index received' (duration: 226.482098ms)","trace[348777648] 'applied index is now lower than readState.Index' (duration: 626.674µs)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:28.584945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.408759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-14T07:01:28.585043Z","caller":"traceutil/trace.go:171","msg":"trace[1318244978] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:517; }","duration":"227.586774ms","start":"2026-03-14T07:01:28.357439Z","end":"2026-03-14T07:01:28.585026Z","steps":["trace[1318244978] 'agreement among raft nodes before linearized reading' (duration: 227.241315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:36.369762Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224805510703756,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-14T07:01:36.518497Z","caller":"traceutil/trace.go:171","msg":"trace[1676040841] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"649.523212ms","start":"2026-03-14T07:01:35.868935Z","end":"2026-03-14T07:01:36.518458Z","steps":["trace[1676040841] 'read index received' (duration: 649.152313ms)","trace[1676040841] 'applied index is now lower than readState.Index' (duration: 369.589µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:36.518931Z","caller":"traceutil/trace.go:171","msg":"trace[1120710038] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"821.555537ms","start":"2026-03-14T07:01:35.697337Z","end":"2026-03-14T07:01:36.518892Z","steps":["trace[1120710038] 'process raft request' (duration: 820.855312ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:36.519119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:35.69729Z","time spent":"821.736791ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:01:36.51911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.131385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:36.519262Z","caller":"traceutil/trace.go:171","msg":"trace[1859254180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"650.34459ms","start":"2026-03-14T07:01:35.868881Z","end":"2026-03-14T07:01:36.519225Z","steps":["trace[1859254180] 'agreement among raft nodes before linearized reading' (duration: 650.072494ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:36.519369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:35.868858Z","time spent":"650.499644ms","remote":"127.0.0.1:43302","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-14T07:01:36.793694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.502893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-14T07:01:36.793806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.801047ms","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-14T07:01:36.793862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.476673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-14T07:01:36.793931Z","caller":"traceutil/trace.go:171","msg":"trace[138597824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"154.999411ms","start":"2026-03-14T07:01:36.638912Z","end":"2026-03-14T07:01:36.793912Z","steps":["trace[138597824] 'range keys from in-memory index tree' (duration: 154.548032ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:36.793987Z","caller":"traceutil/trace.go:171","msg":"trace[738707768] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:534; }","duration":"159.696997ms","start":"2026-03-14T07:01:36.63426Z","end":"2026-03-14T07:01:36.793957Z","steps":["trace[738707768] 'range keys from in-memory index tree' (duration: 159.186077ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:36.793853Z","caller":"traceutil/trace.go:171","msg":"trace[1873706010] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"159.736078ms","start":"2026-03-14T07:01:36.634086Z","end":"2026-03-14T07:01:36.793823Z","steps":["trace[1873706010] 'range keys from in-memory index tree' (duration: 159.252388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:38.370584Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224805510703768,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-14T07:01:38.507859Z","caller":"traceutil/trace.go:171","msg":"trace[1111399720] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"638.240658ms","start":"2026-03-14T07:01:37.869592Z","end":"2026-03-14T07:01:38.507833Z","steps":["trace[1111399720] 'read index received' (duration: 638.006833ms)","trace[1111399720] 'applied index is now lower than readState.Index' (duration: 232.645µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:38.507931Z","caller":"traceutil/trace.go:171","msg":"trace[1141340108] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"760.354861ms","start":"2026-03-14T07:01:37.74755Z","end":"2026-03-14T07:01:38.507905Z","steps":["trace[1141340108] 'process raft request' (duration: 760.076505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:38.50844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:37.747525Z","time spent":"760.798432ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:01:38.508578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.007291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-14T07:01:38.508664Z","caller":"traceutil/trace.go:171","msg":"trace[1554586591] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:539; }","duration":"463.137864ms","start":"2026-03-14T07:01:38.045501Z","end":"2026-03-14T07:01:38.508639Z","steps":["trace[1554586591] 'agreement among raft nodes before linearized reading' (duration: 462.840367ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:38.508736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:38.045478Z","time spent":"463.241376ms","remote":"127.0.0.1:43310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.223\" "} {"level":"warn","ts":"2026-03-14T07:01:38.508161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.490524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:38.509087Z","caller":"traceutil/trace.go:171","msg":"trace[1409182289] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"639.542107ms","start":"2026-03-14T07:01:37.869521Z","end":"2026-03-14T07:01:38.509063Z","steps":["trace[1409182289] 'agreement among raft nodes before linearized reading' (duration: 638.481394ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:38.509151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:37.869492Z","time spent":"639.64686ms","remote":"127.0.0.1:43302","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-14T07:01:39.979301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.500432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:39.979388Z","caller":"traceutil/trace.go:171","msg":"trace[1512130656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"110.639285ms","start":"2026-03-14T07:01:39.868729Z","end":"2026-03-14T07:01:39.979368Z","steps":["trace[1512130656] 'range keys from in-memory index tree' (duration: 110.350039ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:41.226693Z","caller":"traceutil/trace.go:171","msg":"trace[180274834] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"250.100308ms","start":"2026-03-14T07:01:40.976558Z","end":"2026-03-14T07:01:41.226659Z","steps":["trace[180274834] 'read index received' (duration: 249.388262ms)","trace[180274834] 'applied index is now lower than readState.Index' (duration: 710.336µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:41.226819Z","caller":"traceutil/trace.go:171","msg":"trace[1575639416] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"348.820116ms","start":"2026-03-14T07:01:40.877971Z","end":"2026-03-14T07:01:41.226791Z","steps":["trace[1575639416] 'process raft request' (duration: 347.954587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:41.226972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.392785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-14T07:01:41.226985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:40.877936Z","time spent":"348.965159ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:41.227021Z","caller":"traceutil/trace.go:171","msg":"trace[194496531] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:575; }","duration":"250.481096ms","start":"2026-03-14T07:01:40.976525Z","end":"2026-03-14T07:01:41.227006Z","steps":["trace[194496531] 'agreement among raft nodes before linearized reading' (duration: 250.283152ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:41.230275Z","caller":"traceutil/trace.go:171","msg":"trace[1584816005] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"172.69072ms","start":"2026-03-14T07:01:41.057553Z","end":"2026-03-14T07:01:41.230244Z","steps":["trace[1584816005] 'process raft request' (duration: 172.494685ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:41.23037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.001544ms","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-14T07:01:41.230687Z","caller":"traceutil/trace.go:171","msg":"trace[2127116736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"253.265341ms","start":"2026-03-14T07:01:40.977318Z","end":"2026-03-14T07:01:41.230584Z","steps":["trace[2127116736] 'agreement among raft nodes before linearized reading' (duration: 252.901743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:41.230744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.340772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-14T07:01:41.230817Z","caller":"traceutil/trace.go:171","msg":"trace[171519668] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"253.455664ms","start":"2026-03-14T07:01:40.977339Z","end":"2026-03-14T07:01:41.230794Z","steps":["trace[171519668] 'agreement among raft nodes before linearized reading' (duration: 253.24434ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:48.807199Z","caller":"traceutil/trace.go:171","msg":"trace[392592345] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"103.239961ms","start":"2026-03-14T07:01:48.703934Z","end":"2026-03-14T07:01:48.807174Z","steps":["trace[392592345] 'read index received' (duration: 103.101928ms)","trace[392592345] 'applied index is now lower than readState.Index' (duration: 136.533µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:48.807274Z","caller":"traceutil/trace.go:171","msg":"trace[508095127] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"250.754679ms","start":"2026-03-14T07:01:48.556497Z","end":"2026-03-14T07:01:48.807252Z","steps":["trace[508095127] 'process raft request' (duration: 250.526385ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:48.807453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.482027ms","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-14T07:01:48.8075Z","caller":"traceutil/trace.go:171","msg":"trace[380617176] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:661; }","duration":"103.601829ms","start":"2026-03-14T07:01:48.703888Z","end":"2026-03-14T07:01:48.80749Z","steps":["trace[380617176] 'agreement among raft nodes before linearized reading' (duration: 103.392494ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:49.082814Z","caller":"traceutil/trace.go:171","msg":"trace[1994753007] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"343.344807ms","start":"2026-03-14T07:01:48.739442Z","end":"2026-03-14T07:01:49.082787Z","steps":["trace[1994753007] 'process raft request' (duration: 343.066261ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:49.08296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:48.739418Z","time spent":"343.462029ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:49.083216Z","caller":"traceutil/trace.go:171","msg":"trace[410065572] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:684; }","duration":"214.762306ms","start":"2026-03-14T07:01:48.868431Z","end":"2026-03-14T07:01:49.083194Z","steps":["trace[410065572] 'read index received' (duration: 214.088151ms)","trace[410065572] 'applied index is now lower than readState.Index' (duration: 673.135µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:49.083282Z","caller":"traceutil/trace.go:171","msg":"trace[2050505189] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"270.943027ms","start":"2026-03-14T07:01:48.812297Z","end":"2026-03-14T07:01:49.08324Z","steps":["trace[2050505189] 'process raft request' (duration: 270.793044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:49.083322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.876118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:49.083348Z","caller":"traceutil/trace.go:171","msg":"trace[1503618450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"214.94428ms","start":"2026-03-14T07:01:48.868397Z","end":"2026-03-14T07:01:49.083342Z","steps":["trace[1503618450] 'agreement among raft nodes before linearized reading' (duration: 214.880509ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:53.420927Z","caller":"traceutil/trace.go:171","msg":"trace[1917265605] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:732; }","duration":"123.122891ms","start":"2026-03-14T07:01:53.297782Z","end":"2026-03-14T07:01:53.420904Z","steps":["trace[1917265605] 'read index received' (duration: 37.98087ms)","trace[1917265605] 'applied index is now lower than readState.Index' (duration: 85.140661ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:53.421072Z","caller":"traceutil/trace.go:171","msg":"trace[196797585] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"126.229749ms","start":"2026-03-14T07:01:53.294805Z","end":"2026-03-14T07:01:53.421035Z","steps":["trace[196797585] 'process raft request' (duration: 64.597228ms)","trace[196797585] 'compare' (duration: 61.395976ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:53.421163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.358306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-14T07:01:53.421206Z","caller":"traceutil/trace.go:171","msg":"trace[1519996230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:711; }","duration":"123.426877ms","start":"2026-03-14T07:01:53.297765Z","end":"2026-03-14T07:01:53.421192Z","steps":["trace[1519996230] 'agreement among raft nodes before linearized reading' (duration: 123.268803ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:53.421321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.618053ms","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-14T07:01:53.42139Z","caller":"traceutil/trace.go:171","msg":"trace[680652822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:711; }","duration":"114.722595ms","start":"2026-03-14T07:01:53.306649Z","end":"2026-03-14T07:01:53.421371Z","steps":["trace[680652822] 'agreement among raft nodes before linearized reading' (duration: 114.49837ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:55.368868Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224805510704095,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:01:55.869072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224805510704095,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-14T07:01:56.0888Z","caller":"traceutil/trace.go:171","msg":"trace[338414449] linearizableReadLoop","detail":"{readStateIndex:738; appliedIndex:737; }","duration":"1.220304643s","start":"2026-03-14T07:01:54.868478Z","end":"2026-03-14T07:01:56.088783Z","steps":["trace[338414449] 'read index received' (duration: 1.220217591s)","trace[338414449] 'applied index is now lower than readState.Index' (duration: 86.512µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:01:56.088842Z","caller":"traceutil/trace.go:171","msg":"trace[1581085662] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"1.265067223s","start":"2026-03-14T07:01:54.823745Z","end":"2026-03-14T07:01:56.088812Z","steps":["trace[1581085662] 'process raft request' (duration: 1.264882679s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:56.088917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220431066s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:56.088941Z","caller":"traceutil/trace.go:171","msg":"trace[407241834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:714; }","duration":"1.220507717s","start":"2026-03-14T07:01:54.868427Z","end":"2026-03-14T07:01:56.088935Z","steps":["trace[407241834] 'agreement among raft nodes before linearized reading' (duration: 1.220437986s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:56.088962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:54.868411Z","time spent":"1.220546789s","remote":"127.0.0.1:43302","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-14T07:01:56.088983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:54.823719Z","time spent":"1.265189185s","remote":"127.0.0.1:43354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":811,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:01:56.089171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.774403ms","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-14T07:01:56.089193Z","caller":"traceutil/trace.go:171","msg":"trace[1832635741] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:714; }","duration":"967.836335ms","start":"2026-03-14T07:01:55.121352Z","end":"2026-03-14T07:01:56.089188Z","steps":["trace[1832635741] 'agreement among raft nodes before linearized reading' (duration: 967.755743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:56.089224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:55.121327Z","time spent":"967.893375ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-14T07:01:56.089425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.711651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-14T07:01:56.08945Z","caller":"traceutil/trace.go:171","msg":"trace[1329027519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:714; }","duration":"623.755422ms","start":"2026-03-14T07:01:55.46568Z","end":"2026-03-14T07:01:56.089436Z","steps":["trace[1329027519] 'agreement among raft nodes before linearized reading' (duration: 623.709781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:56.089474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:55.465662Z","time spent":"623.798563ms","remote":"127.0.0.1:43566","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-14T07:01:56.089567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"749.92249ms","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-14T07:01:56.089622Z","caller":"traceutil/trace.go:171","msg":"trace[1948010933] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:714; }","duration":"750.022582ms","start":"2026-03-14T07:01:55.339588Z","end":"2026-03-14T07:01:56.08961Z","steps":["trace[1948010933] 'agreement among raft nodes before linearized reading' (duration: 749.852828ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:56.089656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:55.339564Z","time spent":"750.084533ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-14T07:01:56.089671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.284953ms","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-14T07:01:56.08969Z","caller":"traceutil/trace.go:171","msg":"trace[818992933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"624.332285ms","start":"2026-03-14T07:01:55.465352Z","end":"2026-03-14T07:01:56.089684Z","steps":["trace[818992933] 'agreement among raft nodes before linearized reading' (duration: 624.287564ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:56.08971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:55.465333Z","time spent":"624.373685ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-14T07:01:56.089955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.592343ms","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-14T07:01:56.089996Z","caller":"traceutil/trace.go:171","msg":"trace[493206871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:714; }","duration":"664.665895ms","start":"2026-03-14T07:01:55.42532Z","end":"2026-03-14T07:01:56.089986Z","steps":["trace[493206871] 'agreement among raft nodes before linearized reading' (duration: 664.42544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:56.090024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:55.425302Z","time spent":"664.715596ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-14T07:01:56.598792Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224805510704102,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:01:57.138615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.459653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:01:57.138723Z","caller":"traceutil/trace.go:171","msg":"trace[1340266793] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"1.044904177s","start":"2026-03-14T07:01:56.093799Z","end":"2026-03-14T07:01:57.138704Z","steps":["trace[1340266793] 'process raft request' (duration: 694.093346ms)","trace[1340266793] 'compare' (duration: 349.996813ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:57.138786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:56.093786Z","time spent":"1.044971098s","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:01:57.848843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.95294ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:01:57.849078Z","caller":"traceutil/trace.go:171","msg":"trace[1045160] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"1.754586687s","start":"2026-03-14T07:01:56.094474Z","end":"2026-03-14T07:01:57.849061Z","steps":["trace[1045160] 'process raft request' (duration: 1.754518575s)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:57.849128Z","caller":"traceutil/trace.go:171","msg":"trace[1497146636] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"1.754834842s","start":"2026-03-14T07:01:56.094263Z","end":"2026-03-14T07:01:57.849098Z","steps":["trace[1497146636] 'process raft request' (duration: 1.754680549s)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:01:57.849146Z","caller":"traceutil/trace.go:171","msg":"trace[269765829] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"1.755235832s","start":"2026-03-14T07:01:56.093886Z","end":"2026-03-14T07:01:57.849122Z","steps":["trace[269765829] 'process raft request' (duration: 1.577915915s)","trace[269765829] 'compare' (duration: 176.827926ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:57.849168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:56.094464Z","time spent":"1.754652678s","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:01:57.849257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:56.094244Z","time spent":"1.754943744s","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:57.849306Z","caller":"traceutil/trace.go:171","msg":"trace[779393133] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:738; }","duration":"1.751037429s","start":"2026-03-14T07:01:56.09826Z","end":"2026-03-14T07:01:57.849298Z","steps":["trace[779393133] 'read index received' (duration: 689.731639ms)","trace[779393133] 'applied index is now lower than readState.Index' (duration: 1.06130484s)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:57.849257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:56.093868Z","time spent":"1.755335774s","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:01:57.849401Z","caller":"traceutil/trace.go:171","msg":"trace[1383513686] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"1.754402343s","start":"2026-03-14T07:01:56.094992Z","end":"2026-03-14T07:01:57.849395Z","steps":["trace[1383513686] 'process raft request' (duration: 1.754032085s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:57.849433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:56.094982Z","time spent":"1.754433503s","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:01:57.849531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.751268204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:57.849551Z","caller":"traceutil/trace.go:171","msg":"trace[1402086166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:719; }","duration":"1.751300944s","start":"2026-03-14T07:01:56.098243Z","end":"2026-03-14T07:01:57.849544Z","steps":["trace[1402086166] 'agreement among raft nodes before linearized reading' (duration: 1.751268933s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:57.849569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:56.098236Z","time spent":"1.751329116s","remote":"127.0.0.1:43302","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-14T07:01:57.849594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.584468677s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:01:57.849651Z","caller":"traceutil/trace.go:171","msg":"trace[531925957] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:719; }","duration":"1.58458635s","start":"2026-03-14T07:01:56.265051Z","end":"2026-03-14T07:01:57.849637Z","steps":["trace[531925957] 'agreement among raft nodes before linearized reading' (duration: 1.584468038s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:57.849677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.368315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-14T07:01:57.849684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:56.265032Z","time spent":"1.584642623s","remote":"127.0.0.1:43530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-03-14T07:01:57.849704Z","caller":"traceutil/trace.go:171","msg":"trace[527661316] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:719; }","duration":"638.429956ms","start":"2026-03-14T07:01:57.211266Z","end":"2026-03-14T07:01:57.849696Z","steps":["trace[527661316] 'agreement among raft nodes before linearized reading' (duration: 638.364985ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:57.849724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:57.211248Z","time spent":"638.471997ms","remote":"127.0.0.1:43818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:01:57.849853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.136813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T07:01:57.849939Z","caller":"traceutil/trace.go:171","msg":"trace[1320499206] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:719; }","duration":"573.409258ms","start":"2026-03-14T07:01:57.276513Z","end":"2026-03-14T07:01:57.849922Z","steps":["trace[1320499206] 'agreement among raft nodes before linearized reading' (duration: 573.077671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:01:57.850548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:57.276497Z","time spent":"574.039493ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:01:58.804538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.92072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:01:58.804663Z","caller":"traceutil/trace.go:171","msg":"trace[1608093997] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"644.797897ms","start":"2026-03-14T07:01:58.159845Z","end":"2026-03-14T07:01:58.804643Z","steps":["trace[1608093997] 'process raft request' (duration: 37.663042ms)","trace[1608093997] 'compare' (duration: 606.768967ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:01:58.804717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:01:58.159827Z","time spent":"644.866878ms","remote":"127.0.0.1:43310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:01:59.059911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.48085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T07:01:59.06002Z","caller":"traceutil/trace.go:171","msg":"trace[896722802] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:720; }","duration":"124.664594ms","start":"2026-03-14T07:01:58.935331Z","end":"2026-03-14T07:01:59.059995Z","steps":["trace[896722802] 'count revisions from in-memory index tree' (duration: 124.381328ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:19.36179Z","caller":"traceutil/trace.go:171","msg":"trace[1082672390] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"112.508706ms","start":"2026-03-14T07:02:19.249251Z","end":"2026-03-14T07:02:19.36176Z","steps":["trace[1082672390] 'process raft request' (duration: 112.334842ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:21.838622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.673453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:02:21.838861Z","caller":"traceutil/trace.go:171","msg":"trace[1972756528] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"382.330909ms","start":"2026-03-14T07:02:21.4565Z","end":"2026-03-14T07:02:21.838831Z","steps":["trace[1972756528] 'process raft request' (duration: 229.296547ms)","trace[1972756528] 'compare' (duration: 152.210843ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:02:21.838947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:21.456477Z","time spent":"382.43386ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:22.328917Z","caller":"traceutil/trace.go:171","msg":"trace[1290428940] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"302.056773ms","start":"2026-03-14T07:02:22.026833Z","end":"2026-03-14T07:02:22.32889Z","steps":["trace[1290428940] 'process raft request' (duration: 301.90243ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:22.32909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:22.0268Z","time spent":"302.198156ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:22.372672Z","caller":"traceutil/trace.go:171","msg":"trace[1464730166] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"345.551545ms","start":"2026-03-14T07:02:22.027096Z","end":"2026-03-14T07:02:22.372647Z","steps":["trace[1464730166] 'process raft request' (duration: 345.498693ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:22.372812Z","caller":"traceutil/trace.go:171","msg":"trace[320481157] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"345.749099ms","start":"2026-03-14T07:02:22.02697Z","end":"2026-03-14T07:02:22.372719Z","steps":["trace[320481157] 'process raft request' (duration: 345.504264ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:22.373004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:22.026949Z","time spent":"345.955433ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:22.373233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:22.027073Z","time spent":"345.670417ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:23.974985Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224805510704332,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:02:24.398034Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.157994205s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-14T07:02:24.784212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.977848ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-14T07:02:24.784314Z","caller":"traceutil/trace.go:171","msg":"trace[2040526701] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"1.309890211s","start":"2026-03-14T07:02:23.474407Z","end":"2026-03-14T07:02:24.784298Z","steps":["trace[2040526701] 'read index received' (duration: 923.76548ms)","trace[2040526701] 'applied index is now lower than readState.Index' (duration: 386.123291ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:02:24.784636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310222459s","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-14T07:02:24.784683Z","caller":"traceutil/trace.go:171","msg":"trace[1128509354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:795; }","duration":"1.31030645s","start":"2026-03-14T07:02:23.474365Z","end":"2026-03-14T07:02:24.784671Z","steps":["trace[1128509354] 'agreement among raft nodes before linearized reading' (duration: 1.310044875s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:24.784666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.666777ms","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-14T07:02:24.784705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.726403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:02:24.784761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.12041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-14T07:02:24.784794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.531434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-14T07:02:24.784766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:23.474347Z","time spent":"1.310407083s","remote":"127.0.0.1:43566","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-14T07:02:24.784793Z","caller":"traceutil/trace.go:171","msg":"trace[1164074583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"915.805075ms","start":"2026-03-14T07:02:23.868965Z","end":"2026-03-14T07:02:24.784771Z","steps":["trace[1164074583] 'agreement among raft nodes before linearized reading' (duration: 915.701872ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:24.784815Z","caller":"traceutil/trace.go:171","msg":"trace[799120575] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:795; }","duration":"407.578384ms","start":"2026-03-14T07:02:24.377231Z","end":"2026-03-14T07:02:24.784809Z","steps":["trace[799120575] 'agreement among raft nodes before linearized reading' (duration: 407.524613ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:24.784823Z","caller":"traceutil/trace.go:171","msg":"trace[1877300217] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:795; }","duration":"758.222932ms","start":"2026-03-14T07:02:24.026583Z","end":"2026-03-14T07:02:24.784806Z","steps":["trace[1877300217] 'agreement among raft nodes before linearized reading' (duration: 758.026158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:24.784842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:24.377219Z","time spent":"407.617755ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-14T07:02:24.784846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:23.868906Z","time spent":"915.930417ms","remote":"127.0.0.1:43302","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-14T07:02:24.784866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:24.026563Z","time spent":"758.294904ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-14T07:02:24.784519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.094332429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T07:02:24.784726Z","caller":"traceutil/trace.go:171","msg":"trace[245527692] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:795; }","duration":"450.773909ms","start":"2026-03-14T07:02:24.333936Z","end":"2026-03-14T07:02:24.78471Z","steps":["trace[245527692] 'agreement among raft nodes before linearized reading' (duration: 450.604206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:24.78506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:24.333915Z","time spent":"451.136798ms","remote":"127.0.0.1:43566","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-14T07:02:24.785011Z","caller":"traceutil/trace.go:171","msg":"trace[881071290] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:795; }","duration":"1.094867771s","start":"2026-03-14T07:02:23.690107Z","end":"2026-03-14T07:02:24.784975Z","steps":["trace[881071290] 'agreement among raft nodes before linearized reading' (duration: 1.094312409s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:24.785211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:23.690085Z","time spent":"1.095085365s","remote":"127.0.0.1:43616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:02:24.785413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.693597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-14T07:02:24.785466Z","caller":"traceutil/trace.go:171","msg":"trace[1443176659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:795; }","duration":"407.799109ms","start":"2026-03-14T07:02:24.377657Z","end":"2026-03-14T07:02:24.785456Z","steps":["trace[1443176659] 'agreement among raft nodes before linearized reading' (duration: 407.328879ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:24.785494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:24.377634Z","time spent":"407.85392ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-14T07:02:24.963717Z","caller":"traceutil/trace.go:171","msg":"trace[1814563256] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"174.27127ms","start":"2026-03-14T07:02:24.789423Z","end":"2026-03-14T07:02:24.963695Z","steps":["trace[1814563256] 'process raft request' (duration: 174.113777ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:25.231333Z","caller":"traceutil/trace.go:171","msg":"trace[605927221] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"441.669279ms","start":"2026-03-14T07:02:24.789615Z","end":"2026-03-14T07:02:25.231284Z","steps":["trace[605927221] 'process raft request' (duration: 441.225008ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:25.23142Z","caller":"traceutil/trace.go:171","msg":"trace[1971442974] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"441.558127ms","start":"2026-03-14T07:02:24.789848Z","end":"2026-03-14T07:02:25.231406Z","steps":["trace[1971442974] 'process raft request' (duration: 441.32129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:25.231533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:24.789608Z","time spent":"441.824092ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:25.231632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:24.78984Z","time spent":"441.624198ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:25.231634Z","caller":"traceutil/trace.go:171","msg":"trace[331871173] linearizableReadLoop","detail":"{readStateIndex:831; appliedIndex:827; }","duration":"127.746583ms","start":"2026-03-14T07:02:25.103858Z","end":"2026-03-14T07:02:25.231604Z","steps":["trace[331871173] 'read index received' (duration: 126.872473ms)","trace[331871173] 'applied index is now lower than readState.Index' (duration: 872.18µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:25.231337Z","caller":"traceutil/trace.go:171","msg":"trace[1123991763] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"441.424013ms","start":"2026-03-14T07:02:24.789878Z","end":"2026-03-14T07:02:25.231302Z","steps":["trace[1123991763] 'process raft request' (duration: 441.33969ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:25.231893Z","caller":"traceutil/trace.go:171","msg":"trace[1345652778] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"441.895924ms","start":"2026-03-14T07:02:24.789973Z","end":"2026-03-14T07:02:25.231869Z","steps":["trace[1345652778] 'process raft request' (duration: 441.28391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:25.231926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:24.789863Z","time spent":"441.952585ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:25.23201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:24.789923Z","time spent":"442.025686ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:25.231906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.03062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T07:02:25.232181Z","caller":"traceutil/trace.go:171","msg":"trace[2066901478] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:800; }","duration":"128.382227ms","start":"2026-03-14T07:02:25.103779Z","end":"2026-03-14T07:02:25.232161Z","steps":["trace[2066901478] 'agreement among raft nodes before linearized reading' (duration: 127.984018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:27.594267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.253804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-14T07:02:27.594356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.233573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-14T07:02:27.594426Z","caller":"traceutil/trace.go:171","msg":"trace[361964922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"357.482959ms","start":"2026-03-14T07:02:27.23692Z","end":"2026-03-14T07:02:27.594403Z","steps":["trace[361964922] 'range keys from in-memory index tree' (duration: 357.054908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:27.594485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:27.236879Z","time spent":"357.592381ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-14T07:02:27.594399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.197742ms","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-14T07:02:27.594467Z","caller":"traceutil/trace.go:171","msg":"trace[1841156100] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"357.405207ms","start":"2026-03-14T07:02:27.237043Z","end":"2026-03-14T07:02:27.594448Z","steps":["trace[1841156100] 'range keys from in-memory index tree' (duration: 357.024928ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:27.59452Z","caller":"traceutil/trace.go:171","msg":"trace[532561066] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:801; }","duration":"357.351205ms","start":"2026-03-14T07:02:27.237161Z","end":"2026-03-14T07:02:27.594512Z","steps":["trace[532561066] 'range keys from in-memory index tree' (duration: 357.058918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:27.594568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:27.237153Z","time spent":"357.403176ms","remote":"127.0.0.1:43566","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-14T07:02:27.594586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:27.237021Z","time spent":"357.531609ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-14T07:02:27.594737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.880845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-14T07:02:27.594778Z","caller":"traceutil/trace.go:171","msg":"trace[1405221691] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"356.953536ms","start":"2026-03-14T07:02:27.237809Z","end":"2026-03-14T07:02:27.594762Z","steps":["trace[1405221691] 'range keys from in-memory index tree' (duration: 356.105368ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:27.594817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:27.237787Z","time spent":"357.024958ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-14T07:02:27.705431Z","caller":"traceutil/trace.go:171","msg":"trace[1474175782] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"106.601175ms","start":"2026-03-14T07:02:27.598793Z","end":"2026-03-14T07:02:27.705394Z","steps":["trace[1474175782] 'process raft request' (duration: 106.460822ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:28.351511Z","caller":"traceutil/trace.go:171","msg":"trace[538717453] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:833; }","duration":"481.945208ms","start":"2026-03-14T07:02:27.869543Z","end":"2026-03-14T07:02:28.351489Z","steps":["trace[538717453] 'read index received' (duration: 481.168561ms)","trace[538717453] 'applied index is now lower than readState.Index' (duration: 775.787µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:28.351771Z","caller":"traceutil/trace.go:171","msg":"trace[2026650242] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"752.662649ms","start":"2026-03-14T07:02:27.599096Z","end":"2026-03-14T07:02:28.351758Z","steps":["trace[2026650242] 'process raft request' (duration: 751.684938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:28.351958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:27.599069Z","time spent":"752.787612ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:28.35196Z","caller":"traceutil/trace.go:171","msg":"trace[1505203493] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"752.117498ms","start":"2026-03-14T07:02:27.599815Z","end":"2026-03-14T07:02:28.351933Z","steps":["trace[1505203493] 'process raft request' (duration: 751.574786ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:28.35213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.555603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:02:28.352157Z","caller":"traceutil/trace.go:171","msg":"trace[1903406071] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"752.122818ms","start":"2026-03-14T07:02:27.600003Z","end":"2026-03-14T07:02:28.352126Z","steps":["trace[1903406071] 'process raft request' (duration: 751.451452ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:28.352211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:27.599806Z","time spent":"752.285232ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:28.352216Z","caller":"traceutil/trace.go:171","msg":"trace[1166128382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"482.724167ms","start":"2026-03-14T07:02:27.869474Z","end":"2026-03-14T07:02:28.352198Z","steps":["trace[1166128382] 'agreement among raft nodes before linearized reading' (duration: 482.349257ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:28.352266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:27.869445Z","time spent":"482.812979ms","remote":"127.0.0.1:43302","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-14T07:02:28.352269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.424314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-14T07:02:28.352316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:27.599939Z","time spent":"752.297292ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:28.352341Z","caller":"traceutil/trace.go:171","msg":"trace[173704494] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:805; }","duration":"303.549407ms","start":"2026-03-14T07:02:28.048774Z","end":"2026-03-14T07:02:28.352323Z","steps":["trace[173704494] 'agreement among raft nodes before linearized reading' (duration: 303.244721ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:28.352389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:28.048749Z","time spent":"303.63243ms","remote":"127.0.0.1:43310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.223\" "} {"level":"info","ts":"2026-03-14T07:02:28.590278Z","caller":"traceutil/trace.go:171","msg":"trace[1159126396] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"159.340281ms","start":"2026-03-14T07:02:28.430911Z","end":"2026-03-14T07:02:28.590252Z","steps":["trace[1159126396] 'process raft request' (duration: 94.740104ms)","trace[1159126396] 'compare' (duration: 64.455194ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:37.697708Z","caller":"traceutil/trace.go:171","msg":"trace[1330204918] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"150.7146ms","start":"2026-03-14T07:02:37.546946Z","end":"2026-03-14T07:02:37.69766Z","steps":["trace[1330204918] 'process raft request' (duration: 109.882288ms)","trace[1330204918] 'compare' (duration: 40.592547ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:37.698157Z","caller":"traceutil/trace.go:171","msg":"trace[564955952] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"146.058557ms","start":"2026-03-14T07:02:37.552072Z","end":"2026-03-14T07:02:37.69813Z","steps":["trace[564955952] 'process raft request' (duration: 145.76667ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:37.698201Z","caller":"traceutil/trace.go:171","msg":"trace[255032803] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"147.571561ms","start":"2026-03-14T07:02:37.5506Z","end":"2026-03-14T07:02:37.698171Z","steps":["trace[255032803] 'process raft request' (duration: 147.126371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:40.273491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.51032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:02:40.273593Z","caller":"traceutil/trace.go:171","msg":"trace[2094736153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:886; }","duration":"405.671653ms","start":"2026-03-14T07:02:39.867901Z","end":"2026-03-14T07:02:40.273573Z","steps":["trace[2094736153] 'range keys from in-memory index tree' (duration: 405.434118ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:40.273617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.819995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-14T07:02:40.273653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:39.867885Z","time spent":"405.751396ms","remote":"127.0.0.1:43302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-14T07:02:40.273699Z","caller":"traceutil/trace.go:171","msg":"trace[1776316881] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:886; }","duration":"495.952487ms","start":"2026-03-14T07:02:39.777726Z","end":"2026-03-14T07:02:40.273679Z","steps":["trace[1776316881] 'range keys from in-memory index tree' (duration: 495.647501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:40.273748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:39.777709Z","time spent":"496.03086ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-14T07:02:40.535369Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224805510704545,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:02:41.036235Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224805510704545,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:02:41.53636Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224805510704545,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:02:42.036505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224805510704545,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:02:42.275265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00042877s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-14T07:02:42.275402Z","caller":"traceutil/trace.go:171","msg":"trace[103089351] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000632193s","start":"2026-03-14T07:02:40.274748Z","end":"2026-03-14T07:02:42.275381Z","steps":["trace[103089351] 'agreement among raft nodes before linearized reading' (duration: 2.00045733s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:42.275461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:40.274731Z","time spent":"2.000719946s","remote":"127.0.0.1:43290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-14T07:02:42.537394Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224805510704545,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:02:42.728818Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.791659912s","expected-duration":"1s"} {"level":"info","ts":"2026-03-14T07:02:42.72919Z","caller":"traceutil/trace.go:171","msg":"trace[1315101832] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"2.792204454s","start":"2026-03-14T07:02:39.93696Z","end":"2026-03-14T07:02:42.729164Z","steps":["trace[1315101832] 'process raft request' (duration: 2.7920462s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:42.729367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:39.936938Z","time spent":"2.792345907s","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:42.923629Z","caller":"traceutil/trace.go:171","msg":"trace[1159273146] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:923; }","duration":"2.888791917s","start":"2026-03-14T07:02:40.034819Z","end":"2026-03-14T07:02:42.923611Z","steps":["trace[1159273146] 'read index received' (duration: 2.69425268s)","trace[1159273146] 'applied index is now lower than readState.Index' (duration: 194.538417ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:02:42.923805Z","caller":"traceutil/trace.go:171","msg":"trace[1864671441] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"2.646189037s","start":"2026-03-14T07:02:40.277608Z","end":"2026-03-14T07:02:42.923797Z","steps":["trace[1864671441] 'process raft request' (duration: 2.603228858s)","trace[1864671441] 'compare' (duration: 42.422147ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:02:42.923878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:40.277583Z","time spent":"2.646237108s","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:42.923981Z","caller":"traceutil/trace.go:171","msg":"trace[1289135087] transaction","detail":"{read_only:false; response_revision:889; number_of_response:1; }","duration":"714.886835ms","start":"2026-03-14T07:02:42.209089Z","end":"2026-03-14T07:02:42.923976Z","steps":["trace[1289135087] 'process raft request' (duration: 714.401465ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:42.924006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:42.209066Z","time spent":"714.925106ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:42.92408Z","caller":"traceutil/trace.go:171","msg":"trace[1318119525] transaction","detail":"{read_only:false; response_revision:890; number_of_response:1; }","duration":"643.905267ms","start":"2026-03-14T07:02:42.280167Z","end":"2026-03-14T07:02:42.924073Z","steps":["trace[1318119525] 'process raft request' (duration: 643.407117ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:42.924142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:42.280127Z","time spent":"643.965728ms","remote":"127.0.0.1:43354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:02:42.924253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.592983311s","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-14T07:02:42.924351Z","caller":"traceutil/trace.go:171","msg":"trace[576541470] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:890; }","duration":"2.593139364s","start":"2026-03-14T07:02:40.331188Z","end":"2026-03-14T07:02:42.924327Z","steps":["trace[576541470] 'agreement among raft nodes before linearized reading' (duration: 2.592912499s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:42.924352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.706616827s","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-14T07:02:42.924406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:40.331175Z","time spent":"2.593219416s","remote":"127.0.0.1:43462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:02:42.924461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.443357066s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T07:02:42.924457Z","caller":"traceutil/trace.go:171","msg":"trace[1393581171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:890; }","duration":"1.706794561s","start":"2026-03-14T07:02:41.217638Z","end":"2026-03-14T07:02:42.924433Z","steps":["trace[1393581171] 'agreement among raft nodes before linearized reading' (duration: 1.706499465s)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:42.92448Z","caller":"traceutil/trace.go:171","msg":"trace[925184402] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:890; }","duration":"2.443397206s","start":"2026-03-14T07:02:40.481076Z","end":"2026-03-14T07:02:42.924474Z","steps":["trace[925184402] 'agreement among raft nodes before linearized reading' (duration: 2.443340825s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:42.924498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:40.481059Z","time spent":"2.443435378s","remote":"127.0.0.1:43630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:02:42.924518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:41.217603Z","time spent":"1.706905123s","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-14T07:02:42.92458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.514807314s","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-14T07:02:42.924642Z","caller":"traceutil/trace.go:171","msg":"trace[1145696835] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:890; }","duration":"2.514912996s","start":"2026-03-14T07:02:40.409715Z","end":"2026-03-14T07:02:42.924628Z","steps":["trace[1145696835] 'agreement among raft nodes before linearized reading' (duration: 2.514733682s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:42.924657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.520278674s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-14T07:02:42.924674Z","caller":"traceutil/trace.go:171","msg":"trace[1368811649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:890; }","duration":"2.520336805s","start":"2026-03-14T07:02:40.404333Z","end":"2026-03-14T07:02:42.92467Z","steps":["trace[1368811649] 'agreement among raft nodes before linearized reading' (duration: 2.520289024s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:42.924683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:40.409697Z","time spent":"2.514981218s","remote":"127.0.0.1:43566","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-14T07:02:42.924702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:40.404309Z","time spent":"2.520388008s","remote":"127.0.0.1:43566","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-14T07:02:42.924399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.511133092s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-14T07:02:42.924765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356344309s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:02:42.92478Z","caller":"traceutil/trace.go:171","msg":"trace[1403362456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:890; }","duration":"2.511530123s","start":"2026-03-14T07:02:40.413242Z","end":"2026-03-14T07:02:42.924772Z","steps":["trace[1403362456] 'agreement among raft nodes before linearized reading' (duration: 2.511065452s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:42.924788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.889971014s","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-14T07:02:42.924801Z","caller":"traceutil/trace.go:171","msg":"trace[1099624024] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:890; }","duration":"2.890017745s","start":"2026-03-14T07:02:40.034778Z","end":"2026-03-14T07:02:42.924796Z","steps":["trace[1099624024] 'agreement among raft nodes before linearized reading' (duration: 2.889997085s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:42.924801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:40.41323Z","time spent":"2.511562653s","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-14T07:02:42.924813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:40.034757Z","time spent":"2.890053005s","remote":"127.0.0.1:43664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":29,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"info","ts":"2026-03-14T07:02:42.924837Z","caller":"traceutil/trace.go:171","msg":"trace[222943412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"1.35638621s","start":"2026-03-14T07:02:41.568386Z","end":"2026-03-14T07:02:42.924773Z","steps":["trace[222943412] 'agreement among raft nodes before linearized reading' (duration: 1.356367339s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:42.92485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:41.568365Z","time spent":"1.356482422s","remote":"127.0.0.1:43302","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-14T07:02:42.924905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.815049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:02:42.924949Z","caller":"traceutil/trace.go:171","msg":"trace[699970076] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:890; }","duration":"640.893291ms","start":"2026-03-14T07:02:42.284041Z","end":"2026-03-14T07:02:42.924934Z","steps":["trace[699970076] 'agreement among raft nodes before linearized reading' (duration: 640.83057ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:42.924978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:42.284027Z","time spent":"640.945232ms","remote":"127.0.0.1:43290","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-14T07:02:43.436329Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224805510704551,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:02:43.937183Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521224805510704551,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-14T07:02:44.002681Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.070138134s","expected-duration":"1s"} {"level":"info","ts":"2026-03-14T07:02:44.013216Z","caller":"traceutil/trace.go:171","msg":"trace[349003021] transaction","detail":"{read_only:false; response_revision:891; number_of_response:1; }","duration":"1.080757649s","start":"2026-03-14T07:02:42.932434Z","end":"2026-03-14T07:02:44.013192Z","steps":["trace[349003021] 'process raft request' (duration: 1.070594735s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:44.013363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:42.932406Z","time spent":"1.080882952s","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:44.03215Z","caller":"traceutil/trace.go:171","msg":"trace[49226808] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"1.099411441s","start":"2026-03-14T07:02:42.932679Z","end":"2026-03-14T07:02:44.03209Z","steps":["trace[49226808] 'process raft request' (duration: 1.099324699s)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:02:44.032177Z","caller":"traceutil/trace.go:171","msg":"trace[2100987157] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"1.099495604s","start":"2026-03-14T07:02:42.932655Z","end":"2026-03-14T07:02:44.03215Z","steps":["trace[2100987157] 'process raft request' (duration: 1.099173467s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:44.032297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:42.932623Z","time spent":"1.099609896s","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:02:44.03235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:42.932639Z","time spent":"1.099593385s","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:44.032363Z","caller":"traceutil/trace.go:171","msg":"trace[746119705] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"1.099609956s","start":"2026-03-14T07:02:42.932727Z","end":"2026-03-14T07:02:44.032337Z","steps":["trace[746119705] 'process raft request' (duration: 1.099320649s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:44.032522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:42.932722Z","time spent":"1.099712148s","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:02:44.035935Z","caller":"traceutil/trace.go:171","msg":"trace[693670328] linearizableReadLoop","detail":"{readStateIndex:932; appliedIndex:927; }","duration":"1.100560647s","start":"2026-03-14T07:02:42.935352Z","end":"2026-03-14T07:02:44.035912Z","steps":["trace[693670328] 'read index received' (duration: 1.06769683s)","trace[693670328] 'applied index is now lower than readState.Index' (duration: 32.862237ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:02:44.036108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10071924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:02:44.03616Z","caller":"traceutil/trace.go:171","msg":"trace[772041972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:894; }","duration":"1.100838853s","start":"2026-03-14T07:02:42.93531Z","end":"2026-03-14T07:02:44.036149Z","steps":["trace[772041972] 'agreement among raft nodes before linearized reading' (duration: 1.100733141s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:44.036192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:42.935296Z","time spent":"1.100888935s","remote":"127.0.0.1:43302","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-14T07:02:44.036289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.384226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:02:44.036426Z","caller":"traceutil/trace.go:171","msg":"trace[2005228256] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:894; }","duration":"400.55957ms","start":"2026-03-14T07:02:43.635842Z","end":"2026-03-14T07:02:44.036401Z","steps":["trace[2005228256] 'agreement among raft nodes before linearized reading' (duration: 400.282044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:44.036946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:43.635803Z","time spent":"401.124554ms","remote":"127.0.0.1:43610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:02:44.036583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.886995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T07:02:44.037249Z","caller":"traceutil/trace.go:171","msg":"trace[120374471] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:894; }","duration":"314.595771ms","start":"2026-03-14T07:02:43.722639Z","end":"2026-03-14T07:02:44.037235Z","steps":["trace[120374471] 'agreement among raft nodes before linearized reading' (duration: 313.849704ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:44.037325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:43.722615Z","time spent":"314.692124ms","remote":"127.0.0.1:43354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":106,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:02:44.036601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.422269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:02:44.037428Z","caller":"traceutil/trace.go:171","msg":"trace[977994290] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:894; }","duration":"364.314409ms","start":"2026-03-14T07:02:43.673102Z","end":"2026-03-14T07:02:44.037416Z","steps":["trace[977994290] 'agreement among raft nodes before linearized reading' (duration: 363.432409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:02:44.037456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:02:43.673052Z","time spent":"364.396941ms","remote":"127.0.0.1:43702","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:03:00.241747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.469653ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:03:00.241877Z","caller":"traceutil/trace.go:171","msg":"trace[954209003] linearizableReadLoop","detail":"{readStateIndex:978; appliedIndex:977; }","duration":"196.037089ms","start":"2026-03-14T07:03:00.04582Z","end":"2026-03-14T07:03:00.241857Z","steps":["trace[954209003] 'read index received' (duration: 22.400132ms)","trace[954209003] 'applied index is now lower than readState.Index' (duration: 173.635727ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:03:00.241938Z","caller":"traceutil/trace.go:171","msg":"trace[1177362394] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"207.490591ms","start":"2026-03-14T07:03:00.034399Z","end":"2026-03-14T07:03:00.241889Z","steps":["trace[1177362394] 'process raft request' (duration: 33.803663ms)","trace[1177362394] 'compare' (duration: 173.276409ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:03:00.242335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.509709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5184"} {"level":"info","ts":"2026-03-14T07:03:00.242395Z","caller":"traceutil/trace.go:171","msg":"trace[1602771974] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:937; }","duration":"196.608912ms","start":"2026-03-14T07:03:00.045771Z","end":"2026-03-14T07:03:00.24238Z","steps":["trace[1602771974] 'agreement among raft nodes before linearized reading' (duration: 196.202813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:00.24247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.074275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-14T07:03:00.242478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.356099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-14T07:03:00.242529Z","caller":"traceutil/trace.go:171","msg":"trace[1864258309] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:937; }","duration":"138.198608ms","start":"2026-03-14T07:03:00.104317Z","end":"2026-03-14T07:03:00.242515Z","steps":["trace[1864258309] 'agreement among raft nodes before linearized reading' (duration: 138.011004ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:00.242545Z","caller":"traceutil/trace.go:171","msg":"trace[105601719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:937; }","duration":"132.465522ms","start":"2026-03-14T07:03:00.110062Z","end":"2026-03-14T07:03:00.242527Z","steps":["trace[105601719] 'agreement among raft nodes before linearized reading' (duration: 132.283688ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:00.242577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.00973ms","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-14T07:03:00.242623Z","caller":"traceutil/trace.go:171","msg":"trace[471008706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:937; }","duration":"126.091232ms","start":"2026-03-14T07:03:00.116517Z","end":"2026-03-14T07:03:00.242608Z","steps":["trace[471008706] 'agreement among raft nodes before linearized reading' (duration: 125.985859ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:00.242477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.450232ms","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-14T07:03:00.243387Z","caller":"traceutil/trace.go:171","msg":"trace[682884117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:937; }","duration":"129.379964ms","start":"2026-03-14T07:03:00.113977Z","end":"2026-03-14T07:03:00.243357Z","steps":["trace[682884117] 'agreement among raft nodes before linearized reading' (duration: 128.370281ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:00.369417Z","caller":"traceutil/trace.go:171","msg":"trace[2059258584] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"122.444911ms","start":"2026-03-14T07:03:00.246947Z","end":"2026-03-14T07:03:00.369392Z","steps":["trace[2059258584] 'process raft request' (duration: 122.126344ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:00.369931Z","caller":"traceutil/trace.go:171","msg":"trace[1828077139] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"122.599106ms","start":"2026-03-14T07:03:00.247311Z","end":"2026-03-14T07:03:00.36991Z","steps":["trace[1828077139] 'process raft request' (duration: 122.306869ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:00.369716Z","caller":"traceutil/trace.go:171","msg":"trace[1880771204] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"122.391831ms","start":"2026-03-14T07:03:00.247291Z","end":"2026-03-14T07:03:00.369683Z","steps":["trace[1880771204] 'process raft request' (duration: 122.025043ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:00.370211Z","caller":"traceutil/trace.go:171","msg":"trace[332938252] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"122.285379ms","start":"2026-03-14T07:03:00.247907Z","end":"2026-03-14T07:03:00.370193Z","steps":["trace[332938252] 'process raft request' (duration: 121.87923ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:03.842878Z","caller":"traceutil/trace.go:171","msg":"trace[951740881] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"100.009989ms","start":"2026-03-14T07:03:03.742846Z","end":"2026-03-14T07:03:03.842856Z","steps":["trace[951740881] 'process raft request' (duration: 99.866246ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:04.607057Z","caller":"traceutil/trace.go:171","msg":"trace[684273071] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"203.600425ms","start":"2026-03-14T07:03:04.403433Z","end":"2026-03-14T07:03:04.607033Z","steps":["trace[684273071] 'process raft request' (duration: 203.442742ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:04.643647Z","caller":"traceutil/trace.go:171","msg":"trace[1803994895] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"237.563391ms","start":"2026-03-14T07:03:04.406063Z","end":"2026-03-14T07:03:04.643626Z","steps":["trace[1803994895] 'process raft request' (duration: 237.52128ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:04.643901Z","caller":"traceutil/trace.go:171","msg":"trace[584385951] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"238.430232ms","start":"2026-03-14T07:03:04.405425Z","end":"2026-03-14T07:03:04.643855Z","steps":["trace[584385951] 'process raft request' (duration: 238.120165ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:04.644171Z","caller":"traceutil/trace.go:171","msg":"trace[307595879] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"238.825651ms","start":"2026-03-14T07:03:04.405329Z","end":"2026-03-14T07:03:04.644155Z","steps":["trace[307595879] 'process raft request' (duration: 209.612188ms)","trace[307595879] 'compare' (duration: 28.476006ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:03:05.491615Z","caller":"traceutil/trace.go:171","msg":"trace[720157293] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"265.982237ms","start":"2026-03-14T07:03:05.225612Z","end":"2026-03-14T07:03:05.491594Z","steps":["trace[720157293] 'process raft request' (duration: 265.867724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:05.98163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.559644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:03:05.981735Z","caller":"traceutil/trace.go:171","msg":"trace[642092870] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"112.729797ms","start":"2026-03-14T07:03:05.86898Z","end":"2026-03-14T07:03:05.98171Z","steps":["trace[642092870] 'range keys from in-memory index tree' (duration: 112.464572ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:06.774804Z","caller":"traceutil/trace.go:171","msg":"trace[1578238304] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"122.013491ms","start":"2026-03-14T07:03:06.652764Z","end":"2026-03-14T07:03:06.774777Z","steps":["trace[1578238304] 'process raft request' (duration: 42.443093ms)","trace[1578238304] 'compare' (duration: 79.317673ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:03:06.775058Z","caller":"traceutil/trace.go:171","msg":"trace[106946720] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"121.830907ms","start":"2026-03-14T07:03:06.653176Z","end":"2026-03-14T07:03:06.775007Z","steps":["trace[106946720] 'process raft request' (duration: 121.556481ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:07.498869Z","caller":"traceutil/trace.go:171","msg":"trace[1858770534] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"423.9593ms","start":"2026-03-14T07:03:07.074884Z","end":"2026-03-14T07:03:07.498844Z","steps":["trace[1858770534] 'process raft request' (duration: 423.816947ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:07.49901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:07.074863Z","time spent":"424.079333ms","remote":"127.0.0.1:43354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":787,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:03:07.973298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.341482ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:03:07.973534Z","caller":"traceutil/trace.go:171","msg":"trace[1666909733] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"897.649422ms","start":"2026-03-14T07:03:07.075862Z","end":"2026-03-14T07:03:07.973512Z","steps":["trace[1666909733] 'process raft request' (duration: 577.022994ms)","trace[1666909733] 'compare' (duration: 320.115657ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:03:07.973618Z","caller":"traceutil/trace.go:171","msg":"trace[1317558692] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"470.994873ms","start":"2026-03-14T07:03:07.502561Z","end":"2026-03-14T07:03:07.973556Z","steps":["trace[1317558692] 'process raft request' (duration: 470.928532ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:07.973618Z","caller":"traceutil/trace.go:171","msg":"trace[583525021] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1019; }","duration":"477.684019ms","start":"2026-03-14T07:03:07.495886Z","end":"2026-03-14T07:03:07.97357Z","steps":["trace[583525021] 'read index received' (duration: 2.802171ms)","trace[583525021] 'applied index is now lower than readState.Index' (duration: 474.876108ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:03:07.973719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:07.502545Z","time spent":"471.135817ms","remote":"127.0.0.1:43354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":835,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:03:07.973969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.001867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-03-14T07:03:07.97401Z","caller":"traceutil/trace.go:171","msg":"trace[887544182] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:980; }","duration":"478.167981ms","start":"2026-03-14T07:03:07.495828Z","end":"2026-03-14T07:03:07.973996Z","steps":["trace[887544182] 'agreement among raft nodes before linearized reading' (duration: 477.885164ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:07.974192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:07.495802Z","time spent":"478.379215ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-14T07:03:07.97366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:07.075852Z","time spent":"897.720413ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:03:07.97511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.56833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:03:07.97519Z","caller":"traceutil/trace.go:171","msg":"trace[1843842491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:980; }","duration":"106.309007ms","start":"2026-03-14T07:03:07.868861Z","end":"2026-03-14T07:03:07.97517Z","steps":["trace[1843842491] 'agreement among raft nodes before linearized reading' (duration: 104.972037ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:08.538228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.336225ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:03:08.538618Z","caller":"traceutil/trace.go:171","msg":"trace[1847265074] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1022; }","duration":"486.092846ms","start":"2026-03-14T07:03:08.052499Z","end":"2026-03-14T07:03:08.538592Z","steps":["trace[1847265074] 'read index received' (duration: 37.357352ms)","trace[1847265074] 'applied index is now lower than readState.Index' (duration: 448.734084ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:03:08.538671Z","caller":"traceutil/trace.go:171","msg":"trace[1255412730] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"558.429335ms","start":"2026-03-14T07:03:07.980222Z","end":"2026-03-14T07:03:08.538651Z","steps":["trace[1255412730] 'process raft request' (duration: 109.547368ms)","trace[1255412730] 'compare' (duration: 448.183052ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:03:08.538668Z","caller":"traceutil/trace.go:171","msg":"trace[989654396] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"557.690059ms","start":"2026-03-14T07:03:07.980947Z","end":"2026-03-14T07:03:08.538637Z","steps":["trace[989654396] 'process raft request' (duration: 557.506235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:08.538802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-14T07:03:08.53884Z","caller":"traceutil/trace.go:171","msg":"trace[1616650900] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:983; }","duration":"486.397832ms","start":"2026-03-14T07:03:08.052431Z","end":"2026-03-14T07:03:08.538829Z","steps":["trace[1616650900] 'agreement among raft nodes before linearized reading' (duration: 486.231839ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:08.538878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:08.052407Z","time spent":"486.463764ms","remote":"127.0.0.1:43310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.223\" "} {"level":"info","ts":"2026-03-14T07:03:08.538771Z","caller":"traceutil/trace.go:171","msg":"trace[1576903845] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"553.454317ms","start":"2026-03-14T07:03:07.985255Z","end":"2026-03-14T07:03:08.53871Z","steps":["trace[1576903845] 'process raft request' (duration: 553.284043ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:08.539143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:07.985247Z","time spent":"553.756893ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:08.538738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:07.980202Z","time spent":"558.503466ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:08.538923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:07.980935Z","time spent":"557.882623ms","remote":"127.0.0.1:43354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:03:08.798209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.791588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:03:08.79836Z","caller":"traceutil/trace.go:171","msg":"trace[1920041108] linearizableReadLoop","detail":"{readStateIndex:1027; appliedIndex:1026; }","duration":"145.687063ms","start":"2026-03-14T07:03:08.65264Z","end":"2026-03-14T07:03:08.798327Z","steps":["trace[1920041108] 'read index received' (duration: 42.663138ms)","trace[1920041108] 'applied index is now lower than readState.Index' (duration: 103.022175ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:03:08.798619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.969209ms","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-14T07:03:08.798684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.599486ms","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-14T07:03:08.798717Z","caller":"traceutil/trace.go:171","msg":"trace[490329789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:984; }","duration":"123.645328ms","start":"2026-03-14T07:03:08.675062Z","end":"2026-03-14T07:03:08.798707Z","steps":["trace[490329789] 'agreement among raft nodes before linearized reading' (duration: 123.399823ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:08.798715Z","caller":"traceutil/trace.go:171","msg":"trace[574558333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:984; }","duration":"146.121922ms","start":"2026-03-14T07:03:08.65258Z","end":"2026-03-14T07:03:08.798701Z","steps":["trace[574558333] 'agreement among raft nodes before linearized reading' (duration: 145.844566ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:08.798919Z","caller":"traceutil/trace.go:171","msg":"trace[900099680] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"234.897654ms","start":"2026-03-14T07:03:08.563474Z","end":"2026-03-14T07:03:08.798372Z","steps":["trace[900099680] 'process raft request' (duration: 131.822948ms)","trace[900099680] 'compare' (duration: 101.92892ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:03:13.086879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.923383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:03:13.086966Z","caller":"traceutil/trace.go:171","msg":"trace[1082635520] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:996; }","duration":"219.049765ms","start":"2026-03-14T07:03:12.867897Z","end":"2026-03-14T07:03:13.086947Z","steps":["trace[1082635520] 'range keys from in-memory index tree' (duration: 218.857351ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:13.087029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.999507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-14T07:03:13.087085Z","caller":"traceutil/trace.go:171","msg":"trace[1025859304] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:996; }","duration":"231.105511ms","start":"2026-03-14T07:03:12.855966Z","end":"2026-03-14T07:03:13.087072Z","steps":["trace[1025859304] 'range keys from in-memory index tree' (duration: 230.588479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:13.087134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.670742ms","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-14T07:03:13.087121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.031488ms","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-14T07:03:13.087187Z","caller":"traceutil/trace.go:171","msg":"trace[1599203445] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:996; }","duration":"230.749803ms","start":"2026-03-14T07:03:12.856422Z","end":"2026-03-14T07:03:13.087172Z","steps":["trace[1599203445] 'range keys from in-memory index tree' (duration: 230.412495ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:13.087206Z","caller":"traceutil/trace.go:171","msg":"trace[1816291638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:996; }","duration":"230.255172ms","start":"2026-03-14T07:03:12.856932Z","end":"2026-03-14T07:03:13.087187Z","steps":["trace[1816291638] 'range keys from in-memory index tree' (duration: 229.74881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:13.087314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.676543ms","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-14T07:03:13.087358Z","caller":"traceutil/trace.go:171","msg":"trace[789731394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:996; }","duration":"251.760235ms","start":"2026-03-14T07:03:12.835589Z","end":"2026-03-14T07:03:13.087349Z","steps":["trace[789731394] 'range keys from in-memory index tree' (duration: 251.12918ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:13.087025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.247731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:03:13.087919Z","caller":"traceutil/trace.go:171","msg":"trace[305884112] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:996; }","duration":"428.144572ms","start":"2026-03-14T07:03:12.659729Z","end":"2026-03-14T07:03:13.087873Z","steps":["trace[305884112] 'range keys from in-memory index tree' (duration: 427.1679ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:13.087965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:12.659709Z","time spent":"428.240034ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-03-14T07:03:13.674315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.389908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:03:13.674705Z","caller":"traceutil/trace.go:171","msg":"trace[1983697975] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1043; }","duration":"453.921268ms","start":"2026-03-14T07:03:13.220768Z","end":"2026-03-14T07:03:13.67469Z","steps":["trace[1983697975] 'read index received' (duration: 423.519899ms)","trace[1983697975] 'applied index is now lower than readState.Index' (duration: 30.400699ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:03:13.6748Z","caller":"traceutil/trace.go:171","msg":"trace[978566441] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"457.925726ms","start":"2026-03-14T07:03:13.216861Z","end":"2026-03-14T07:03:13.674787Z","steps":["trace[978566441] 'process raft request' (duration: 457.793463ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:13.674705Z","caller":"traceutil/trace.go:171","msg":"trace[666394390] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"530.674664ms","start":"2026-03-14T07:03:13.144003Z","end":"2026-03-14T07:03:13.674677Z","steps":["trace[666394390] 'process raft request' (duration: 55.848317ms)","trace[666394390] 'compare' (duration: 474.149573ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:03:13.674907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:13.143941Z","time spent":"530.93731ms","remote":"127.0.0.1:43354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:03:13.674936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:13.216837Z","time spent":"458.011737ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:03:13.674734Z","caller":"traceutil/trace.go:171","msg":"trace[1286872323] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"492.151757ms","start":"2026-03-14T07:03:13.182558Z","end":"2026-03-14T07:03:13.67471Z","steps":["trace[1286872323] 'process raft request' (duration: 492.035045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:13.675283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:13.182536Z","time spent":"492.637648ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-14T07:03:13.859212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.396993ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-03-14T07:03:13.859506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.693529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-14T07:03:13.859585Z","caller":"traceutil/trace.go:171","msg":"trace[1659665564] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"184.743841ms","start":"2026-03-14T07:03:13.674786Z","end":"2026-03-14T07:03:13.85953Z","steps":["trace[1659665564] 'read index received' (duration: 48.741µs)","trace[1659665564] 'applied index is now lower than readState.Index' (duration: 184.69389ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:03:13.859664Z","caller":"traceutil/trace.go:171","msg":"trace[1007172799] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"178.911443ms","start":"2026-03-14T07:03:13.680721Z","end":"2026-03-14T07:03:13.859633Z","steps":["trace[1007172799] 'process raft request' (duration: 178.701288ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:13.859653Z","caller":"traceutil/trace.go:171","msg":"trace[639341731] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1003; }","duration":"638.924685ms","start":"2026-03-14T07:03:13.220693Z","end":"2026-03-14T07:03:13.859618Z","steps":["trace[639341731] 'agreement among raft nodes before linearized reading' (duration: 454.118782ms)","trace[639341731] 'count revisions from in-memory index tree' (duration: 184.541756ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:03:13.859845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:13.220681Z","time spent":"639.094688ms","remote":"127.0.0.1:43510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":29,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:03:13.859963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.20739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-03-14T07:03:13.860002Z","caller":"traceutil/trace.go:171","msg":"trace[1009845233] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1004; }","duration":"316.288772ms","start":"2026-03-14T07:03:13.543703Z","end":"2026-03-14T07:03:13.859992Z","steps":["trace[1009845233] 'agreement among raft nodes before linearized reading' (duration: 316.009086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:13.860049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:13.543678Z","time spent":"316.362044ms","remote":"127.0.0.1:43566","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-14T07:03:14.218296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.525017ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:03:14.218723Z","caller":"traceutil/trace.go:171","msg":"trace[888821629] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"354.948292ms","start":"2026-03-14T07:03:13.86375Z","end":"2026-03-14T07:03:14.218698Z","steps":["trace[888821629] 'process raft request' (duration: 354.8726ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:14.218867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:13.863734Z","time spent":"355.061164ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:03:14.219301Z","caller":"traceutil/trace.go:171","msg":"trace[1951292408] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"535.937551ms","start":"2026-03-14T07:03:13.683326Z","end":"2026-03-14T07:03:14.219263Z","steps":["trace[1951292408] 'process raft request' (duration: 304.294589ms)","trace[1951292408] 'compare' (duration: 230.255071ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:03:14.219374Z","caller":"traceutil/trace.go:171","msg":"trace[1557408900] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"433.699294ms","start":"2026-03-14T07:03:13.785643Z","end":"2026-03-14T07:03:14.219343Z","steps":["trace[1557408900] 'process raft request' (duration: 432.840414ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:14.219451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:13.785614Z","time spent":"433.786165ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:03:14.219461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:13.683311Z","time spent":"536.071565ms","remote":"127.0.0.1:43354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":869,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-14T07:03:14.219803Z","caller":"traceutil/trace.go:171","msg":"trace[362380155] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1048; }","duration":"360.088406ms","start":"2026-03-14T07:03:13.859702Z","end":"2026-03-14T07:03:14.21979Z","steps":["trace[362380155] 'read index received' (duration: 127.925353ms)","trace[362380155] 'applied index is now lower than readState.Index' (duration: 232.161403ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:03:14.219979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.828334ms","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-14T07:03:14.220027Z","caller":"traceutil/trace.go:171","msg":"trace[945657923] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1007; }","duration":"538.905165ms","start":"2026-03-14T07:03:13.681112Z","end":"2026-03-14T07:03:14.220017Z","steps":["trace[945657923] 'agreement among raft nodes before linearized reading' (duration: 538.712581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:03:14.220054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:03:13.681093Z","time spent":"538.954047ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-14T07:03:15.417251Z","caller":"traceutil/trace.go:171","msg":"trace[946021745] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"229.561706ms","start":"2026-03-14T07:03:15.187668Z","end":"2026-03-14T07:03:15.41723Z","steps":["trace[946021745] 'process raft request' (duration: 229.429993ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:15.418644Z","caller":"traceutil/trace.go:171","msg":"trace[1719564682] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"230.269731ms","start":"2026-03-14T07:03:15.188341Z","end":"2026-03-14T07:03:15.41861Z","steps":["trace[1719564682] 'process raft request' (duration: 230.22144ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:15.418662Z","caller":"traceutil/trace.go:171","msg":"trace[902985948] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"230.683311ms","start":"2026-03-14T07:03:15.187939Z","end":"2026-03-14T07:03:15.418622Z","steps":["trace[902985948] 'process raft request' (duration: 230.524478ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:34.409174Z","caller":"traceutil/trace.go:171","msg":"trace[69180256] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"125.07118ms","start":"2026-03-14T07:03:34.284081Z","end":"2026-03-14T07:03:34.409153Z","steps":["trace[69180256] 'process raft request' (duration: 124.942307ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:34.416145Z","caller":"traceutil/trace.go:171","msg":"trace[2144401183] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"131.175704ms","start":"2026-03-14T07:03:34.28495Z","end":"2026-03-14T07:03:34.416125Z","steps":["trace[2144401183] 'process raft request' (duration: 131.065212ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:59.570814Z","caller":"traceutil/trace.go:171","msg":"trace[1244546766] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"113.928285ms","start":"2026-03-14T07:03:59.456821Z","end":"2026-03-14T07:03:59.570749Z","steps":["trace[1244546766] 'process raft request' (duration: 113.206719ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:03:59.576655Z","caller":"traceutil/trace.go:171","msg":"trace[1944105167] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"116.969631ms","start":"2026-03-14T07:03:59.459669Z","end":"2026-03-14T07:03:59.576638Z","steps":["trace[1944105167] 'process raft request' (duration: 116.884509ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:04:44.77479Z","caller":"traceutil/trace.go:171","msg":"trace[907989281] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"110.010847ms","start":"2026-03-14T07:04:44.664747Z","end":"2026-03-14T07:04:44.774758Z","steps":["trace[907989281] 'process raft request' (duration: 88.871363ms)","trace[907989281] 'compare' (duration: 21.023882ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:04:45.865885Z","caller":"traceutil/trace.go:171","msg":"trace[326408555] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"103.587127ms","start":"2026-03-14T07:04:45.762278Z","end":"2026-03-14T07:04:45.865865Z","steps":["trace[326408555] 'process raft request' (duration: 55.020919ms)","trace[326408555] 'compare' (duration: 48.495117ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:04:47.401922Z","caller":"traceutil/trace.go:171","msg":"trace[2139442279] transaction","detail":"{read_only:false; response_revision:1409; number_of_response:1; }","duration":"143.337761ms","start":"2026-03-14T07:04:47.258541Z","end":"2026-03-14T07:04:47.401879Z","steps":["trace[2139442279] 'process raft request' (duration: 143.118796ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:04:48.194842Z","caller":"traceutil/trace.go:171","msg":"trace[158853255] linearizableReadLoop","detail":"{readStateIndex:1476; appliedIndex:1474; }","duration":"125.526799ms","start":"2026-03-14T07:04:48.069293Z","end":"2026-03-14T07:04:48.19482Z","steps":["trace[158853255] 'read index received' (duration: 26.502563ms)","trace[158853255] 'applied index is now lower than readState.Index' (duration: 99.023256ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:04:48.19505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.733244ms","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-14T07:04:48.195089Z","caller":"traceutil/trace.go:171","msg":"trace[996808479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1413; }","duration":"125.804036ms","start":"2026-03-14T07:04:48.069275Z","end":"2026-03-14T07:04:48.195079Z","steps":["trace[996808479] 'agreement among raft nodes before linearized reading' (duration: 125.641032ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:04:48.195335Z","caller":"traceutil/trace.go:171","msg":"trace[1501325041] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"132.569603ms","start":"2026-03-14T07:04:48.062731Z","end":"2026-03-14T07:04:48.195301Z","steps":["trace[1501325041] 'process raft request' (duration: 131.96185ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:05:59.60385Z","caller":"traceutil/trace.go:171","msg":"trace[1049019002] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"111.21772ms","start":"2026-03-14T07:05:59.492614Z","end":"2026-03-14T07:05:59.603831Z","steps":["trace[1049019002] 'process raft request' (duration: 111.058326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:04.431621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.205869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-03-14T07:06:04.431702Z","caller":"traceutil/trace.go:171","msg":"trace[477989750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1726; }","duration":"106.327721ms","start":"2026-03-14T07:06:04.325355Z","end":"2026-03-14T07:06:04.431683Z","steps":["trace[477989750] 'range keys from in-memory index tree' (duration: 106.042205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:04.431909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.911951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:04.43195Z","caller":"traceutil/trace.go:171","msg":"trace[1977889055] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:0; response_revision:1726; }","duration":"177.982901ms","start":"2026-03-14T07:06:04.253954Z","end":"2026-03-14T07:06:04.431936Z","steps":["trace[1977889055] 'range keys from in-memory index tree' (duration: 177.839448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:04.432209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.678153ms","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-14T07:06:04.432512Z","caller":"traceutil/trace.go:171","msg":"trace[221274886] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1726; }","duration":"158.900498ms","start":"2026-03-14T07:06:04.273506Z","end":"2026-03-14T07:06:04.432407Z","steps":["trace[221274886] 'range keys from in-memory index tree' (duration: 158.670833ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:04.614832Z","caller":"traceutil/trace.go:171","msg":"trace[1238546724] transaction","detail":"{read_only:false; response_revision:1728; number_of_response:1; }","duration":"179.867834ms","start":"2026-03-14T07:06:04.434932Z","end":"2026-03-14T07:06:04.614799Z","steps":["trace[1238546724] 'process raft request' (duration: 125.686651ms)","trace[1238546724] 'compare' (duration: 53.953528ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:04.615095Z","caller":"traceutil/trace.go:171","msg":"trace[319993213] transaction","detail":"{read_only:false; response_revision:1729; number_of_response:1; }","duration":"176.352767ms","start":"2026-03-14T07:06:04.438727Z","end":"2026-03-14T07:06:04.61508Z","steps":["trace[319993213] 'process raft request' (duration: 175.949917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:04.869952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.04988ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:06:04.870166Z","caller":"traceutil/trace.go:171","msg":"trace[299601852] linearizableReadLoop","detail":"{readStateIndex:1811; appliedIndex:1808; }","duration":"249.790578ms","start":"2026-03-14T07:06:04.620358Z","end":"2026-03-14T07:06:04.870148Z","steps":["trace[299601852] 'read index received' (duration: 18.46745ms)","trace[299601852] 'applied index is now lower than readState.Index' (duration: 231.322128ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:04.870168Z","caller":"traceutil/trace.go:171","msg":"trace[1604175625] transaction","detail":"{read_only:false; response_revision:1731; number_of_response:1; }","duration":"250.84056ms","start":"2026-03-14T07:06:04.619297Z","end":"2026-03-14T07:06:04.870137Z","steps":["trace[1604175625] 'process raft request' (duration: 128.490383ms)","trace[1604175625] 'compare' (duration: 121.904827ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:04.870488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.124265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1948"} {"level":"info","ts":"2026-03-14T07:06:04.87052Z","caller":"traceutil/trace.go:171","msg":"trace[549831844] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1732; }","duration":"250.171296ms","start":"2026-03-14T07:06:04.620339Z","end":"2026-03-14T07:06:04.87051Z","steps":["trace[549831844] 'agreement among raft nodes before linearized reading' (duration: 250.109964ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:04.870499Z","caller":"traceutil/trace.go:171","msg":"trace[6778162] transaction","detail":"{read_only:false; response_revision:1732; number_of_response:1; }","duration":"250.596246ms","start":"2026-03-14T07:06:04.619867Z","end":"2026-03-14T07:06:04.870463Z","steps":["trace[6778162] 'process raft request' (duration: 250.195737ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:04.870783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.504946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-14T07:06:04.871486Z","caller":"traceutil/trace.go:171","msg":"trace[1884012555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1732; }","duration":"233.70653ms","start":"2026-03-14T07:06:04.6372Z","end":"2026-03-14T07:06:04.870907Z","steps":["trace[1884012555] 'agreement among raft nodes before linearized reading' (duration: 233.262421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:05.123257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.851846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:06:05.123437Z","caller":"traceutil/trace.go:171","msg":"trace[1464659026] transaction","detail":"{read_only:false; response_revision:1734; number_of_response:1; }","duration":"192.401633ms","start":"2026-03-14T07:06:04.931003Z","end":"2026-03-14T07:06:05.123404Z","steps":["trace[1464659026] 'process raft request' (duration: 43.275322ms)","trace[1464659026] 'compare' (duration: 148.681351ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:05.123659Z","caller":"traceutil/trace.go:171","msg":"trace[1381273488] linearizableReadLoop","detail":"{readStateIndex:1813; appliedIndex:1812; }","duration":"164.869562ms","start":"2026-03-14T07:06:04.958513Z","end":"2026-03-14T07:06:05.123383Z","steps":["trace[1381273488] 'read index received' (duration: 15.75178ms)","trace[1381273488] 'applied index is now lower than readState.Index' (duration: 149.115942ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:05.12383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.311161ms","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-14T07:06:05.123877Z","caller":"traceutil/trace.go:171","msg":"trace[1723383367] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1734; }","duration":"165.415873ms","start":"2026-03-14T07:06:04.958447Z","end":"2026-03-14T07:06:05.123863Z","steps":["trace[1723383367] 'agreement among raft nodes before linearized reading' (duration: 165.26698ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:07.107268Z","caller":"traceutil/trace.go:171","msg":"trace[721311747] transaction","detail":"{read_only:false; response_revision:1742; number_of_response:1; }","duration":"173.942048ms","start":"2026-03-14T07:06:06.933294Z","end":"2026-03-14T07:06:07.107237Z","steps":["trace[721311747] 'process raft request' (duration: 151.061766ms)","trace[721311747] 'compare' (duration: 22.591465ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:07.289004Z","caller":"traceutil/trace.go:171","msg":"trace[1786831904] transaction","detail":"{read_only:false; response_revision:1743; number_of_response:1; }","duration":"147.789924ms","start":"2026-03-14T07:06:07.141174Z","end":"2026-03-14T07:06:07.288964Z","steps":["trace[1786831904] 'process raft request' (duration: 115.145327ms)","trace[1786831904] 'compare' (duration: 32.458503ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:09.484676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.942182ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:06:09.484792Z","caller":"traceutil/trace.go:171","msg":"trace[890442050] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"164.293847ms","start":"2026-03-14T07:06:09.320475Z","end":"2026-03-14T07:06:09.484768Z","steps":["trace[890442050] 'process raft request' (duration: 29.177601ms)","trace[890442050] 'compare' (duration: 134.832389ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:10.258691Z","caller":"traceutil/trace.go:171","msg":"trace[1243357567] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"125.696429ms","start":"2026-03-14T07:06:10.132953Z","end":"2026-03-14T07:06:10.258649Z","steps":["trace[1243357567] 'process raft request' (duration: 83.287548ms)","trace[1243357567] 'compare' (duration: 42.046963ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:19.050368Z","caller":"traceutil/trace.go:171","msg":"trace[1997939429] linearizableReadLoop","detail":"{readStateIndex:1890; appliedIndex:1889; }","duration":"183.024666ms","start":"2026-03-14T07:06:18.867314Z","end":"2026-03-14T07:06:19.050338Z","steps":["trace[1997939429] 'read index received' (duration: 182.842743ms)","trace[1997939429] 'applied index is now lower than readState.Index' (duration: 180.853µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:19.050428Z","caller":"traceutil/trace.go:171","msg":"trace[1503466105] transaction","detail":"{read_only:false; response_revision:1808; number_of_response:1; }","duration":"271.206612ms","start":"2026-03-14T07:06:18.779198Z","end":"2026-03-14T07:06:19.050405Z","steps":["trace[1503466105] 'process raft request' (duration: 270.984307ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:19.050505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.166111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:19.050542Z","caller":"traceutil/trace.go:171","msg":"trace[1910473090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1808; }","duration":"183.235513ms","start":"2026-03-14T07:06:18.867299Z","end":"2026-03-14T07:06:19.050535Z","steps":["trace[1910473090] 'agreement among raft nodes before linearized reading' (duration: 183.14423ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:19.266148Z","caller":"traceutil/trace.go:171","msg":"trace[387003759] transaction","detail":"{read_only:false; response_revision:1809; number_of_response:1; }","duration":"158.308644ms","start":"2026-03-14T07:06:19.107794Z","end":"2026-03-14T07:06:19.266103Z","steps":["trace[387003759] 'process raft request' (duration: 135.915623ms)","trace[387003759] 'compare' (duration: 22.124235ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:20.068168Z","caller":"traceutil/trace.go:171","msg":"trace[1650927737] transaction","detail":"{read_only:false; response_revision:1811; number_of_response:1; }","duration":"100.466806ms","start":"2026-03-14T07:06:19.96768Z","end":"2026-03-14T07:06:20.068146Z","steps":["trace[1650927737] 'process raft request' (duration: 100.310512ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:20.268674Z","caller":"traceutil/trace.go:171","msg":"trace[60940258] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"130.371191ms","start":"2026-03-14T07:06:20.13826Z","end":"2026-03-14T07:06:20.268632Z","steps":["trace[60940258] 'process raft request' (duration: 130.226218ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:25.441484Z","caller":"traceutil/trace.go:171","msg":"trace[302137918] transaction","detail":"{read_only:false; response_revision:1836; number_of_response:1; }","duration":"135.459653ms","start":"2026-03-14T07:06:25.305992Z","end":"2026-03-14T07:06:25.441451Z","steps":["trace[302137918] 'process raft request' (duration: 89.216537ms)","trace[302137918] 'compare' (duration: 45.897958ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:25.622569Z","caller":"traceutil/trace.go:171","msg":"trace[1714801142] transaction","detail":"{read_only:false; response_revision:1838; number_of_response:1; }","duration":"174.535451ms","start":"2026-03-14T07:06:25.447987Z","end":"2026-03-14T07:06:25.622522Z","steps":["trace[1714801142] 'process raft request' (duration: 107.127011ms)","trace[1714801142] 'compare' (duration: 67.185625ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:26.282257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.473993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:26.282366Z","caller":"traceutil/trace.go:171","msg":"trace[1147505272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1840; }","duration":"412.641747ms","start":"2026-03-14T07:06:25.869701Z","end":"2026-03-14T07:06:26.282343Z","steps":["trace[1147505272] 'range keys from in-memory index tree' (duration: 412.35379ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:26.282503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.729092ms","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-14T07:06:26.28255Z","caller":"traceutil/trace.go:171","msg":"trace[1360793107] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1840; }","duration":"121.783173ms","start":"2026-03-14T07:06:26.160753Z","end":"2026-03-14T07:06:26.282537Z","steps":["trace[1360793107] 'count revisions from in-memory index tree' (duration: 121.63393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:26.282826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.254038ms","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-14T07:06:26.282902Z","caller":"traceutil/trace.go:171","msg":"trace[1571382921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1840; }","duration":"186.37145ms","start":"2026-03-14T07:06:26.096511Z","end":"2026-03-14T07:06:26.282883Z","steps":["trace[1571382921] 'range keys from in-memory index tree' (duration: 185.789648ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:26.282415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:25.869683Z","time spent":"412.722078ms","remote":"127.0.0.1:43302","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-14T07:06:26.28315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.026616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:26.283298Z","caller":"traceutil/trace.go:171","msg":"trace[2012478727] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1840; }","duration":"172.258891ms","start":"2026-03-14T07:06:26.111004Z","end":"2026-03-14T07:06:26.283263Z","steps":["trace[2012478727] 'count revisions from in-memory index tree' (duration: 171.119806ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:27.307987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.660615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:06:27.308149Z","caller":"traceutil/trace.go:171","msg":"trace[1439730197] linearizableReadLoop","detail":"{readStateIndex:1925; appliedIndex:1923; }","duration":"1.003805532s","start":"2026-03-14T07:06:26.304319Z","end":"2026-03-14T07:06:27.308124Z","steps":["trace[1439730197] 'read index received' (duration: 13.740912ms)","trace[1439730197] 'applied index is now lower than readState.Index' (duration: 990.0623ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:27.30826Z","caller":"traceutil/trace.go:171","msg":"trace[378153977] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"1.019422264s","start":"2026-03-14T07:06:26.288821Z","end":"2026-03-14T07:06:27.308243Z","steps":["trace[378153977] 'process raft request' (duration: 145.131005ms)","trace[378153977] 'compare' (duration: 873.533363ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:27.308335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:26.288801Z","time spent":"1.019492086s","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:06:27.30864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004312353s","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-14T07:06:27.308693Z","caller":"traceutil/trace.go:171","msg":"trace[693188502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1842; }","duration":"1.004387465s","start":"2026-03-14T07:06:26.304293Z","end":"2026-03-14T07:06:27.30868Z","steps":["trace[693188502] 'agreement among raft nodes before linearized reading' (duration: 1.004234611s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:27.308725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:26.304283Z","time spent":"1.004435306s","remote":"127.0.0.1:43566","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-14T07:06:27.308809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.025815ms","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:531"} {"level":"warn","ts":"2026-03-14T07:06:27.308821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.965892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-14T07:06:27.308966Z","caller":"traceutil/trace.go:171","msg":"trace[467449347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1842; }","duration":"197.166587ms","start":"2026-03-14T07:06:27.111781Z","end":"2026-03-14T07:06:27.308948Z","steps":["trace[467449347] 'agreement among raft nodes before linearized reading' (duration: 196.8551ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:27.308986Z","caller":"traceutil/trace.go:171","msg":"trace[1411928760] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1842; }","duration":"752.169569ms","start":"2026-03-14T07:06:26.556695Z","end":"2026-03-14T07:06:27.308865Z","steps":["trace[1411928760] 'agreement among raft nodes before linearized reading' (duration: 751.937694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:27.309631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:26.556659Z","time spent":"752.961137ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-03-14T07:06:27.585787Z","caller":"traceutil/trace.go:171","msg":"trace[318570081] linearizableReadLoop","detail":"{readStateIndex:1928; appliedIndex:1925; }","duration":"262.578682ms","start":"2026-03-14T07:06:27.323181Z","end":"2026-03-14T07:06:27.58576Z","steps":["trace[318570081] 'read index received' (duration: 36.182494ms)","trace[318570081] 'applied index is now lower than readState.Index' (duration: 226.394958ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:27.585869Z","caller":"traceutil/trace.go:171","msg":"trace[1131727998] transaction","detail":"{read_only:false; response_revision:1845; number_of_response:1; }","duration":"271.716543ms","start":"2026-03-14T07:06:27.314129Z","end":"2026-03-14T07:06:27.585845Z","steps":["trace[1131727998] 'process raft request' (duration: 271.58482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:27.586426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.831069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-14T07:06:27.586507Z","caller":"traceutil/trace.go:171","msg":"trace[1404923444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1845; }","duration":"246.940751ms","start":"2026-03-14T07:06:27.339534Z","end":"2026-03-14T07:06:27.586475Z","steps":["trace[1404923444] 'agreement among raft nodes before linearized reading' (duration: 246.374748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:27.58651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.287691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-14T07:06:27.586592Z","caller":"traceutil/trace.go:171","msg":"trace[308906779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1845; }","duration":"141.351693ms","start":"2026-03-14T07:06:27.445204Z","end":"2026-03-14T07:06:27.586555Z","steps":["trace[308906779] 'agreement among raft nodes before linearized reading' (duration: 140.756119ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:27.585854Z","caller":"traceutil/trace.go:171","msg":"trace[358803570] transaction","detail":"{read_only:false; response_revision:1844; number_of_response:1; }","duration":"272.48506ms","start":"2026-03-14T07:06:27.313322Z","end":"2026-03-14T07:06:27.585807Z","steps":["trace[358803570] 'process raft request' (duration: 272.249555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:27.587198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.011954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:27.587267Z","caller":"traceutil/trace.go:171","msg":"trace[739679964] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1845; }","duration":"264.115867ms","start":"2026-03-14T07:06:27.323133Z","end":"2026-03-14T07:06:27.587249Z","steps":["trace[739679964] 'agreement among raft nodes before linearized reading' (duration: 262.755056ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:27.803482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.198353ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:06:27.803667Z","caller":"traceutil/trace.go:171","msg":"trace[1948132071] transaction","detail":"{read_only:false; response_revision:1846; number_of_response:1; }","duration":"210.424429ms","start":"2026-03-14T07:06:27.593215Z","end":"2026-03-14T07:06:27.803639Z","steps":["trace[1948132071] 'process raft request' (duration: 76.97885ms)","trace[1948132071] 'compare' (duration: 133.095191ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:27.804019Z","caller":"traceutil/trace.go:171","msg":"trace[647600916] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1928; }","duration":"209.15078ms","start":"2026-03-14T07:06:27.594855Z","end":"2026-03-14T07:06:27.804006Z","steps":["trace[647600916] 'read index received' (duration: 75.352853ms)","trace[647600916] 'applied index is now lower than readState.Index' (duration: 133.796917ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:27.804084Z","caller":"traceutil/trace.go:171","msg":"trace[635000945] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"209.889627ms","start":"2026-03-14T07:06:27.594159Z","end":"2026-03-14T07:06:27.804049Z","steps":["trace[635000945] 'process raft request' (duration: 209.421477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:27.804298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.383305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:27.804438Z","caller":"traceutil/trace.go:171","msg":"trace[961229142] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1847; }","duration":"209.558659ms","start":"2026-03-14T07:06:27.594831Z","end":"2026-03-14T07:06:27.80439Z","steps":["trace[961229142] 'agreement among raft nodes before linearized reading' (duration: 209.212662ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:28.273655Z","caller":"traceutil/trace.go:171","msg":"trace[767019142] linearizableReadLoop","detail":"{readStateIndex:1931; appliedIndex:1930; }","duration":"406.179335ms","start":"2026-03-14T07:06:27.867442Z","end":"2026-03-14T07:06:28.273621Z","steps":["trace[767019142] 'read index received' (duration: 311.125119ms)","trace[767019142] 'applied index is now lower than readState.Index' (duration: 95.049766ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:28.273752Z","caller":"traceutil/trace.go:171","msg":"trace[860017173] transaction","detail":"{read_only:false; response_revision:1849; number_of_response:1; }","duration":"363.322485ms","start":"2026-03-14T07:06:27.910412Z","end":"2026-03-14T07:06:28.273734Z","steps":["trace[860017173] 'process raft request' (duration: 362.989108ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:28.273962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:27.910392Z","time spent":"363.450057ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:06:28.27399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.536313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:28.273853Z","caller":"traceutil/trace.go:171","msg":"trace[54993904] transaction","detail":"{read_only:false; response_revision:1848; number_of_response:1; }","duration":"465.940797ms","start":"2026-03-14T07:06:27.807749Z","end":"2026-03-14T07:06:28.27369Z","steps":["trace[54993904] 'process raft request' (duration: 370.805189ms)","trace[54993904] 'compare' (duration: 94.494254ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:28.274119Z","caller":"traceutil/trace.go:171","msg":"trace[237657550] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1850; }","duration":"406.625415ms","start":"2026-03-14T07:06:27.867396Z","end":"2026-03-14T07:06:28.274021Z","steps":["trace[237657550] 'agreement among raft nodes before linearized reading' (duration: 406.38397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:28.274183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.392369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:06:28.274243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:27.867366Z","time spent":"406.86083ms","remote":"127.0.0.1:41716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-03-14T07:06:28.274264Z","caller":"traceutil/trace.go:171","msg":"trace[2055903170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1850; }","duration":"406.473482ms","start":"2026-03-14T07:06:27.867757Z","end":"2026-03-14T07:06:28.274231Z","steps":["trace[2055903170] 'agreement among raft nodes before linearized reading' (duration: 406.395ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:28.274298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:27.867746Z","time spent":"406.545053ms","remote":"127.0.0.1:43302","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-14T07:06:28.274383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:27.807732Z","time spent":"466.422277ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:06:28.274474Z","caller":"traceutil/trace.go:171","msg":"trace[1450813672] transaction","detail":"{read_only:false; response_revision:1850; number_of_response:1; }","duration":"131.374583ms","start":"2026-03-14T07:06:28.142191Z","end":"2026-03-14T07:06:28.273565Z","steps":["trace[1450813672] 'process raft request' (duration: 131.331752ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:28.274527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.845627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-14T07:06:28.274634Z","caller":"traceutil/trace.go:171","msg":"trace[1646026109] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:1850; }","duration":"211.002961ms","start":"2026-03-14T07:06:28.063607Z","end":"2026-03-14T07:06:28.27461Z","steps":["trace[1646026109] 'agreement among raft nodes before linearized reading' (duration: 210.727245ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:28.647159Z","caller":"traceutil/trace.go:171","msg":"trace[1853264202] transaction","detail":"{read_only:false; response_revision:1851; number_of_response:1; }","duration":"277.220175ms","start":"2026-03-14T07:06:28.369907Z","end":"2026-03-14T07:06:28.647127Z","steps":["trace[1853264202] 'process raft request' (duration: 277.026261ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:28.667608Z","caller":"traceutil/trace.go:171","msg":"trace[2067573117] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"294.72373ms","start":"2026-03-14T07:06:28.372855Z","end":"2026-03-14T07:06:28.667579Z","steps":["trace[2067573117] 'process raft request' (duration: 294.512094ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:36.07341Z","caller":"traceutil/trace.go:171","msg":"trace[1386034151] linearizableReadLoop","detail":"{readStateIndex:1971; appliedIndex:1970; }","duration":"131.057957ms","start":"2026-03-14T07:06:35.942289Z","end":"2026-03-14T07:06:36.073347Z","steps":["trace[1386034151] 'read index received' (duration: 46.472781ms)","trace[1386034151] 'applied index is now lower than readState.Index' (duration: 84.583536ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:36.07368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.246612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslice-controller\" ","response":"range_response_count:1 size:793"} {"level":"info","ts":"2026-03-14T07:06:36.0737Z","caller":"traceutil/trace.go:171","msg":"trace[1395943484] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"155.607735ms","start":"2026-03-14T07:06:35.918029Z","end":"2026-03-14T07:06:36.073637Z","steps":["trace[1395943484] 'process raft request' (duration: 70.810534ms)","trace[1395943484] 'compare' (duration: 84.349051ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:36.073729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.403155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:endpointslicemirroring-controller\" ","response":"range_response_count:1 size:843"} {"level":"info","ts":"2026-03-14T07:06:36.073742Z","caller":"traceutil/trace.go:171","msg":"trace[82990998] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslice-controller; range_end:; response_count:1; response_revision:1885; }","duration":"106.383944ms","start":"2026-03-14T07:06:35.967341Z","end":"2026-03-14T07:06:36.073724Z","steps":["trace[82990998] 'agreement among raft nodes before linearized reading' (duration: 106.228951ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:36.073824Z","caller":"traceutil/trace.go:171","msg":"trace[463676570] range","detail":"{range_begin:/registry/clusterroles/system:controller:endpointslicemirroring-controller; range_end:; response_count:1; response_revision:1885; }","duration":"131.551777ms","start":"2026-03-14T07:06:35.942248Z","end":"2026-03-14T07:06:36.0738Z","steps":["trace[463676570] 'agreement among raft nodes before linearized reading' (duration: 131.307942ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:44.075548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.992093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-public/\" range_end:\"/registry/limitranges/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:44.075652Z","caller":"traceutil/trace.go:171","msg":"trace[1248952978] range","detail":"{range_begin:/registry/limitranges/kube-public/; range_end:/registry/limitranges/kube-public0; response_count:0; response_revision:1920; }","duration":"104.134746ms","start":"2026-03-14T07:06:43.971491Z","end":"2026-03-14T07:06:44.075626Z","steps":["trace[1248952978] 'range keys from in-memory index tree' (duration: 103.908251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:44.075632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.454773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:44.075731Z","caller":"traceutil/trace.go:171","msg":"trace[925069603] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:1920; }","duration":"104.602567ms","start":"2026-03-14T07:06:43.971104Z","end":"2026-03-14T07:06:44.075707Z","steps":["trace[925069603] 'range keys from in-memory index tree' (duration: 104.395412ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:57.958409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.006929ms","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-14T07:06:57.95852Z","caller":"traceutil/trace.go:171","msg":"trace[1509725746] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1973; }","duration":"102.187993ms","start":"2026-03-14T07:06:57.856304Z","end":"2026-03-14T07:06:57.958492Z","steps":["trace[1509725746] 'range keys from in-memory index tree' (duration: 101.799785ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:58.094063Z","caller":"traceutil/trace.go:171","msg":"trace[419813663] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"130.939384ms","start":"2026-03-14T07:06:57.963087Z","end":"2026-03-14T07:06:58.094026Z","steps":["trace[419813663] 'process raft request' (duration: 130.74706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:58.339658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.078997ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:06:58.339895Z","caller":"traceutil/trace.go:171","msg":"trace[1428994427] linearizableReadLoop","detail":"{readStateIndex:2065; appliedIndex:2063; }","duration":"295.596678ms","start":"2026-03-14T07:06:58.044278Z","end":"2026-03-14T07:06:58.339875Z","steps":["trace[1428994427] 'read index received' (duration: 49.609219ms)","trace[1428994427] 'applied index is now lower than readState.Index' (duration: 245.985719ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:58.33996Z","caller":"traceutil/trace.go:171","msg":"trace[1622462826] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"376.610646ms","start":"2026-03-14T07:06:57.963296Z","end":"2026-03-14T07:06:58.339906Z","steps":["trace[1622462826] 'process raft request' (duration: 199.16034ms)","trace[1622462826] 'compare' (duration: 176.889083ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:58.340207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.869453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.223\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-14T07:06:58.340264Z","caller":"traceutil/trace.go:171","msg":"trace[86820513] range","detail":"{range_begin:/registry/masterleases/199.204.45.223; range_end:; response_count:1; response_revision:1975; }","duration":"274.955724ms","start":"2026-03-14T07:06:58.065298Z","end":"2026-03-14T07:06:58.340253Z","steps":["trace[86820513] 'agreement among raft nodes before linearized reading' (duration: 274.774571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:58.340248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.966546ms","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-14T07:06:58.340287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.431477ms","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-14T07:06:58.340316Z","caller":"traceutil/trace.go:171","msg":"trace[13493775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1975; }","duration":"296.038827ms","start":"2026-03-14T07:06:58.044264Z","end":"2026-03-14T07:06:58.340302Z","steps":["trace[13493775] 'agreement among raft nodes before linearized reading' (duration: 295.723291ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:58.340321Z","caller":"traceutil/trace.go:171","msg":"trace[1136302989] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1975; }","duration":"245.464818ms","start":"2026-03-14T07:06:58.094844Z","end":"2026-03-14T07:06:58.340308Z","steps":["trace[1136302989] 'agreement among raft nodes before linearized reading' (duration: 245.395116ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:58.340196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:06:57.963258Z","time spent":"376.79895ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:06:58.581696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.037067ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-03-14T07:06:58.584003Z","caller":"traceutil/trace.go:171","msg":"trace[1446514954] linearizableReadLoop","detail":"{readStateIndex:2068; appliedIndex:2065; }","duration":"213.288921ms","start":"2026-03-14T07:06:58.370695Z","end":"2026-03-14T07:06:58.583984Z","steps":["trace[1446514954] 'read index received' (duration: 104.944712ms)","trace[1446514954] 'applied index is now lower than readState.Index' (duration: 108.343129ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:58.584193Z","caller":"traceutil/trace.go:171","msg":"trace[1424061835] transaction","detail":"{read_only:false; response_revision:1976; number_of_response:1; }","duration":"239.211501ms","start":"2026-03-14T07:06:58.344961Z","end":"2026-03-14T07:06:58.584172Z","steps":["trace[1424061835] 'process raft request' (duration: 238.860484ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:58.584206Z","caller":"traceutil/trace.go:171","msg":"trace[558181239] transaction","detail":"{read_only:false; response_revision:1977; number_of_response:1; }","duration":"239.111399ms","start":"2026-03-14T07:06:58.345072Z","end":"2026-03-14T07:06:58.584183Z","steps":["trace[558181239] 'process raft request' (duration: 238.874514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:58.584581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.837483ms","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:525"} {"level":"info","ts":"2026-03-14T07:06:58.585017Z","caller":"traceutil/trace.go:171","msg":"trace[2090719536] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1977; }","duration":"214.322563ms","start":"2026-03-14T07:06:58.370641Z","end":"2026-03-14T07:06:58.584963Z","steps":["trace[2090719536] 'agreement among raft nodes before linearized reading' (duration: 213.589647ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:06:58.791573Z","caller":"traceutil/trace.go:171","msg":"trace[1181286122] linearizableReadLoop","detail":"{readStateIndex:2070; appliedIndex:2069; }","duration":"120.966565ms","start":"2026-03-14T07:06:58.670577Z","end":"2026-03-14T07:06:58.791543Z","steps":["trace[1181286122] 'read index received' (duration: 56.46188ms)","trace[1181286122] 'applied index is now lower than readState.Index' (duration: 64.503615ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:58.791652Z","caller":"traceutil/trace.go:171","msg":"trace[1819677218] transaction","detail":"{read_only:false; response_revision:1979; number_of_response:1; }","duration":"202.688639ms","start":"2026-03-14T07:06:58.588924Z","end":"2026-03-14T07:06:58.791613Z","steps":["trace[1819677218] 'process raft request' (duration: 138.108221ms)","trace[1819677218] 'compare' (duration: 64.367633ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:58.79181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.19614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-14T07:06:58.791947Z","caller":"traceutil/trace.go:171","msg":"trace[271241830] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1979; }","duration":"121.386814ms","start":"2026-03-14T07:06:58.670536Z","end":"2026-03-14T07:06:58.791923Z","steps":["trace[271241830] 'agreement among raft nodes before linearized reading' (duration: 121.105617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:59.00688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.649125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:06:59.006982Z","caller":"traceutil/trace.go:171","msg":"trace[1689450370] linearizableReadLoop","detail":"{readStateIndex:2071; appliedIndex:2070; }","duration":"138.763416ms","start":"2026-03-14T07:06:58.868202Z","end":"2026-03-14T07:06:59.006965Z","steps":["trace[1689450370] 'read index received' (duration: 34.862585ms)","trace[1689450370] 'applied index is now lower than readState.Index' (duration: 103.899671ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:06:59.007059Z","caller":"traceutil/trace.go:171","msg":"trace[575351621] transaction","detail":"{read_only:false; response_revision:1980; number_of_response:1; }","duration":"156.455055ms","start":"2026-03-14T07:06:58.850592Z","end":"2026-03-14T07:06:59.007047Z","steps":["trace[575351621] 'process raft request' (duration: 52.546464ms)","trace[575351621] 'compare' (duration: 103.535183ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:06:59.00722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.748916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:59.007305Z","caller":"traceutil/trace.go:171","msg":"trace[532166803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1980; }","duration":"138.877979ms","start":"2026-03-14T07:06:58.868404Z","end":"2026-03-14T07:06:59.007282Z","steps":["trace[532166803] 'agreement among raft nodes before linearized reading' (duration: 138.746796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:06:59.007304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.068183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/\" range_end:\"/registry/pods/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:06:59.007386Z","caller":"traceutil/trace.go:171","msg":"trace[2080978815] range","detail":"{range_begin:/registry/pods/default/; range_end:/registry/pods/default0; response_count:0; response_revision:1980; }","duration":"139.182746ms","start":"2026-03-14T07:06:58.868184Z","end":"2026-03-14T07:06:59.007367Z","steps":["trace[2080978815] 'agreement among raft nodes before linearized reading' (duration: 139.037323ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:07:02.788666Z","caller":"traceutil/trace.go:171","msg":"trace[1377692614] linearizableReadLoop","detail":"{readStateIndex:2085; appliedIndex:2084; }","duration":"206.944842ms","start":"2026-03-14T07:07:02.581696Z","end":"2026-03-14T07:07:02.78864Z","steps":["trace[1377692614] 'read index received' (duration: 206.84449ms)","trace[1377692614] 'applied index is now lower than readState.Index' (duration: 99.492µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:07:02.788788Z","caller":"traceutil/trace.go:171","msg":"trace[1471011596] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"209.510879ms","start":"2026-03-14T07:07:02.579264Z","end":"2026-03-14T07:07:02.788775Z","steps":["trace[1471011596] 'process raft request' (duration: 209.244293ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:02.789197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.477053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6rh9j\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-03-14T07:07:02.78926Z","caller":"traceutil/trace.go:171","msg":"trace[2090170888] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6rh9j; range_end:; response_count:1; response_revision:1994; }","duration":"207.581616ms","start":"2026-03-14T07:07:02.581663Z","end":"2026-03-14T07:07:02.789245Z","steps":["trace[2090170888] 'agreement among raft nodes before linearized reading' (duration: 207.341231ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:02.789349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.883904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-03-14T07:07:02.789578Z","caller":"traceutil/trace.go:171","msg":"trace[477136687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1994; }","duration":"193.15597ms","start":"2026-03-14T07:07:02.596408Z","end":"2026-03-14T07:07:02.789564Z","steps":["trace[477136687] 'agreement among raft nodes before linearized reading' (duration: 192.781242ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:02.789565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.189709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-14T07:07:02.789867Z","caller":"traceutil/trace.go:171","msg":"trace[1378709773] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1994; }","duration":"192.505525ms","start":"2026-03-14T07:07:02.597296Z","end":"2026-03-14T07:07:02.789802Z","steps":["trace[1378709773] 'agreement among raft nodes before linearized reading' (duration: 192.016634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:03.024596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.485615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:07:03.024829Z","caller":"traceutil/trace.go:171","msg":"trace[869593457] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"229.16925ms","start":"2026-03-14T07:07:02.795621Z","end":"2026-03-14T07:07:03.02479Z","steps":["trace[869593457] 'process raft request' (duration: 31.204805ms)","trace[869593457] 'compare' (duration: 197.324011ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:07:03.024995Z","caller":"traceutil/trace.go:171","msg":"trace[1325278481] linearizableReadLoop","detail":"{readStateIndex:2087; appliedIndex:2085; }","duration":"226.067793ms","start":"2026-03-14T07:07:02.798882Z","end":"2026-03-14T07:07:03.02495Z","steps":["trace[1325278481] 'read index received' (duration: 27.956214ms)","trace[1325278481] 'applied index is now lower than readState.Index' (duration: 198.110048ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:07:03.025005Z","caller":"traceutil/trace.go:171","msg":"trace[1009524656] transaction","detail":"{read_only:false; response_revision:1996; number_of_response:1; }","duration":"228.541357ms","start":"2026-03-14T07:07:02.796425Z","end":"2026-03-14T07:07:03.024967Z","steps":["trace[1009524656] 'process raft request' (duration: 228.326922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:03.025209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.843913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:07:03.025279Z","caller":"traceutil/trace.go:171","msg":"trace[891612081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1996; }","duration":"156.970065ms","start":"2026-03-14T07:07:02.868295Z","end":"2026-03-14T07:07:03.025265Z","steps":["trace[891612081] 'agreement among raft nodes before linearized reading' (duration: 156.870743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:03.025589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.673515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6rh9j\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-03-14T07:07:03.025705Z","caller":"traceutil/trace.go:171","msg":"trace[518983920] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6rh9j; range_end:; response_count:1; response_revision:1996; }","duration":"226.832788ms","start":"2026-03-14T07:07:02.798849Z","end":"2026-03-14T07:07:03.025682Z","steps":["trace[518983920] 'agreement among raft nodes before linearized reading' (duration: 226.189744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:03.530634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.478923ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-14T07:07:03.530774Z","caller":"traceutil/trace.go:171","msg":"trace[785858163] linearizableReadLoop","detail":"{readStateIndex:2089; appliedIndex:2088; }","duration":"325.123047ms","start":"2026-03-14T07:07:03.205608Z","end":"2026-03-14T07:07:03.530731Z","steps":["trace[785858163] 'read index received' (duration: 4.427408ms)","trace[785858163] 'applied index is now lower than readState.Index' (duration: 320.693959ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:07:03.531019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.404392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6rh9j\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-03-14T07:07:03.531058Z","caller":"traceutil/trace.go:171","msg":"trace[1062745969] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6rh9j; range_end:; response_count:1; response_revision:1997; }","duration":"325.497234ms","start":"2026-03-14T07:07:03.205547Z","end":"2026-03-14T07:07:03.531044Z","steps":["trace[1062745969] 'agreement among raft nodes before linearized reading' (duration: 325.265199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:03.531101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:03.20552Z","time spent":"325.572187ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18547,"request content":"key:\"/registry/pods/kube-system/cilium-6rh9j\" "} {"level":"warn","ts":"2026-03-14T07:07:04.185092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.141772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:07:04.185181Z","caller":"traceutil/trace.go:171","msg":"trace[169135564] linearizableReadLoop","detail":"{readStateIndex:2091; appliedIndex:2090; }","duration":"368.066329ms","start":"2026-03-14T07:07:03.817101Z","end":"2026-03-14T07:07:04.185167Z","steps":["trace[169135564] 'read index received' (duration: 132.762943ms)","trace[169135564] 'applied index is now lower than readState.Index' (duration: 235.302636ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:07:04.18525Z","caller":"traceutil/trace.go:171","msg":"trace[2051274228] transaction","detail":"{read_only:false; response_revision:1999; number_of_response:1; }","duration":"371.872433ms","start":"2026-03-14T07:07:03.813371Z","end":"2026-03-14T07:07:04.185244Z","steps":["trace[2051274228] 'process raft request' (duration: 136.519917ms)","trace[2051274228] 'compare' (duration: 234.988798ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:07:04.185287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:03.813351Z","time spent":"371.918824ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:07:04.185495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.555999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:07:04.185589Z","caller":"traceutil/trace.go:171","msg":"trace[141846297] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1999; }","duration":"316.687721ms","start":"2026-03-14T07:07:03.868878Z","end":"2026-03-14T07:07:04.185566Z","steps":["trace[141846297] 'agreement among raft nodes before linearized reading' (duration: 316.522388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:04.185561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.113156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-14T07:07:04.18557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.452277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-gkg47\" ","response":"range_response_count:1 size:4694"} {"level":"info","ts":"2026-03-14T07:07:04.185652Z","caller":"traceutil/trace.go:171","msg":"trace[78979544] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1999; }","duration":"315.239379ms","start":"2026-03-14T07:07:03.870391Z","end":"2026-03-14T07:07:04.185631Z","steps":["trace[78979544] 'agreement among raft nodes before linearized reading' (duration: 315.070585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:04.185666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:03.868866Z","time spent":"316.765013ms","remote":"127.0.0.1:43302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-14T07:07:04.185673Z","caller":"traceutil/trace.go:171","msg":"trace[744696124] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-gkg47; range_end:; response_count:1; response_revision:1999; }","duration":"368.57563ms","start":"2026-03-14T07:07:03.817064Z","end":"2026-03-14T07:07:04.18564Z","steps":["trace[744696124] 'agreement among raft nodes before linearized reading' (duration: 368.325424ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:04.185702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:03.870379Z","time spent":"315.312141ms","remote":"127.0.0.1:43528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-03-14T07:07:04.185718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:03.817022Z","time spent":"368.688032ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4716,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-gkg47\" "} {"level":"info","ts":"2026-03-14T07:07:04.659096Z","caller":"traceutil/trace.go:171","msg":"trace[899353583] linearizableReadLoop","detail":"{readStateIndex:2092; appliedIndex:2091; }","duration":"388.513746ms","start":"2026-03-14T07:07:04.270548Z","end":"2026-03-14T07:07:04.659062Z","steps":["trace[899353583] 'read index received' (duration: 344.564902ms)","trace[899353583] 'applied index is now lower than readState.Index' (duration: 43.947454ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:07:04.659538Z","caller":"traceutil/trace.go:171","msg":"trace[2058664359] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"469.387452ms","start":"2026-03-14T07:07:04.190113Z","end":"2026-03-14T07:07:04.6595Z","steps":["trace[2058664359] 'process raft request' (duration: 425.005809ms)","trace[2058664359] 'compare' (duration: 43.522364ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:07:04.659554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.99175ms","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:531"} {"level":"info","ts":"2026-03-14T07:07:04.659643Z","caller":"traceutil/trace.go:171","msg":"trace[769713082] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2000; }","duration":"288.150284ms","start":"2026-03-14T07:07:04.37147Z","end":"2026-03-14T07:07:04.659621Z","steps":["trace[769713082] 'agreement among raft nodes before linearized reading' (duration: 287.837177ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:04.65978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:04.190095Z","time spent":"469.522625ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:07:04.659876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.319554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-gkg47\" ","response":"range_response_count:1 size:4694"} {"level":"info","ts":"2026-03-14T07:07:04.659933Z","caller":"traceutil/trace.go:171","msg":"trace[146286832] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-gkg47; range_end:; response_count:1; response_revision:2000; }","duration":"389.448268ms","start":"2026-03-14T07:07:04.270466Z","end":"2026-03-14T07:07:04.659914Z","steps":["trace[146286832] 'agreement among raft nodes before linearized reading' (duration: 388.780042ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:04.659978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:04.270434Z","time spent":"389.535579ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4716,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-gkg47\" "} {"level":"warn","ts":"2026-03-14T07:07:04.994174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.846173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-6bzkk\" ","response":"range_response_count:1 size:4908"} {"level":"info","ts":"2026-03-14T07:07:04.994379Z","caller":"traceutil/trace.go:171","msg":"trace[617014020] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-6bzkk; range_end:; response_count:1; response_revision:2001; }","duration":"198.117219ms","start":"2026-03-14T07:07:04.796236Z","end":"2026-03-14T07:07:04.994353Z","steps":["trace[617014020] 'range keys from in-memory index tree' (duration: 197.512576ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:07:05.203922Z","caller":"traceutil/trace.go:171","msg":"trace[1625488997] transaction","detail":"{read_only:false; response_revision:2004; number_of_response:1; }","duration":"108.45396ms","start":"2026-03-14T07:07:05.095432Z","end":"2026-03-14T07:07:05.203886Z","steps":["trace[1625488997] 'process raft request' (duration: 58.504414ms)","trace[1625488997] 'compare' (duration: 49.67998ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:07:06.087642Z","caller":"traceutil/trace.go:171","msg":"trace[536402184] linearizableReadLoop","detail":"{readStateIndex:2097; appliedIndex:2096; }","duration":"329.175165ms","start":"2026-03-14T07:07:05.758433Z","end":"2026-03-14T07:07:06.087608Z","steps":["trace[536402184] 'read index received' (duration: 329.002541ms)","trace[536402184] 'applied index is now lower than readState.Index' (duration: 171.464µs)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:07:06.087819Z","caller":"traceutil/trace.go:171","msg":"trace[1428001106] transaction","detail":"{read_only:false; response_revision:2005; number_of_response:1; }","duration":"366.783811ms","start":"2026-03-14T07:07:05.720983Z","end":"2026-03-14T07:07:06.087767Z","steps":["trace[1428001106] 'process raft request' (duration: 366.424453ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:06.088026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.407792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-03-14T07:07:06.088069Z","caller":"traceutil/trace.go:171","msg":"trace[1793334130] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2005; }","duration":"310.481754ms","start":"2026-03-14T07:07:05.777571Z","end":"2026-03-14T07:07:06.088053Z","steps":["trace[1793334130] 'agreement among raft nodes before linearized reading' (duration: 310.241739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:06.087869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.41924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"warn","ts":"2026-03-14T07:07:06.088087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.868013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-03-14T07:07:06.088144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:05.777559Z","time spent":"310.575766ms","remote":"127.0.0.1:43566","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":"info","ts":"2026-03-14T07:07:06.088209Z","caller":"traceutil/trace.go:171","msg":"trace[667242258] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:2005; }","duration":"329.781178ms","start":"2026-03-14T07:07:05.758376Z","end":"2026-03-14T07:07:06.088157Z","steps":["trace[667242258] 'agreement among raft nodes before linearized reading' (duration: 329.378979ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:06.087979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.585808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-14T07:07:06.088231Z","caller":"traceutil/trace.go:171","msg":"trace[612941404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2005; }","duration":"275.005126ms","start":"2026-03-14T07:07:05.813168Z","end":"2026-03-14T07:07:06.088173Z","steps":["trace[612941404] 'agreement among raft nodes before linearized reading' (duration: 274.769361ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:06.088333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:05.758352Z","time spent":"329.939162ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5186,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-03-14T07:07:06.088397Z","caller":"traceutil/trace.go:171","msg":"trace[2065874659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2005; }","duration":"219.013618ms","start":"2026-03-14T07:07:05.869308Z","end":"2026-03-14T07:07:06.088322Z","steps":["trace[2065874659] 'agreement among raft nodes before linearized reading' (duration: 218.572138ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:06.088493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:05.720964Z","time spent":"367.091477ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:07:07.133423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.204827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:07:07.133769Z","caller":"traceutil/trace.go:171","msg":"trace[442482747] linearizableReadLoop","detail":"{readStateIndex:2099; appliedIndex:2097; }","duration":"969.375507ms","start":"2026-03-14T07:07:06.16437Z","end":"2026-03-14T07:07:07.133746Z","steps":["trace[442482747] 'read index received' (duration: 6.799629ms)","trace[442482747] 'applied index is now lower than readState.Index' (duration: 962.574358ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:07:07.133778Z","caller":"traceutil/trace.go:171","msg":"trace[1475060417] transaction","detail":"{read_only:false; response_revision:2006; number_of_response:1; }","duration":"1.040735234s","start":"2026-03-14T07:07:06.09292Z","end":"2026-03-14T07:07:07.133655Z","steps":["trace[1475060417] 'process raft request' (duration: 78.235408ms)","trace[1475060417] 'compare' (duration: 960.984902ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:07:07.134081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:06.092889Z","time spent":"1.041020679s","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:07:07.134098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"969.729354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-03-14T07:07:07.134142Z","caller":"traceutil/trace.go:171","msg":"trace[2026551992] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:2007; }","duration":"969.812986ms","start":"2026-03-14T07:07:06.164316Z","end":"2026-03-14T07:07:07.134129Z","steps":["trace[2026551992] 'agreement among raft nodes before linearized reading' (duration: 969.713314ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:07.134188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:06.164301Z","time spent":"969.878867ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5186,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-03-14T07:07:07.134386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.762862ms","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-14T07:07:07.135475Z","caller":"traceutil/trace.go:171","msg":"trace[1810830643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2007; }","duration":"471.895567ms","start":"2026-03-14T07:07:06.663559Z","end":"2026-03-14T07:07:07.135455Z","steps":["trace[1810830643] 'agreement among raft nodes before linearized reading' (duration: 470.534277ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:07.135583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:06.663539Z","time spent":"472.0268ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-14T07:07:07.134655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.935263ms","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:531"} {"level":"info","ts":"2026-03-14T07:07:07.135718Z","caller":"traceutil/trace.go:171","msg":"trace[714913291] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2007; }","duration":"424.025347ms","start":"2026-03-14T07:07:06.711661Z","end":"2026-03-14T07:07:07.135687Z","steps":["trace[714913291] 'agreement among raft nodes before linearized reading' (duration: 422.891602ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:07:07.13502Z","caller":"traceutil/trace.go:171","msg":"trace[2125400560] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"1.041874238s","start":"2026-03-14T07:07:06.093113Z","end":"2026-03-14T07:07:07.134988Z","steps":["trace[2125400560] 'process raft request' (duration: 1.040465707s)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:07.135805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:06.711649Z","time spent":"424.132549ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-03-14T07:07:07.135904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:06.093092Z","time spent":"1.042713636s","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:07:07.653151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.626977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-14T07:07:07.653266Z","caller":"traceutil/trace.go:171","msg":"trace[1906374524] transaction","detail":"{read_only:false; response_revision:2008; number_of_response:1; }","duration":"513.246535ms","start":"2026-03-14T07:07:07.14Z","end":"2026-03-14T07:07:07.653246Z","steps":["trace[1906374524] 'process raft request' (duration: 177.426784ms)","trace[1906374524] 'compare' (duration: 335.257778ms)"],"step_count":2} {"level":"warn","ts":"2026-03-14T07:07:07.653326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.139975Z","time spent":"513.323207ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:07:07.737854Z","caller":"traceutil/trace.go:171","msg":"trace[1756270558] transaction","detail":"{read_only:false; response_revision:2009; number_of_response:1; }","duration":"597.076265ms","start":"2026-03-14T07:07:07.140746Z","end":"2026-03-14T07:07:07.737823Z","steps":["trace[1756270558] 'process raft request' (duration: 596.84523ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:07.738088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.140711Z","time spent":"597.28539ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-14T07:07:07.738118Z","caller":"traceutil/trace.go:171","msg":"trace[513925235] linearizableReadLoop","detail":"{readStateIndex:2102; appliedIndex:2099; }","duration":"595.901569ms","start":"2026-03-14T07:07:07.14219Z","end":"2026-03-14T07:07:07.738092Z","steps":["trace[513925235] 'read index received' (duration: 175.347978ms)","trace[513925235] 'applied index is now lower than readState.Index' (duration: 420.552611ms)"],"step_count":2} {"level":"info","ts":"2026-03-14T07:07:07.738246Z","caller":"traceutil/trace.go:171","msg":"trace[574750150] transaction","detail":"{read_only:false; response_revision:2011; number_of_response:1; }","duration":"595.94199ms","start":"2026-03-14T07:07:07.142293Z","end":"2026-03-14T07:07:07.738235Z","steps":["trace[574750150] 'process raft request' (duration: 595.704025ms)"],"step_count":1} {"level":"info","ts":"2026-03-14T07:07:07.738303Z","caller":"traceutil/trace.go:171","msg":"trace[1805581381] transaction","detail":"{read_only:false; response_revision:2010; number_of_response:1; }","duration":"597.466345ms","start":"2026-03-14T07:07:07.140813Z","end":"2026-03-14T07:07:07.73828Z","steps":["trace[1805581381] 'process raft request' (duration: 596.952853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:07.738351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.142282Z","time spent":"595.994781ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:07:07.738418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.140787Z","time spent":"597.574586ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-14T07:07:07.738527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.329599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-03-14T07:07:07.738601Z","caller":"traceutil/trace.go:171","msg":"trace[672275637] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:2011; }","duration":"596.527693ms","start":"2026-03-14T07:07:07.14206Z","end":"2026-03-14T07:07:07.738587Z","steps":["trace[672275637] 'agreement among raft nodes before linearized reading' (duration: 596.37838ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:07.738585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"530.630237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-14T07:07:07.738661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.142051Z","time spent":"596.598995ms","remote":"127.0.0.1:43486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5186,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-03-14T07:07:07.738693Z","caller":"traceutil/trace.go:171","msg":"trace[1823581828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2011; }","duration":"530.813271ms","start":"2026-03-14T07:07:07.207856Z","end":"2026-03-14T07:07:07.73867Z","steps":["trace[1823581828] 'agreement among raft nodes before linearized reading' (duration: 530.484114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-14T07:07:07.738978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-14T07:07:07.207833Z","time spent":"531.129238ms","remote":"127.0.0.1:43566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}