{"level":"warn","ts":"2026-06-19T13:35:21.681283Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-19T13:35:21.6814Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.122: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.19.213.122:2380","--initial-cluster=instance=https://199.19.213.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.122: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-06-19T13:35:21.681483Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-19T13:35:21.6815Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.122:2380"]} {"level":"info","ts":"2026-06-19T13:35:21.681527Z","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-06-19T13:35:21.682331Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"]} {"level":"info","ts":"2026-06-19T13:35:21.68242Z","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.19.213.122:2380"],"listen-peer-urls":["https://199.19.213.122:2380"],"advertise-client-urls":["https://199.19.213.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.122: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-06-19T13:35:21.684926Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.276965ms"} {"level":"info","ts":"2026-06-19T13:35:21.691001Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f9ba8c11acfbc749","cluster-id":"ca7c3e103d4cd533"} {"level":"info","ts":"2026-06-19T13:35:21.691084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=()"} {"level":"info","ts":"2026-06-19T13:35:21.691108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became follower at term 0"} {"level":"info","ts":"2026-06-19T13:35:21.69112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f9ba8c11acfbc749 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-19T13:35:21.691139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became follower at term 1"} {"level":"info","ts":"2026-06-19T13:35:21.69118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=(17994849268656752457)"} {"level":"warn","ts":"2026-06-19T13:35:21.696412Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-19T13:35:21.698176Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-19T13:35:21.699474Z","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-06-19T13:35:21.702605Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f9ba8c11acfbc749","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-19T13:35:21.705554Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f9ba8c11acfbc749","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-19T13:35:21.705663Z","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-06-19T13:35:21.705808Z","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-06-19T13:35:21.705828Z","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-06-19T13:35:21.710564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=(17994849268656752457)"} {"level":"info","ts":"2026-06-19T13:35:21.710771Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ca7c3e103d4cd533","local-member-id":"f9ba8c11acfbc749","added-peer-id":"f9ba8c11acfbc749","added-peer-peer-urls":["https://199.19.213.122:2380"]} {"level":"info","ts":"2026-06-19T13:35:21.711653Z","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-06-19T13:35:21.711887Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f9ba8c11acfbc749","initial-advertise-peer-urls":["https://199.19.213.122:2380"],"listen-peer-urls":["https://199.19.213.122:2380"],"advertise-client-urls":["https://199.19.213.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-19T13:35:21.711936Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-19T13:35:21.712559Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.122:2380"} {"level":"info","ts":"2026-06-19T13:35:21.712604Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.122:2380"} {"level":"info","ts":"2026-06-19T13:35:22.292308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 is starting a new election at term 1"} {"level":"info","ts":"2026-06-19T13:35:22.292366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-19T13:35:22.292389Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 received MsgPreVoteResp from f9ba8c11acfbc749 at term 1"} {"level":"info","ts":"2026-06-19T13:35:22.292409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became candidate at term 2"} {"level":"info","ts":"2026-06-19T13:35:22.292421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 received MsgVoteResp from f9ba8c11acfbc749 at term 2"} {"level":"info","ts":"2026-06-19T13:35:22.292438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became leader at term 2"} {"level":"info","ts":"2026-06-19T13:35:22.292458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f9ba8c11acfbc749 elected leader f9ba8c11acfbc749 at term 2"} {"level":"info","ts":"2026-06-19T13:35:22.294123Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T13:35:22.294781Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f9ba8c11acfbc749","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.122:2379]}","request-path":"/0/members/f9ba8c11acfbc749/attributes","cluster-id":"ca7c3e103d4cd533","publish-timeout":"7s"} {"level":"info","ts":"2026-06-19T13:35:22.295042Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ca7c3e103d4cd533","local-member-id":"f9ba8c11acfbc749","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T13:35:22.295171Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T13:35:22.295214Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-19T13:35:22.295236Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-19T13:35:22.295693Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-19T13:35:22.29581Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-19T13:35:22.29583Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-19T13:35:22.298513Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.122:2379"} {"level":"info","ts":"2026-06-19T13:35:22.298507Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-19T13:35:57.58919Z","caller":"traceutil/trace.go:171","msg":"trace[217039152] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"352.177827ms","start":"2026-06-19T13:35:57.236985Z","end":"2026-06-19T13:35:57.589146Z","steps":["trace[217039152] 'process raft request' (duration: 261.527437ms)","trace[217039152] 'compare' (duration: 90.428754ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T13:35:57.589685Z","caller":"traceutil/trace.go:171","msg":"trace[1827964190] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"290.342004ms","start":"2026-06-19T13:35:57.299292Z","end":"2026-06-19T13:35:57.589634Z","steps":["trace[1827964190] 'process raft request' (duration: 290.158169ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:35:57.589808Z","caller":"traceutil/trace.go:171","msg":"trace[1478496304] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"286.738537ms","start":"2026-06-19T13:35:57.303052Z","end":"2026-06-19T13:35:57.58979Z","steps":["trace[1478496304] 'process raft request' (duration: 286.546233ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:35:57.589942Z","caller":"traceutil/trace.go:171","msg":"trace[1060353940] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"344.101263ms","start":"2026-06-19T13:35:57.245826Z","end":"2026-06-19T13:35:57.589928Z","steps":["trace[1060353940] 'process raft request' (duration: 343.250082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:35:57.590015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:35:57.23697Z","time spent":"352.392093ms","remote":"127.0.0.1:55882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T13:35:57.590062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:35:57.245815Z","time spent":"344.159934ms","remote":"127.0.0.1:55882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T13:36:03.893408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.48834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T13:36:03.89354Z","caller":"traceutil/trace.go:171","msg":"trace[1786485013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"452.728756ms","start":"2026-06-19T13:36:03.440784Z","end":"2026-06-19T13:36:03.893512Z","steps":["trace[1786485013] 'range keys from in-memory index tree' (duration: 452.352168ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:03.893588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:03.440765Z","time spent":"452.813719ms","remote":"127.0.0.1:55832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-19T13:36:03.914767Z","caller":"traceutil/trace.go:171","msg":"trace[758517830] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"390.370101ms","start":"2026-06-19T13:36:03.524327Z","end":"2026-06-19T13:36:03.914697Z","steps":["trace[758517830] 'process raft request' (duration: 388.551306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:03.915092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:03.524292Z","time spent":"390.649447ms","remote":"127.0.0.1:55918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T13:36:04.76978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.149746ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-19T13:36:04.770022Z","caller":"traceutil/trace.go:171","msg":"trace[1775069473] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"480.584349ms","start":"2026-06-19T13:36:04.289416Z","end":"2026-06-19T13:36:04.77Z","steps":["trace[1775069473] 'process raft request' (duration: 480.482226ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:36:04.770056Z","caller":"traceutil/trace.go:171","msg":"trace[1055029778] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"567.720913ms","start":"2026-06-19T13:36:04.202306Z","end":"2026-06-19T13:36:04.770026Z","steps":["trace[1055029778] 'read index received' (duration: 162.068485ms)","trace[1055029778] 'applied index is now lower than readState.Index' (duration: 405.650078ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T13:36:04.77014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:04.289375Z","time spent":"480.703531ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T13:36:04.770206Z","caller":"traceutil/trace.go:171","msg":"trace[1145120266] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"781.741103ms","start":"2026-06-19T13:36:03.988452Z","end":"2026-06-19T13:36:04.770193Z","steps":["trace[1145120266] 'process raft request' (duration: 375.922251ms)","trace[1145120266] 'compare' (duration: 404.965722ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T13:36:04.770307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:03.988424Z","time spent":"781.817985ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-19T13:36:04.770342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.956136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T13:36:04.770405Z","caller":"traceutil/trace.go:171","msg":"trace[2124084231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"329.063809ms","start":"2026-06-19T13:36:04.441322Z","end":"2026-06-19T13:36:04.770386Z","steps":["trace[2124084231] 'agreement among raft nodes before linearized reading' (duration: 328.959176ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:04.77044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:04.441301Z","time spent":"329.13267ms","remote":"127.0.0.1:55832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-19T13:36:04.770448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.802079ms","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-06-19T13:36:04.770499Z","caller":"traceutil/trace.go:171","msg":"trace[69038640] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"291.87485ms","start":"2026-06-19T13:36:04.478609Z","end":"2026-06-19T13:36:04.770484Z","steps":["trace[69038640] 'agreement among raft nodes before linearized reading' (duration: 291.710736ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:04.770573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.257757ms","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-06-19T13:36:04.770654Z","caller":"traceutil/trace.go:171","msg":"trace[1687301989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"568.347799ms","start":"2026-06-19T13:36:04.202287Z","end":"2026-06-19T13:36:04.770635Z","steps":["trace[1687301989] 'agreement among raft nodes before linearized reading' (duration: 568.122063ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:04.770701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:04.202206Z","time spent":"568.487222ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-19T13:36:04.770738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.253666ms","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-06-19T13:36:04.770981Z","caller":"traceutil/trace.go:171","msg":"trace[1030710765] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"296.529963ms","start":"2026-06-19T13:36:04.474419Z","end":"2026-06-19T13:36:04.770949Z","steps":["trace[1030710765] 'agreement among raft nodes before linearized reading' (duration: 296.126764ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:05.525563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360183572157980785,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-19T13:36:06.394255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.995508ms","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-06-19T13:36:06.394404Z","caller":"traceutil/trace.go:171","msg":"trace[1924369551] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:634; }","duration":"454.154991ms","start":"2026-06-19T13:36:05.940222Z","end":"2026-06-19T13:36:06.394378Z","steps":["trace[1924369551] 'range keys from in-memory index tree' (duration: 453.975837ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:06.394441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.221392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-19T13:36:06.394562Z","caller":"traceutil/trace.go:171","msg":"trace[1672800894] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"1.369534454s","start":"2026-06-19T13:36:05.025007Z","end":"2026-06-19T13:36:06.394541Z","steps":["trace[1672800894] 'read index received' (duration: 605.147249ms)","trace[1672800894] 'applied index is now lower than readState.Index' (duration: 764.385315ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T13:36:06.39465Z","caller":"traceutil/trace.go:171","msg":"trace[1204144524] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.575027579s","start":"2026-06-19T13:36:04.819611Z","end":"2026-06-19T13:36:06.394639Z","steps":["trace[1204144524] 'process raft request' (duration: 810.50115ms)","trace[1204144524] 'compare' (duration: 763.911714ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T13:36:06.394754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:04.819598Z","time spent":"1.575128141s","remote":"127.0.0.1:56026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-19T13:36:06.394825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110309972s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-06-19T13:36:06.394865Z","caller":"traceutil/trace.go:171","msg":"trace[1888015004] range","detail":"{range_begin:/registry/masterleases/199.19.213.122; range_end:; response_count:1; response_revision:635; }","duration":"1.110388783s","start":"2026-06-19T13:36:05.284465Z","end":"2026-06-19T13:36:06.394854Z","steps":["trace[1888015004] 'agreement among raft nodes before linearized reading' (duration: 1.11027141s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:06.394899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:05.284446Z","time spent":"1.110445325s","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.122\" "} {"level":"warn","ts":"2026-06-19T13:36:06.395188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.37019143s","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-06-19T13:36:06.395223Z","caller":"traceutil/trace.go:171","msg":"trace[1597882239] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"1.370342173s","start":"2026-06-19T13:36:05.024872Z","end":"2026-06-19T13:36:06.395214Z","steps":["trace[1597882239] 'agreement among raft nodes before linearized reading' (duration: 1.370225381s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:06.39525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:05.024847Z","time spent":"1.370395785s","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-19T13:36:06.39538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.505079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T13:36:06.395475Z","caller":"traceutil/trace.go:171","msg":"trace[518048926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"954.680803ms","start":"2026-06-19T13:36:05.44077Z","end":"2026-06-19T13:36:06.39545Z","steps":["trace[518048926] 'agreement among raft nodes before linearized reading' (duration: 954.480728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:06.395525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:05.440737Z","time spent":"954.779125ms","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-19T13:36:06.762296Z","caller":"traceutil/trace.go:171","msg":"trace[1524816540] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"361.923152ms","start":"2026-06-19T13:36:06.400341Z","end":"2026-06-19T13:36:06.762264Z","steps":["trace[1524816540] 'process raft request' (duration: 361.799869ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:36:06.762395Z","caller":"traceutil/trace.go:171","msg":"trace[1812151142] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"361.138944ms","start":"2026-06-19T13:36:06.401224Z","end":"2026-06-19T13:36:06.762363Z","steps":["trace[1812151142] 'process raft request' (duration: 360.956819ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:36:06.762401Z","caller":"traceutil/trace.go:171","msg":"trace[1019874605] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"361.102492ms","start":"2026-06-19T13:36:06.401267Z","end":"2026-06-19T13:36:06.762369Z","steps":["trace[1019874605] 'process raft request' (duration: 360.981109ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:06.762564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:06.401212Z","time spent":"361.252646ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T13:36:06.762574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:06.40031Z","time spent":"362.063075ms","remote":"127.0.0.1:55902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-19T13:36:06.762588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:06.401253Z","time spent":"361.237686ms","remote":"127.0.0.1:56026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T13:36:06.762562Z","caller":"traceutil/trace.go:171","msg":"trace[1995962747] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"358.194911ms","start":"2026-06-19T13:36:06.404333Z","end":"2026-06-19T13:36:06.762528Z","steps":["trace[1995962747] 'process raft request' (duration: 357.969636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:06.762751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:06.404323Z","time spent":"358.334165ms","remote":"127.0.0.1:56066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T13:36:06.762904Z","caller":"traceutil/trace.go:171","msg":"trace[906196666] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"362.551206ms","start":"2026-06-19T13:36:06.400307Z","end":"2026-06-19T13:36:06.762859Z","steps":["trace[906196666] 'process raft request' (duration: 361.628154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:06.763066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:06.400275Z","time spent":"362.70398ms","remote":"127.0.0.1:55902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-19T13:36:06.812486Z","caller":"traceutil/trace.go:171","msg":"trace[1149660221] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:657; }","duration":"370.918792ms","start":"2026-06-19T13:36:06.441539Z","end":"2026-06-19T13:36:06.812458Z","steps":["trace[1149660221] 'read index received' (duration: 320.380281ms)","trace[1149660221] 'applied index is now lower than readState.Index' (duration: 50.537501ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T13:36:06.812506Z","caller":"traceutil/trace.go:171","msg":"trace[2052519780] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"385.434245ms","start":"2026-06-19T13:36:06.427049Z","end":"2026-06-19T13:36:06.812483Z","steps":["trace[2052519780] 'process raft request' (duration: 385.157029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:06.8127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:06.427034Z","time spent":"385.559818ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T13:36:06.812749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.201418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T13:36:06.812792Z","caller":"traceutil/trace.go:171","msg":"trace[1857038439] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"371.28889ms","start":"2026-06-19T13:36:06.441492Z","end":"2026-06-19T13:36:06.812781Z","steps":["trace[1857038439] 'agreement among raft nodes before linearized reading' (duration: 371.109766ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:06.812827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:06.441477Z","time spent":"371.342661ms","remote":"127.0.0.1:55832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-19T13:36:08.75396Z","caller":"traceutil/trace.go:171","msg":"trace[1281426768] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"230.747918ms","start":"2026-06-19T13:36:08.523184Z","end":"2026-06-19T13:36:08.753932Z","steps":["trace[1281426768] 'process raft request' (duration: 230.578424ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:36:19.110025Z","caller":"traceutil/trace.go:171","msg":"trace[1372348018] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"288.739941ms","start":"2026-06-19T13:36:18.821264Z","end":"2026-06-19T13:36:19.110004Z","steps":["trace[1372348018] 'process raft request' (duration: 288.470504ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:36:19.110258Z","caller":"traceutil/trace.go:171","msg":"trace[448888773] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:802; }","duration":"233.425034ms","start":"2026-06-19T13:36:18.876534Z","end":"2026-06-19T13:36:19.109959Z","steps":["trace[448888773] 'read index received' (duration: 233.2465ms)","trace[448888773] 'applied index is now lower than readState.Index' (duration: 176.944µs)"],"step_count":2} {"level":"warn","ts":"2026-06-19T13:36:19.11035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.787323ms","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-06-19T13:36:19.110465Z","caller":"traceutil/trace.go:171","msg":"trace[1010886767] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:778; }","duration":"233.982137ms","start":"2026-06-19T13:36:18.876458Z","end":"2026-06-19T13:36:19.11044Z","steps":["trace[1010886767] 'agreement among raft nodes before linearized reading' (duration: 233.642709ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:19.110746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.575779ms","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-06-19T13:36:19.110821Z","caller":"traceutil/trace.go:171","msg":"trace[67175810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:778; }","duration":"215.731763ms","start":"2026-06-19T13:36:18.895073Z","end":"2026-06-19T13:36:19.110805Z","steps":["trace[67175810] 'agreement among raft nodes before linearized reading' (duration: 215.344174ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:19.110496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.07898ms","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-06-19T13:36:19.11143Z","caller":"traceutil/trace.go:171","msg":"trace[430289737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:778; }","duration":"219.071204ms","start":"2026-06-19T13:36:18.892342Z","end":"2026-06-19T13:36:19.111414Z","steps":["trace[430289737] 'agreement among raft nodes before linearized reading' (duration: 218.007918ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:36:21.92988Z","caller":"traceutil/trace.go:171","msg":"trace[1765565056] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"488.241467ms","start":"2026-06-19T13:36:21.441605Z","end":"2026-06-19T13:36:21.929847Z","steps":["trace[1765565056] 'read index received' (duration: 488.026612ms)","trace[1765565056] 'applied index is now lower than readState.Index' (duration: 213.245µs)"],"step_count":2} {"level":"warn","ts":"2026-06-19T13:36:21.930082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.447093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T13:36:21.930178Z","caller":"traceutil/trace.go:171","msg":"trace[226414182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"488.613368ms","start":"2026-06-19T13:36:21.441541Z","end":"2026-06-19T13:36:21.930155Z","steps":["trace[226414182] 'agreement among raft nodes before linearized reading' (duration: 488.421152ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:21.930239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:21.441516Z","time spent":"488.7141ms","remote":"127.0.0.1:55832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-19T13:36:21.930613Z","caller":"traceutil/trace.go:171","msg":"trace[488474494] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"509.914375ms","start":"2026-06-19T13:36:21.420669Z","end":"2026-06-19T13:36:21.930584Z","steps":["trace[488474494] 'process raft request' (duration: 508.973942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:21.930753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:21.420632Z","time spent":"510.049738ms","remote":"127.0.0.1:56026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T13:36:22.821398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.749804ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-19T13:36:22.821518Z","caller":"traceutil/trace.go:171","msg":"trace[410959550] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"889.896298ms","start":"2026-06-19T13:36:21.931603Z","end":"2026-06-19T13:36:22.8215Z","steps":["trace[410959550] 'read index received' (duration: 46.901µs)","trace[410959550] 'applied index is now lower than readState.Index' (duration: 889.847727ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T13:36:22.822261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.618746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T13:36:22.822378Z","caller":"traceutil/trace.go:171","msg":"trace[1071559373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"890.79835ms","start":"2026-06-19T13:36:21.931554Z","end":"2026-06-19T13:36:22.822353Z","steps":["trace[1071559373] 'agreement among raft nodes before linearized reading' (duration: 890.576005ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:22.822433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:21.931529Z","time spent":"890.894452ms","remote":"127.0.0.1:55820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-19T13:36:22.822512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.797231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-19T13:36:22.822608Z","caller":"traceutil/trace.go:171","msg":"trace[1113873517] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:795; }","duration":"619.946195ms","start":"2026-06-19T13:36:22.202638Z","end":"2026-06-19T13:36:22.822584Z","steps":["trace[1113873517] 'agreement among raft nodes before linearized reading' (duration: 619.662158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:22.822667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:22.202606Z","time spent":"620.052737ms","remote":"127.0.0.1:56084","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-06-19T13:36:57.826432Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360183572157981559,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-19T13:36:57.91398Z","caller":"traceutil/trace.go:171","msg":"trace[994354461] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"605.615497ms","start":"2026-06-19T13:36:57.308343Z","end":"2026-06-19T13:36:57.913958Z","steps":["trace[994354461] 'process raft request' (duration: 605.485554ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:57.91413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:57.308325Z","time spent":"605.731779ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T13:36:59.220847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002415548s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T13:36:59.220987Z","caller":"traceutil/trace.go:171","msg":"trace[217916654] linearizableReadLoop","detail":"{readStateIndex:1045; appliedIndex:1043; }","duration":"1.895086272s","start":"2026-06-19T13:36:57.325879Z","end":"2026-06-19T13:36:59.220965Z","steps":["trace[217916654] 'read index received' (duration: 588.055779ms)","trace[217916654] 'applied index is now lower than readState.Index' (duration: 1.307028423s)"],"step_count":2} {"level":"warn","ts":"2026-06-19T13:36:59.221085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.895184444s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T13:36:59.221202Z","caller":"traceutil/trace.go:171","msg":"trace[1347868963] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1011; }","duration":"1.895345389s","start":"2026-06-19T13:36:57.325841Z","end":"2026-06-19T13:36:59.221186Z","steps":["trace[1347868963] 'agreement among raft nodes before linearized reading' (duration: 1.895179695s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:59.221246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:57.325832Z","time spent":"1.89540208s","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-06-19T13:36:59.221319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.848319284s","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-06-19T13:36:59.221378Z","caller":"traceutil/trace.go:171","msg":"trace[1025623026] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1011; }","duration":"1.848426847s","start":"2026-06-19T13:36:57.372937Z","end":"2026-06-19T13:36:59.221364Z","steps":["trace[1025623026] 'agreement among raft nodes before linearized reading' (duration: 1.848172591s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:59.221424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:57.372914Z","time spent":"1.848496959s","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-19T13:36:59.221471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.842637015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-19T13:36:59.221434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.876873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T13:36:59.22153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074844851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T13:36:59.221545Z","caller":"traceutil/trace.go:171","msg":"trace[1969884845] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1011; }","duration":"1.074860541s","start":"2026-06-19T13:36:58.14668Z","end":"2026-06-19T13:36:59.221541Z","steps":["trace[1969884845] 'agreement among raft nodes before linearized reading' (duration: 1.07483215s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:59.221559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:58.146659Z","time spent":"1.074896432s","remote":"127.0.0.1:56012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"info","ts":"2026-06-19T13:36:59.22155Z","caller":"traceutil/trace.go:171","msg":"trace[1356547731] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1011; }","duration":"781.047387ms","start":"2026-06-19T13:36:58.440472Z","end":"2026-06-19T13:36:59.22152Z","steps":["trace[1356547731] 'agreement among raft nodes before linearized reading' (duration: 780.853662ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:59.221599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:58.440454Z","time spent":"781.134099ms","remote":"127.0.0.1:55832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-19T13:36:59.221647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.843377283s","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-06-19T13:36:59.221732Z","caller":"traceutil/trace.go:171","msg":"trace[1968307163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1011; }","duration":"1.843476506s","start":"2026-06-19T13:36:57.378241Z","end":"2026-06-19T13:36:59.221718Z","steps":["trace[1968307163] 'agreement among raft nodes before linearized reading' (duration: 1.843318023s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:59.221774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:57.378232Z","time spent":"1.843530627s","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-19T13:36:59.221501Z","caller":"traceutil/trace.go:171","msg":"trace[873728159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1011; }","duration":"1.842721837s","start":"2026-06-19T13:36:57.37877Z","end":"2026-06-19T13:36:59.221492Z","steps":["trace[873728159] 'agreement among raft nodes before linearized reading' (duration: 1.842601164s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:59.221831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:57.378748Z","time spent":"1.843077726s","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-19T13:36:59.221899Z","caller":"traceutil/trace.go:171","msg":"trace[713773228] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"1.580283628s","start":"2026-06-19T13:36:57.640694Z","end":"2026-06-19T13:36:59.220978Z","steps":["trace[713773228] 'process raft request' (duration: 577.624794ms)","trace[713773228] 'compare' (duration: 1.001598768s)"],"step_count":2} {"level":"warn","ts":"2026-06-19T13:36:59.221961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.046119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-19T13:36:59.222009Z","caller":"traceutil/trace.go:171","msg":"trace[1545312469] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1011; }","duration":"202.155302ms","start":"2026-06-19T13:36:59.01984Z","end":"2026-06-19T13:36:59.221995Z","steps":["trace[1545312469] 'agreement among raft nodes before linearized reading' (duration: 201.972628ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:59.221997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:57.640668Z","time spent":"1.581264132s","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T13:36:59.650485Z","caller":"traceutil/trace.go:171","msg":"trace[631039215] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"425.418657ms","start":"2026-06-19T13:36:59.225034Z","end":"2026-06-19T13:36:59.650453Z","steps":["trace[631039215] 'process raft request' (duration: 422.421964ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:59.650643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:59.225013Z","time spent":"425.552951ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T13:36:59.650687Z","caller":"traceutil/trace.go:171","msg":"trace[666605152] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"425.188753ms","start":"2026-06-19T13:36:59.225477Z","end":"2026-06-19T13:36:59.650665Z","steps":["trace[666605152] 'process raft request' (duration: 424.895205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:59.650823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:59.225463Z","time spent":"425.294166ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T13:36:59.650861Z","caller":"traceutil/trace.go:171","msg":"trace[2015760816] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"425.267294ms","start":"2026-06-19T13:36:59.225569Z","end":"2026-06-19T13:36:59.650836Z","steps":["trace[2015760816] 'process raft request' (duration: 425.033178ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:59.650928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:59.225564Z","time spent":"425.331496ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T13:36:59.651187Z","caller":"traceutil/trace.go:171","msg":"trace[1070353260] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"425.263145ms","start":"2026-06-19T13:36:59.225911Z","end":"2026-06-19T13:36:59.651174Z","steps":["trace[1070353260] 'process raft request' (duration: 424.885196ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:59.65124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:59.225893Z","time spent":"425.321497ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T13:36:59.651808Z","caller":"traceutil/trace.go:171","msg":"trace[1610249362] linearizableReadLoop","detail":"{readStateIndex:1050; appliedIndex:1045; }","duration":"314.19539ms","start":"2026-06-19T13:36:59.337595Z","end":"2026-06-19T13:36:59.65179Z","steps":["trace[1610249362] 'read index received' (duration: 309.873285ms)","trace[1610249362] 'applied index is now lower than readState.Index' (duration: 4.320885ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T13:36:59.652064Z","caller":"traceutil/trace.go:171","msg":"trace[513957890] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"356.168312ms","start":"2026-06-19T13:36:59.295863Z","end":"2026-06-19T13:36:59.652032Z","steps":["trace[513957890] 'process raft request' (duration: 355.166518ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:59.652485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:59.295832Z","time spent":"356.604382ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T13:36:59.65218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.576429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T13:36:59.652687Z","caller":"traceutil/trace.go:171","msg":"trace[876900364] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1016; }","duration":"315.141302ms","start":"2026-06-19T13:36:59.33753Z","end":"2026-06-19T13:36:59.652672Z","steps":["trace[876900364] 'agreement among raft nodes before linearized reading' (duration: 314.596339ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:36:59.652736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:36:59.33751Z","time spent":"315.214745ms","remote":"127.0.0.1:56210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-06-19T13:37:23.941871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360183572157981763,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-19T13:37:24.241072Z","caller":"traceutil/trace.go:171","msg":"trace[1787901237] linearizableReadLoop","detail":"{readStateIndex:1129; appliedIndex:1128; }","duration":"799.383662ms","start":"2026-06-19T13:37:23.441662Z","end":"2026-06-19T13:37:24.241045Z","steps":["trace[1787901237] 'read index received' (duration: 799.177007ms)","trace[1787901237] 'applied index is now lower than readState.Index' (duration: 205.565µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T13:37:24.241206Z","caller":"traceutil/trace.go:171","msg":"trace[202251354] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"937.2998ms","start":"2026-06-19T13:37:23.303861Z","end":"2026-06-19T13:37:24.24116Z","steps":["trace[202251354] 'process raft request' (duration: 937.038592ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:24.241287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.480172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T13:37:24.241339Z","caller":"traceutil/trace.go:171","msg":"trace[1943371876] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1090; }","duration":"792.574365ms","start":"2026-06-19T13:37:23.448752Z","end":"2026-06-19T13:37:24.241326Z","steps":["trace[1943371876] 'agreement among raft nodes before linearized reading' (duration: 792.484943ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:24.241374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:23.448732Z","time spent":"792.634196ms","remote":"127.0.0.1:37394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-06-19T13:37:24.241495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.492763ms","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-06-19T13:37:24.241542Z","caller":"traceutil/trace.go:171","msg":"trace[847981198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1090; }","duration":"461.559565ms","start":"2026-06-19T13:37:23.779971Z","end":"2026-06-19T13:37:24.241531Z","steps":["trace[847981198] 'agreement among raft nodes before linearized reading' (duration: 461.36841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:24.241441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:23.303837Z","time spent":"937.474295ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T13:37:24.241617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.703322ms","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-06-19T13:37:24.241709Z","caller":"traceutil/trace.go:171","msg":"trace[49364719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1090; }","duration":"466.827155ms","start":"2026-06-19T13:37:23.774859Z","end":"2026-06-19T13:37:24.241687Z","steps":["trace[49364719] 'agreement among raft nodes before linearized reading' (duration: 466.552818ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:24.241756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:23.774848Z","time spent":"466.899286ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-19T13:37:24.24158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:23.779961Z","time spent":"461.611886ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-19T13:37:24.241809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.687867ms","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-06-19T13:37:24.241666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.164928ms","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-06-19T13:37:24.241885Z","caller":"traceutil/trace.go:171","msg":"trace[352574163] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1090; }","duration":"461.81472ms","start":"2026-06-19T13:37:23.780058Z","end":"2026-06-19T13:37:24.241872Z","steps":["trace[352574163] 'agreement among raft nodes before linearized reading' (duration: 461.593475ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:24.241954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:23.780033Z","time spent":"461.910693ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-19T13:37:24.241955Z","caller":"traceutil/trace.go:171","msg":"trace[547489162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1090; }","duration":"468.438775ms","start":"2026-06-19T13:37:23.773448Z","end":"2026-06-19T13:37:24.241887Z","steps":["trace[547489162] 'agreement among raft nodes before linearized reading' (duration: 468.166238ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:24.242097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.596819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T13:37:24.242072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:23.773429Z","time spent":"468.622359ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-19T13:37:24.242129Z","caller":"traceutil/trace.go:171","msg":"trace[1793484371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1090; }","duration":"800.508731ms","start":"2026-06-19T13:37:23.441608Z","end":"2026-06-19T13:37:24.242116Z","steps":["trace[1793484371] 'agreement among raft nodes before linearized reading' (duration: 799.601409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:24.242161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:23.441539Z","time spent":"800.615113ms","remote":"127.0.0.1:55832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-19T13:37:24.242172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.301928ms","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-06-19T13:37:24.24224Z","caller":"traceutil/trace.go:171","msg":"trace[784079677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1090; }","duration":"218.37056ms","start":"2026-06-19T13:37:24.02385Z","end":"2026-06-19T13:37:24.242221Z","steps":["trace[784079677] 'agreement among raft nodes before linearized reading' (duration: 217.54477ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:37:25.670555Z","caller":"traceutil/trace.go:171","msg":"trace[1108261117] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"230.721336ms","start":"2026-06-19T13:37:25.439801Z","end":"2026-06-19T13:37:25.670522Z","steps":["trace[1108261117] 'read index received' (duration: 230.4596ms)","trace[1108261117] 'applied index is now lower than readState.Index' (duration: 260.256µs)"],"step_count":2} {"level":"warn","ts":"2026-06-19T13:37:25.670592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:25.290249Z","time spent":"380.334933ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-19T13:37:25.670743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.907651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T13:37:25.670789Z","caller":"traceutil/trace.go:171","msg":"trace[1564489001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1095; }","duration":"231.034794ms","start":"2026-06-19T13:37:25.439743Z","end":"2026-06-19T13:37:25.670777Z","steps":["trace[1564489001] 'agreement among raft nodes before linearized reading' (duration: 230.914291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:26.116287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.146779ms","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-06-19T13:37:26.116446Z","caller":"traceutil/trace.go:171","msg":"trace[1793283034] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1095; }","duration":"175.338694ms","start":"2026-06-19T13:37:25.941088Z","end":"2026-06-19T13:37:26.116426Z","steps":["trace[1793283034] 'range keys from in-memory index tree' (duration: 175.124189ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:37:26.651682Z","caller":"traceutil/trace.go:171","msg":"trace[295906917] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"979.868495ms","start":"2026-06-19T13:37:25.671787Z","end":"2026-06-19T13:37:26.651656Z","steps":["trace[295906917] 'process raft request' (duration: 979.687931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:26.651852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:25.671752Z","time spent":"980.017759ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T13:37:26.713899Z","caller":"traceutil/trace.go:171","msg":"trace[1901290188] linearizableReadLoop","detail":"{readStateIndex:1137; appliedIndex:1135; }","duration":"407.993517ms","start":"2026-06-19T13:37:26.305867Z","end":"2026-06-19T13:37:26.71386Z","steps":["trace[1901290188] 'read index received' (duration: 345.581591ms)","trace[1901290188] 'applied index is now lower than readState.Index' (duration: 62.410506ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T13:37:26.713933Z","caller":"traceutil/trace.go:171","msg":"trace[2049511407] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"467.503572ms","start":"2026-06-19T13:37:26.246388Z","end":"2026-06-19T13:37:26.713892Z","steps":["trace[2049511407] 'process raft request' (duration: 467.266966ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:37:26.714032Z","caller":"traceutil/trace.go:171","msg":"trace[436978283] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"272.948911ms","start":"2026-06-19T13:37:26.441072Z","end":"2026-06-19T13:37:26.714021Z","steps":["trace[436978283] 'process raft request' (duration: 272.743546ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:26.714113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:26.246366Z","time spent":"467.654686ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T13:37:26.714363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.129541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T13:37:26.714438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.992316ms","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-06-19T13:37:26.714455Z","caller":"traceutil/trace.go:171","msg":"trace[1881629830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"274.292725ms","start":"2026-06-19T13:37:26.44014Z","end":"2026-06-19T13:37:26.714433Z","steps":["trace[1881629830] 'agreement among raft nodes before linearized reading' (duration: 274.1133ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:26.714438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.054628ms","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-06-19T13:37:26.714431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.260398ms","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-06-19T13:37:26.714507Z","caller":"traceutil/trace.go:171","msg":"trace[452406418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1098; }","duration":"389.13648ms","start":"2026-06-19T13:37:26.32536Z","end":"2026-06-19T13:37:26.714496Z","steps":["trace[452406418] 'agreement among raft nodes before linearized reading' (duration: 388.968756ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:26.71455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:26.325351Z","time spent":"389.191521ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-19T13:37:26.714542Z","caller":"traceutil/trace.go:171","msg":"trace[617280548] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1098; }","duration":"390.452212ms","start":"2026-06-19T13:37:26.324066Z","end":"2026-06-19T13:37:26.714518Z","steps":["trace[617280548] 'agreement among raft nodes before linearized reading' (duration: 390.149154ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:26.714591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:26.324041Z","time spent":"390.541044ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-19T13:37:26.714481Z","caller":"traceutil/trace.go:171","msg":"trace[203168047] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1098; }","duration":"389.053977ms","start":"2026-06-19T13:37:26.325417Z","end":"2026-06-19T13:37:26.714471Z","steps":["trace[203168047] 'agreement among raft nodes before linearized reading' (duration: 388.897633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:26.714716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:26.325405Z","time spent":"389.303273ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-19T13:37:26.714468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.990215ms","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-06-19T13:37:26.714785Z","caller":"traceutil/trace.go:171","msg":"trace[56466437] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1098; }","duration":"389.322223ms","start":"2026-06-19T13:37:26.325452Z","end":"2026-06-19T13:37:26.714774Z","steps":["trace[56466437] 'agreement among raft nodes before linearized reading' (duration: 388.934194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:26.714826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:26.325448Z","time spent":"389.368136ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-19T13:37:26.714469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.583891ms","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-06-19T13:37:26.714905Z","caller":"traceutil/trace.go:171","msg":"trace[1123437170] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1098; }","duration":"409.095394ms","start":"2026-06-19T13:37:26.3058Z","end":"2026-06-19T13:37:26.714896Z","steps":["trace[1123437170] 'agreement among raft nodes before linearized reading' (duration: 408.502759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:26.714931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:26.305775Z","time spent":"409.149706ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-19T13:37:26.883371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.79818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T13:37:26.883556Z","caller":"traceutil/trace.go:171","msg":"trace[156616575] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"163.369748ms","start":"2026-06-19T13:37:26.720147Z","end":"2026-06-19T13:37:26.883517Z","steps":["trace[156616575] 'process raft request' (duration: 54.917091ms)","trace[156616575] 'compare' (duration: 107.475792ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T13:37:26.891985Z","caller":"traceutil/trace.go:171","msg":"trace[834185309] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"171.775636ms","start":"2026-06-19T13:37:26.720179Z","end":"2026-06-19T13:37:26.891955Z","steps":["trace[834185309] 'process raft request' (duration: 171.571541ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:37:26.892224Z","caller":"traceutil/trace.go:171","msg":"trace[1467334688] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"171.783805ms","start":"2026-06-19T13:37:26.720423Z","end":"2026-06-19T13:37:26.892206Z","steps":["trace[1467334688] 'process raft request' (duration: 171.486648ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:37:26.892542Z","caller":"traceutil/trace.go:171","msg":"trace[1868360805] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"172.039661ms","start":"2026-06-19T13:37:26.720492Z","end":"2026-06-19T13:37:26.892532Z","steps":["trace[1868360805] 'process raft request' (duration: 171.617471ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:37:26.892564Z","caller":"traceutil/trace.go:171","msg":"trace[1285296006] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"171.945359ms","start":"2026-06-19T13:37:26.720589Z","end":"2026-06-19T13:37:26.892534Z","steps":["trace[1285296006] 'process raft request' (duration: 171.798036ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:29.640734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.401696ms","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-06-19T13:37:29.640823Z","caller":"traceutil/trace.go:171","msg":"trace[638736621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1104; }","duration":"745.519379ms","start":"2026-06-19T13:37:28.895281Z","end":"2026-06-19T13:37:29.640801Z","steps":["trace[638736621] 'range keys from in-memory index tree' (duration: 745.243342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:29.640862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:28.895269Z","time spent":"745.5843ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-19T13:37:29.640826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.792499ms","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-06-19T13:37:29.640922Z","caller":"traceutil/trace.go:171","msg":"trace[318915559] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1104; }","duration":"752.975734ms","start":"2026-06-19T13:37:28.887925Z","end":"2026-06-19T13:37:29.6409Z","steps":["trace[318915559] 'range keys from in-memory index tree' (duration: 752.543183ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:29.641006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:28.887905Z","time spent":"753.059046ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-19T13:37:29.641248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.132999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-19T13:37:29.641355Z","caller":"traceutil/trace.go:171","msg":"trace[805327510] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1104; }","duration":"745.253012ms","start":"2026-06-19T13:37:28.896076Z","end":"2026-06-19T13:37:29.641329Z","steps":["trace[805327510] 'range keys from in-memory index tree' (duration: 744.571596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:29.641385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.373875ms","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-06-19T13:37:29.641416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.883578ms","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-06-19T13:37:29.64142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:28.896066Z","time spent":"745.330714ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-19T13:37:29.641444Z","caller":"traceutil/trace.go:171","msg":"trace[1766564957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1104; }","duration":"745.450947ms","start":"2026-06-19T13:37:28.895979Z","end":"2026-06-19T13:37:29.64143Z","steps":["trace[1766564957] 'range keys from in-memory index tree' (duration: 744.579776ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:37:29.641463Z","caller":"traceutil/trace.go:171","msg":"trace[1942170461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1104; }","duration":"745.97713ms","start":"2026-06-19T13:37:28.895473Z","end":"2026-06-19T13:37:29.64145Z","steps":["trace[1942170461] 'range keys from in-memory index tree' (duration: 745.013356ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:29.64149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:28.895971Z","time spent":"745.504668ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-19T13:37:29.641495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:28.895448Z","time spent":"746.039352ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-19T13:37:29.905358Z","caller":"traceutil/trace.go:171","msg":"trace[1735274286] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"259.16434ms","start":"2026-06-19T13:37:29.646173Z","end":"2026-06-19T13:37:29.905337Z","steps":["trace[1735274286] 'process raft request' (duration: 259.097978ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:37:29.905388Z","caller":"traceutil/trace.go:171","msg":"trace[1759788033] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"259.218531ms","start":"2026-06-19T13:37:29.646136Z","end":"2026-06-19T13:37:29.905354Z","steps":["trace[1759788033] 'process raft request' (duration: 258.949475ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:37:29.905639Z","caller":"traceutil/trace.go:171","msg":"trace[1761352480] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"259.093339ms","start":"2026-06-19T13:37:29.646535Z","end":"2026-06-19T13:37:29.905628Z","steps":["trace[1761352480] 'process raft request' (duration: 258.77602ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:37:29.905739Z","caller":"traceutil/trace.go:171","msg":"trace[667999045] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"259.181881ms","start":"2026-06-19T13:37:29.64654Z","end":"2026-06-19T13:37:29.905722Z","steps":["trace[667999045] 'process raft request' (duration: 258.975085ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:44.15237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.338505ms","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-06-19T13:37:44.152447Z","caller":"traceutil/trace.go:171","msg":"trace[383932945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1202; }","duration":"169.459987ms","start":"2026-06-19T13:37:43.982968Z","end":"2026-06-19T13:37:44.152428Z","steps":["trace[383932945] 'range keys from in-memory index tree' (duration: 169.18225ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:44.152639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.58253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-19T13:37:44.152657Z","caller":"traceutil/trace.go:171","msg":"trace[55411197] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1202; }","duration":"169.631451ms","start":"2026-06-19T13:37:43.98302Z","end":"2026-06-19T13:37:44.152652Z","steps":["trace[55411197] 'range keys from in-memory index tree' (duration: 169.511999ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:44.152759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.620331ms","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-06-19T13:37:44.152776Z","caller":"traceutil/trace.go:171","msg":"trace[1075222763] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1202; }","duration":"169.686913ms","start":"2026-06-19T13:37:43.983084Z","end":"2026-06-19T13:37:44.15277Z","steps":["trace[1075222763] 'range keys from in-memory index tree' (duration: 169.469827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:44.152869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.867843ms","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-06-19T13:37:44.152885Z","caller":"traceutil/trace.go:171","msg":"trace[369569991] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1202; }","duration":"168.897004ms","start":"2026-06-19T13:37:43.983983Z","end":"2026-06-19T13:37:44.15288Z","steps":["trace[369569991] 'range keys from in-memory index tree' (duration: 168.794731ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:45.939492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360183572157982028,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-19T13:37:46.440497Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360183572157982028,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-19T13:37:46.917205Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.626500464s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-19T13:37:46.91762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:45.290617Z","time spent":"1.626998166s","remote":"127.0.0.1:55870","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-06-19T13:37:46.917627Z","caller":"traceutil/trace.go:171","msg":"trace[1738487377] linearizableReadLoop","detail":"{readStateIndex:1253; appliedIndex:1252; }","duration":"1.478367664s","start":"2026-06-19T13:37:45.439212Z","end":"2026-06-19T13:37:46.917579Z","steps":["trace[1738487377] 'read index received' (duration: 1.47821652s)","trace[1738487377] 'applied index is now lower than readState.Index' (duration: 149.374µs)"],"step_count":2} {"level":"warn","ts":"2026-06-19T13:37:46.917849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.478586729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T13:37:46.917963Z","caller":"traceutil/trace.go:171","msg":"trace[1801207382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1207; }","duration":"1.478811725s","start":"2026-06-19T13:37:45.439126Z","end":"2026-06-19T13:37:46.917938Z","steps":["trace[1801207382] 'agreement among raft nodes before linearized reading' (duration: 1.47858184s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:46.918026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:45.439076Z","time spent":"1.478939578s","remote":"127.0.0.1:55832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-19T13:37:46.965429Z","caller":"traceutil/trace.go:171","msg":"trace[1896948321] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"125.777926ms","start":"2026-06-19T13:37:46.839618Z","end":"2026-06-19T13:37:46.965396Z","steps":["trace[1896948321] 'process raft request' (duration: 125.458908ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:46.965613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.571983ms","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-06-19T13:37:46.965728Z","caller":"traceutil/trace.go:171","msg":"trace[883818619] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1208; }","duration":"794.701207ms","start":"2026-06-19T13:37:46.170998Z","end":"2026-06-19T13:37:46.965699Z","steps":["trace[883818619] 'agreement among raft nodes before linearized reading' (duration: 794.392029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:46.965783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:46.170927Z","time spent":"794.84302ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-19T13:37:46.965909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.503842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-19T13:37:46.965984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14030368s","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-06-19T13:37:46.966013Z","caller":"traceutil/trace.go:171","msg":"trace[991002255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1208; }","duration":"1.140372422s","start":"2026-06-19T13:37:45.825632Z","end":"2026-06-19T13:37:46.966004Z","steps":["trace[991002255] 'agreement among raft nodes before linearized reading' (duration: 1.140285429s)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:37:46.966016Z","caller":"traceutil/trace.go:171","msg":"trace[2084615383] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1208; }","duration":"153.697178ms","start":"2026-06-19T13:37:46.812292Z","end":"2026-06-19T13:37:46.965989Z","steps":["trace[2084615383] 'agreement among raft nodes before linearized reading' (duration: 153.43278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:46.96604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:45.825604Z","time spent":"1.140428433s","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-19T13:37:46.966071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474433787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-19T13:37:46.966074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.886127ms","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-06-19T13:37:46.966113Z","caller":"traceutil/trace.go:171","msg":"trace[1809913087] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1208; }","duration":"1.474518749s","start":"2026-06-19T13:37:45.491574Z","end":"2026-06-19T13:37:46.966093Z","steps":["trace[1809913087] 'agreement among raft nodes before linearized reading' (duration: 1.474455048s)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:37:46.966129Z","caller":"traceutil/trace.go:171","msg":"trace[1115925910] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1208; }","duration":"795.989971ms","start":"2026-06-19T13:37:46.170123Z","end":"2026-06-19T13:37:46.966113Z","steps":["trace[1115925910] 'agreement among raft nodes before linearized reading' (duration: 795.854937ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:46.966153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:45.491543Z","time spent":"1.474603381s","remote":"127.0.0.1:37416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-06-19T13:37:46.966169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:46.170091Z","time spent":"796.067952ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-19T13:37:46.966477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.1089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-19T13:37:46.965934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.237155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-19T13:37:46.966499Z","caller":"traceutil/trace.go:171","msg":"trace[1437193215] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1208; }","duration":"366.181842ms","start":"2026-06-19T13:37:46.600309Z","end":"2026-06-19T13:37:46.966491Z","steps":["trace[1437193215] 'agreement among raft nodes before linearized reading' (duration: 366.147531ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:46.966521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:46.600284Z","time spent":"366.230973ms","remote":"127.0.0.1:53690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-06-19T13:37:46.966528Z","caller":"traceutil/trace.go:171","msg":"trace[1090896993] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1208; }","duration":"796.904742ms","start":"2026-06-19T13:37:46.169612Z","end":"2026-06-19T13:37:46.966516Z","steps":["trace[1090896993] 'agreement among raft nodes before linearized reading' (duration: 796.055001ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:46.966573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:46.169582Z","time spent":"796.972454ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-19T13:37:46.966934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.593679ms","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-06-19T13:37:46.967003Z","caller":"traceutil/trace.go:171","msg":"trace[203153089] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1208; }","duration":"795.679771ms","start":"2026-06-19T13:37:46.17131Z","end":"2026-06-19T13:37:46.966989Z","steps":["trace[203153089] 'agreement among raft nodes before linearized reading' (duration: 795.512927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:46.967034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:37:46.171298Z","time spent":"795.728553ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-19T13:37:59.181743Z","caller":"traceutil/trace.go:171","msg":"trace[173868252] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"141.361782ms","start":"2026-06-19T13:37:59.040357Z","end":"2026-06-19T13:37:59.181719Z","steps":["trace[173868252] 'read index received' (duration: 141.102365ms)","trace[173868252] 'applied index is now lower than readState.Index' (duration: 257.937µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T13:37:59.18186Z","caller":"traceutil/trace.go:171","msg":"trace[889053926] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"150.992381ms","start":"2026-06-19T13:37:59.030825Z","end":"2026-06-19T13:37:59.181817Z","steps":["trace[889053926] 'process raft request' (duration: 150.734095ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:59.18204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.652899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-19T13:37:59.182085Z","caller":"traceutil/trace.go:171","msg":"trace[1504555670] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1250; }","duration":"141.756271ms","start":"2026-06-19T13:37:59.040318Z","end":"2026-06-19T13:37:59.182075Z","steps":["trace[1504555670] 'agreement among raft nodes before linearized reading' (duration: 141.581647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:59.182125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.7007ms","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-06-19T13:37:59.182201Z","caller":"traceutil/trace.go:171","msg":"trace[1292883629] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1250; }","duration":"141.797462ms","start":"2026-06-19T13:37:59.040389Z","end":"2026-06-19T13:37:59.182187Z","steps":["trace[1292883629] 'agreement among raft nodes before linearized reading' (duration: 141.619158ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:37:59.182314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.996549ms","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-06-19T13:37:59.182311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.710357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-19T13:37:59.18233Z","caller":"traceutil/trace.go:171","msg":"trace[1138331149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1250; }","duration":"136.05995ms","start":"2026-06-19T13:37:59.046266Z","end":"2026-06-19T13:37:59.182326Z","steps":["trace[1138331149] 'agreement among raft nodes before linearized reading' (duration: 136.013759ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:37:59.182341Z","caller":"traceutil/trace.go:171","msg":"trace[970912876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1250; }","duration":"134.81667ms","start":"2026-06-19T13:37:59.047516Z","end":"2026-06-19T13:37:59.182332Z","steps":["trace[970912876] 'agreement among raft nodes before linearized reading' (duration: 134.724667ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:37:59.31863Z","caller":"traceutil/trace.go:171","msg":"trace[34761151] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"130.878413ms","start":"2026-06-19T13:37:59.187726Z","end":"2026-06-19T13:37:59.318605Z","steps":["trace[34761151] 'process raft request' (duration: 130.839242ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:37:59.31867Z","caller":"traceutil/trace.go:171","msg":"trace[346488109] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"131.957809ms","start":"2026-06-19T13:37:59.18669Z","end":"2026-06-19T13:37:59.318648Z","steps":["trace[346488109] 'process raft request' (duration: 54.11394ms)","trace[346488109] 'compare' (duration: 77.597023ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T13:37:59.318895Z","caller":"traceutil/trace.go:171","msg":"trace[789856501] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"132.00601ms","start":"2026-06-19T13:37:59.186877Z","end":"2026-06-19T13:37:59.318883Z","steps":["trace[789856501] 'process raft request' (duration: 131.642921ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:38:02.983374Z","caller":"traceutil/trace.go:171","msg":"trace[1114591541] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"176.738539ms","start":"2026-06-19T13:38:02.806612Z","end":"2026-06-19T13:38:02.983351Z","steps":["trace[1114591541] 'process raft request' (duration: 176.626386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:03.620599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.895333ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-19T13:38:03.620946Z","caller":"traceutil/trace.go:171","msg":"trace[59045194] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"625.921747ms","start":"2026-06-19T13:38:02.994999Z","end":"2026-06-19T13:38:03.620921Z","steps":["trace[59045194] 'process raft request' (duration: 625.734692ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:03.621031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:02.994967Z","time spent":"626.027179ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T13:38:03.621293Z","caller":"traceutil/trace.go:171","msg":"trace[1703799526] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"630.593492ms","start":"2026-06-19T13:38:02.990648Z","end":"2026-06-19T13:38:03.621241Z","steps":["trace[1703799526] 'process raft request' (duration: 167.887459ms)","trace[1703799526] 'compare' (duration: 461.550534ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T13:38:03.621529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:02.99064Z","time spent":"630.752555ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":915,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T13:38:03.697633Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360183572157982178,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-19T13:38:04.106436Z","caller":"traceutil/trace.go:171","msg":"trace[1715772333] linearizableReadLoop","detail":"{readStateIndex:1331; appliedIndex:1330; }","duration":"909.014549ms","start":"2026-06-19T13:38:03.197396Z","end":"2026-06-19T13:38:04.10641Z","steps":["trace[1715772333] 'read index received' (duration: 908.744852ms)","trace[1715772333] 'applied index is now lower than readState.Index' (duration: 268.277µs)"],"step_count":2} {"level":"info","ts":"2026-06-19T13:38:04.10653Z","caller":"traceutil/trace.go:171","msg":"trace[72343377] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"1.06446215s","start":"2026-06-19T13:38:03.042002Z","end":"2026-06-19T13:38:04.106464Z","steps":["trace[72343377] 'process raft request' (duration: 1.064219034s)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:04.106674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"909.261644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-19T13:38:04.106959Z","caller":"traceutil/trace.go:171","msg":"trace[129138329] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1282; }","duration":"909.608173ms","start":"2026-06-19T13:38:03.197334Z","end":"2026-06-19T13:38:04.106942Z","steps":["trace[129138329] 'agreement among raft nodes before linearized reading' (duration: 909.188833ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:04.107009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:03.197305Z","time spent":"909.691345ms","remote":"127.0.0.1:56084","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-06-19T13:38:04.106671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.068098ms","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-06-19T13:38:04.107069Z","caller":"traceutil/trace.go:171","msg":"trace[157529845] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1282; }","duration":"900.515058ms","start":"2026-06-19T13:38:03.206531Z","end":"2026-06-19T13:38:04.107046Z","steps":["trace[157529845] 'agreement among raft nodes before linearized reading' (duration: 900.051637ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:04.107132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:03.206511Z","time spent":"900.60467ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-19T13:38:04.106706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.59105ms","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-06-19T13:38:04.107264Z","caller":"traceutil/trace.go:171","msg":"trace[1416002423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1282; }","duration":"769.209525ms","start":"2026-06-19T13:38:03.338045Z","end":"2026-06-19T13:38:04.107254Z","steps":["trace[1416002423] 'agreement among raft nodes before linearized reading' (duration: 768.56592ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:04.107301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:03.33802Z","time spent":"769.272746ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-19T13:38:04.106727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.246817ms","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-06-19T13:38:04.107379Z","caller":"traceutil/trace.go:171","msg":"trace[1422037133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1282; }","duration":"767.968284ms","start":"2026-06-19T13:38:03.339401Z","end":"2026-06-19T13:38:04.107369Z","steps":["trace[1422037133] 'agreement among raft nodes before linearized reading' (duration: 767.183885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:04.107408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:03.33938Z","time spent":"768.019746ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-19T13:38:04.10746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:03.041985Z","time spent":"1.064837589s","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T13:38:04.106893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.705722ms","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-06-19T13:38:04.107696Z","caller":"traceutil/trace.go:171","msg":"trace[2100617257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1282; }","duration":"769.622226ms","start":"2026-06-19T13:38:03.338049Z","end":"2026-06-19T13:38:04.107672Z","steps":["trace[2100617257] 'agreement among raft nodes before linearized reading' (duration: 768.568919ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:04.108865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:03.338039Z","time spent":"770.807965ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-19T13:38:04.109176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.108971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T13:38:04.109301Z","caller":"traceutil/trace.go:171","msg":"trace[2847102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1282; }","duration":"668.254104ms","start":"2026-06-19T13:38:03.441002Z","end":"2026-06-19T13:38:04.109256Z","steps":["trace[2847102] 'agreement among raft nodes before linearized reading' (duration: 668.115841ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:04.1094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:03.440976Z","time spent":"668.388168ms","remote":"127.0.0.1:55832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-19T13:38:04.687341Z","caller":"traceutil/trace.go:171","msg":"trace[320905511] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"575.083406ms","start":"2026-06-19T13:38:04.112224Z","end":"2026-06-19T13:38:04.687307Z","steps":["trace[320905511] 'process raft request' (duration: 526.438181ms)","trace[320905511] 'compare' (duration: 48.328647ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T13:38:04.687468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:04.112209Z","time spent":"575.193559ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T13:38:04.687553Z","caller":"traceutil/trace.go:171","msg":"trace[1960029318] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"575.216961ms","start":"2026-06-19T13:38:04.112312Z","end":"2026-06-19T13:38:04.687529Z","steps":["trace[1960029318] 'process raft request' (duration: 574.930963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:04.687694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:04.11229Z","time spent":"575.330114ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T13:38:04.688229Z","caller":"traceutil/trace.go:171","msg":"trace[1134348218] linearizableReadLoop","detail":"{readStateIndex:1336; appliedIndex:1332; }","duration":"279.587667ms","start":"2026-06-19T13:38:04.408617Z","end":"2026-06-19T13:38:04.688205Z","steps":["trace[1134348218] 'read index received' (duration: 230.043609ms)","trace[1134348218] 'applied index is now lower than readState.Index' (duration: 49.542888ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T13:38:04.688322Z","caller":"traceutil/trace.go:171","msg":"trace[1859439657] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"573.221732ms","start":"2026-06-19T13:38:04.115083Z","end":"2026-06-19T13:38:04.688305Z","steps":["trace[1859439657] 'process raft request' (duration: 572.385251ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:38:04.688385Z","caller":"traceutil/trace.go:171","msg":"trace[259529146] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"573.19871ms","start":"2026-06-19T13:38:04.115172Z","end":"2026-06-19T13:38:04.688371Z","steps":["trace[259529146] 'process raft request' (duration: 572.944874ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:04.688393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:04.115074Z","time spent":"573.282793ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T13:38:04.688493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:04.115167Z","time spent":"573.249992ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T13:38:04.688649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.026848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T13:38:04.688686Z","caller":"traceutil/trace.go:171","msg":"trace[1635696472] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1287; }","duration":"280.153881ms","start":"2026-06-19T13:38:04.408519Z","end":"2026-06-19T13:38:04.688673Z","steps":["trace[1635696472] 'agreement among raft nodes before linearized reading' (duration: 280.094419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:04.688888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.87501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T13:38:04.688918Z","caller":"traceutil/trace.go:171","msg":"trace[1139723502] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1287; }","duration":"253.926961ms","start":"2026-06-19T13:38:04.434982Z","end":"2026-06-19T13:38:04.688909Z","steps":["trace[1139723502] 'agreement among raft nodes before linearized reading' (duration: 253.8785ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:05.885696Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360183572157982195,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-19T13:38:06.058306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.352828ms","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-06-19T13:38:06.058441Z","caller":"traceutil/trace.go:171","msg":"trace[1801802354] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1288; }","duration":"117.502851ms","start":"2026-06-19T13:38:05.940921Z","end":"2026-06-19T13:38:06.058424Z","steps":["trace[1801802354] 'range keys from in-memory index tree' (duration: 117.337907ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:38:06.058461Z","caller":"traceutil/trace.go:171","msg":"trace[1972234435] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1338; }","duration":"672.833738ms","start":"2026-06-19T13:38:05.3856Z","end":"2026-06-19T13:38:06.058434Z","steps":["trace[1972234435] 'read index received' (duration: 653.861318ms)","trace[1972234435] 'applied index is now lower than readState.Index' (duration: 18.97065ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T13:38:06.058573Z","caller":"traceutil/trace.go:171","msg":"trace[1997268906] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"730.000875ms","start":"2026-06-19T13:38:05.328557Z","end":"2026-06-19T13:38:06.058558Z","steps":["trace[1997268906] 'process raft request' (duration: 710.919262ms)","trace[1997268906] 'compare' (duration: 18.414146ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T13:38:06.058674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:05.328542Z","time spent":"730.057787ms","remote":"127.0.0.1:55870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T13:38:06.058699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.936659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T13:38:06.058731Z","caller":"traceutil/trace.go:171","msg":"trace[1489669987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1289; }","duration":"618.014411ms","start":"2026-06-19T13:38:05.440708Z","end":"2026-06-19T13:38:06.058722Z","steps":["trace[1489669987] 'agreement among raft nodes before linearized reading' (duration: 617.931889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:06.058763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:05.440688Z","time spent":"618.067612ms","remote":"127.0.0.1:55832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-19T13:38:06.058963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.362371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T13:38:06.058992Z","caller":"traceutil/trace.go:171","msg":"trace[1365523925] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1289; }","duration":"673.420622ms","start":"2026-06-19T13:38:05.385563Z","end":"2026-06-19T13:38:06.058983Z","steps":["trace[1365523925] 'agreement among raft nodes before linearized reading' (duration: 673.367721ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:06.059024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:05.385551Z","time spent":"673.465323ms","remote":"127.0.0.1:53466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-06-19T13:38:06.059046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.666338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T13:38:06.059093Z","caller":"traceutil/trace.go:171","msg":"trace[1893111062] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1289; }","duration":"413.75995ms","start":"2026-06-19T13:38:05.645321Z","end":"2026-06-19T13:38:06.059081Z","steps":["trace[1893111062] 'agreement among raft nodes before linearized reading' (duration: 413.653308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:06.05913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:05.645302Z","time spent":"413.816972ms","remote":"127.0.0.1:56156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-06-19T13:38:06.684488Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360183572157982204,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-19T13:38:06.743512Z","caller":"traceutil/trace.go:171","msg":"trace[959354726] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"627.564407ms","start":"2026-06-19T13:38:06.115934Z","end":"2026-06-19T13:38:06.743498Z","steps":["trace[959354726] 'process raft request' (duration: 603.990933ms)","trace[959354726] 'compare' (duration: 22.962939ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T13:38:06.743643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:06.115911Z","time spent":"627.658449ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T13:38:06.743293Z","caller":"traceutil/trace.go:171","msg":"trace[656355060] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"559.88582ms","start":"2026-06-19T13:38:06.183385Z","end":"2026-06-19T13:38:06.743271Z","steps":["trace[656355060] 'read index received' (duration: 536.47453ms)","trace[656355060] 'applied index is now lower than readState.Index' (duration: 23.41045ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T13:38:06.743934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.545968ms","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-06-19T13:38:06.743994Z","caller":"traceutil/trace.go:171","msg":"trace[547469798] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1290; }","duration":"560.607119ms","start":"2026-06-19T13:38:06.18337Z","end":"2026-06-19T13:38:06.743977Z","steps":["trace[547469798] 'agreement among raft nodes before linearized reading' (duration: 560.332672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:06.744051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:06.183305Z","time spent":"560.732972ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-19T13:38:06.994817Z","caller":"traceutil/trace.go:171","msg":"trace[913276284] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"245.8034ms","start":"2026-06-19T13:38:06.748987Z","end":"2026-06-19T13:38:06.99479Z","steps":["trace[913276284] 'process raft request' (duration: 245.722238ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:38:06.995032Z","caller":"traceutil/trace.go:171","msg":"trace[2058676542] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"246.160009ms","start":"2026-06-19T13:38:06.748861Z","end":"2026-06-19T13:38:06.995021Z","steps":["trace[2058676542] 'process raft request' (duration: 170.023053ms)","trace[2058676542] 'compare' (duration: 75.665654ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T13:38:06.995212Z","caller":"traceutil/trace.go:171","msg":"trace[1099482421] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"246.096347ms","start":"2026-06-19T13:38:06.749094Z","end":"2026-06-19T13:38:06.995191Z","steps":["trace[1099482421] 'process raft request' (duration: 246.019445ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:38:06.995299Z","caller":"traceutil/trace.go:171","msg":"trace[912430446] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"246.2128ms","start":"2026-06-19T13:38:06.749057Z","end":"2026-06-19T13:38:06.995269Z","steps":["trace[912430446] 'process raft request' (duration: 245.696157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:07.271561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.886179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T13:38:07.271916Z","caller":"traceutil/trace.go:171","msg":"trace[431484037] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1296; }","duration":"170.298069ms","start":"2026-06-19T13:38:07.1016Z","end":"2026-06-19T13:38:07.271898Z","steps":["trace[431484037] 'range keys from in-memory index tree' (duration: 169.761416ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:38:07.644807Z","caller":"traceutil/trace.go:171","msg":"trace[559933279] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"367.293839ms","start":"2026-06-19T13:38:07.277482Z","end":"2026-06-19T13:38:07.644776Z","steps":["trace[559933279] 'process raft request' (duration: 367.127025ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:07.644975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:07.277432Z","time spent":"367.464093ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-19T13:38:07.944071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.051984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-19T13:38:07.944249Z","caller":"traceutil/trace.go:171","msg":"trace[1411552122] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"291.338438ms","start":"2026-06-19T13:38:07.652893Z","end":"2026-06-19T13:38:07.944232Z","steps":["trace[1411552122] 'process raft request' (duration: 291.288016ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:38:07.944226Z","caller":"traceutil/trace.go:171","msg":"trace[484736880] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"594.748784ms","start":"2026-06-19T13:38:07.34944Z","end":"2026-06-19T13:38:07.944189Z","steps":["trace[484736880] 'process raft request' (duration: 428.509236ms)","trace[484736880] 'compare' (duration: 165.894239ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T13:38:07.944253Z","caller":"traceutil/trace.go:171","msg":"trace[937385724] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1347; }","duration":"503.561645ms","start":"2026-06-19T13:38:07.440668Z","end":"2026-06-19T13:38:07.944229Z","steps":["trace[937385724] 'read index received' (duration: 203.892921ms)","trace[937385724] 'applied index is now lower than readState.Index' (duration: 299.666513ms)"],"step_count":2} {"level":"warn","ts":"2026-06-19T13:38:07.944378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:07.349415Z","time spent":"594.883506ms","remote":"127.0.0.1:55902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-19T13:38:07.9444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.722339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-19T13:38:07.944426Z","caller":"traceutil/trace.go:171","msg":"trace[798958636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1300; }","duration":"503.811291ms","start":"2026-06-19T13:38:07.440607Z","end":"2026-06-19T13:38:07.944418Z","steps":["trace[798958636] 'agreement among raft nodes before linearized reading' (duration: 503.727929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:07.944452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:07.440586Z","time spent":"503.861432ms","remote":"127.0.0.1:55832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-19T13:38:07.957171Z","caller":"traceutil/trace.go:171","msg":"trace[909030499] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"127.519679ms","start":"2026-06-19T13:38:07.829601Z","end":"2026-06-19T13:38:07.95712Z","steps":["trace[909030499] 'process raft request' (duration: 127.426067ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:07.957223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.454243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-06-19T13:38:07.95725Z","caller":"traceutil/trace.go:171","msg":"trace[1098745412] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1301; }","duration":"304.523214ms","start":"2026-06-19T13:38:07.652718Z","end":"2026-06-19T13:38:07.957242Z","steps":["trace[1098745412] 'agreement among raft nodes before linearized reading' (duration: 304.415192ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:07.957268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:07.652702Z","time spent":"304.560606ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-19T13:38:07.95733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.234976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-19T13:38:07.957348Z","caller":"traceutil/trace.go:171","msg":"trace[22008828] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1301; }","duration":"461.306679ms","start":"2026-06-19T13:38:07.496037Z","end":"2026-06-19T13:38:07.957344Z","steps":["trace[22008828] 'agreement among raft nodes before linearized reading' (duration: 461.272688ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:07.95736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:07.496013Z","time spent":"461.343569ms","remote":"127.0.0.1:56008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-06-19T13:38:17.160674Z","caller":"traceutil/trace.go:171","msg":"trace[1420793889] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"733.961284ms","start":"2026-06-19T13:38:16.42662Z","end":"2026-06-19T13:38:17.160581Z","steps":["trace[1420793889] 'process raft request' (duration: 733.81535ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:17.160935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:16.426601Z","time spent":"734.19392ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-19T13:38:17.189794Z","caller":"traceutil/trace.go:171","msg":"trace[221666494] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1416; }","duration":"366.941577ms","start":"2026-06-19T13:38:16.822824Z","end":"2026-06-19T13:38:17.189766Z","steps":["trace[221666494] 'read index received' (duration: 337.69884ms)","trace[221666494] 'applied index is now lower than readState.Index' (duration: 29.241457ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T13:38:17.189925Z","caller":"traceutil/trace.go:171","msg":"trace[395233620] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"412.166428ms","start":"2026-06-19T13:38:16.777746Z","end":"2026-06-19T13:38:17.189912Z","steps":["trace[395233620] 'process raft request' (duration: 411.868131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:17.190343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.505491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-19T13:38:17.190432Z","caller":"traceutil/trace.go:171","msg":"trace[1764618709] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1366; }","duration":"367.651005ms","start":"2026-06-19T13:38:16.822762Z","end":"2026-06-19T13:38:17.190413Z","steps":["trace[1764618709] 'agreement among raft nodes before linearized reading' (duration: 367.359238ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:17.190474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:16.822739Z","time spent":"367.726567ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-19T13:38:17.190497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.618173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-06-19T13:38:17.190575Z","caller":"traceutil/trace.go:171","msg":"trace[683750648] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1366; }","duration":"144.745366ms","start":"2026-06-19T13:38:17.045817Z","end":"2026-06-19T13:38:17.190563Z","steps":["trace[683750648] 'agreement among raft nodes before linearized reading' (duration: 144.5131ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:17.190807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.166457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-19T13:38:17.190053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-19T13:38:16.777717Z","time spent":"412.23729ms","remote":"127.0.0.1:56084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-19T13:38:17.190875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.193541ms","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-06-19T13:38:17.191347Z","caller":"traceutil/trace.go:171","msg":"trace[826249836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1366; }","duration":"144.651543ms","start":"2026-06-19T13:38:17.046645Z","end":"2026-06-19T13:38:17.191297Z","steps":["trace[826249836] 'agreement among raft nodes before linearized reading' (duration: 144.15325ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:17.190962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.854733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-19T13:38:17.191427Z","caller":"traceutil/trace.go:171","msg":"trace[599396554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1366; }","duration":"146.335135ms","start":"2026-06-19T13:38:17.045079Z","end":"2026-06-19T13:38:17.191414Z","steps":["trace[599396554] 'agreement among raft nodes before linearized reading' (duration: 145.831722ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:38:17.191689Z","caller":"traceutil/trace.go:171","msg":"trace[1494950359] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1366; }","duration":"147.560936ms","start":"2026-06-19T13:38:17.043551Z","end":"2026-06-19T13:38:17.191112Z","steps":["trace[1494950359] 'agreement among raft nodes before linearized reading' (duration: 146.704145ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:38:17.451598Z","caller":"traceutil/trace.go:171","msg":"trace[1893277710] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"255.343609ms","start":"2026-06-19T13:38:17.19622Z","end":"2026-06-19T13:38:17.451564Z","steps":["trace[1893277710] 'process raft request' (duration: 252.393644ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:38:17.453905Z","caller":"traceutil/trace.go:171","msg":"trace[1478648957] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1418; }","duration":"201.261633ms","start":"2026-06-19T13:38:17.252624Z","end":"2026-06-19T13:38:17.453886Z","steps":["trace[1478648957] 'read index received' (duration: 195.978039ms)","trace[1478648957] 'applied index is now lower than readState.Index' (duration: 5.282774ms)"],"step_count":2} {"level":"info","ts":"2026-06-19T13:38:17.453922Z","caller":"traceutil/trace.go:171","msg":"trace[1052716155] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"256.478618ms","start":"2026-06-19T13:38:17.197404Z","end":"2026-06-19T13:38:17.453883Z","steps":["trace[1052716155] 'process raft request' (duration: 256.293333ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:38:17.453922Z","caller":"traceutil/trace.go:171","msg":"trace[1798188098] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"254.798585ms","start":"2026-06-19T13:38:17.199091Z","end":"2026-06-19T13:38:17.45389Z","steps":["trace[1798188098] 'process raft request' (duration: 254.689792ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:38:17.454027Z","caller":"traceutil/trace.go:171","msg":"trace[500436289] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"253.627606ms","start":"2026-06-19T13:38:17.20039Z","end":"2026-06-19T13:38:17.454017Z","steps":["trace[500436289] 'process raft request' (duration: 253.427811ms)"],"step_count":1} {"level":"info","ts":"2026-06-19T13:38:17.454248Z","caller":"traceutil/trace.go:171","msg":"trace[109567023] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"253.514343ms","start":"2026-06-19T13:38:17.200705Z","end":"2026-06-19T13:38:17.454219Z","steps":["trace[109567023] 'process raft request' (duration: 253.146724ms)"],"step_count":1} {"level":"warn","ts":"2026-06-19T13:38:17.455021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.365831ms","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:530"} {"level":"info","ts":"2026-06-19T13:38:17.455113Z","caller":"traceutil/trace.go:171","msg":"trace[1964637719] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1371; }","duration":"202.535685ms","start":"2026-06-19T13:38:17.252557Z","end":"2026-06-19T13:38:17.455093Z","steps":["trace[1964637719] 'agreement among raft nodes before linearized reading' (duration: 201.629812ms)"],"step_count":1}