{"level":"warn","ts":"2026-04-18T22:12:02.038923Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T22:12:02.039091Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.44:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.44:2380","--initial-cluster=instance=https://199.204.45.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.44: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-04-18T22:12:02.039176Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-18T22:12:02.03919Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-04-18T22:12:02.039205Z","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-04-18T22:12:02.039827Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"]} {"level":"info","ts":"2026-04-18T22:12:02.039924Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.44: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-04-18T22:12:02.043353Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.156083ms"} {"level":"info","ts":"2026-04-18T22:12:02.050228Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a0a01fc7fbf6be45","cluster-id":"92fa3fee501eaaec"} {"level":"info","ts":"2026-04-18T22:12:02.050313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=()"} {"level":"info","ts":"2026-04-18T22:12:02.050334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 0"} {"level":"info","ts":"2026-04-18T22:12:02.050403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a0a01fc7fbf6be45 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-18T22:12:02.050472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became follower at term 1"} {"level":"info","ts":"2026-04-18T22:12:02.050552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"warn","ts":"2026-04-18T22:12:02.05916Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-18T22:12:02.064585Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-18T22:12:02.065838Z","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-04-18T22:12:02.067756Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a0a01fc7fbf6be45","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-18T22:12:02.067854Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a0a01fc7fbf6be45","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-18T22:12:02.067975Z","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-04-18T22:12:02.068232Z","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-04-18T22:12:02.068262Z","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-04-18T22:12:02.069254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 switched to configuration voters=(11574285986128379461)"} {"level":"info","ts":"2026-04-18T22:12:02.069375Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","added-peer-id":"a0a01fc7fbf6be45","added-peer-peer-urls":["https://199.204.45.44:2380"]} {"level":"info","ts":"2026-04-18T22:12:02.072694Z","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-04-18T22:12:02.072854Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-04-18T22:12:02.072889Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.44:2380"} {"level":"info","ts":"2026-04-18T22:12:02.073481Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a0a01fc7fbf6be45","initial-advertise-peer-urls":["https://199.204.45.44:2380"],"listen-peer-urls":["https://199.204.45.44:2380"],"advertise-client-urls":["https://199.204.45.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-18T22:12:02.073511Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-18T22:12:02.650915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 is starting a new election at term 1"} {"level":"info","ts":"2026-04-18T22:12:02.650985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-18T22:12:02.651054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgPreVoteResp from a0a01fc7fbf6be45 at term 1"} {"level":"info","ts":"2026-04-18T22:12:02.651075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became candidate at term 2"} {"level":"info","ts":"2026-04-18T22:12:02.651086Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 received MsgVoteResp from a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-04-18T22:12:02.651102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a0a01fc7fbf6be45 became leader at term 2"} {"level":"info","ts":"2026-04-18T22:12:02.651122Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a0a01fc7fbf6be45 elected leader a0a01fc7fbf6be45 at term 2"} {"level":"info","ts":"2026-04-18T22:12:02.652386Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:12:02.653994Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:12:02.654013Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a0a01fc7fbf6be45","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.44:2379]}","request-path":"/0/members/a0a01fc7fbf6be45/attributes","cluster-id":"92fa3fee501eaaec","publish-timeout":"7s"} {"level":"info","ts":"2026-04-18T22:12:02.654062Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-18T22:12:02.654573Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-18T22:12:02.654636Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-18T22:12:02.65551Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"92fa3fee501eaaec","local-member-id":"a0a01fc7fbf6be45","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:12:02.655673Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:12:02.655786Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-18T22:12:02.655819Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.44:2379"} {"level":"info","ts":"2026-04-18T22:12:02.65634Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-18T22:12:08.124733Z","caller":"traceutil/trace.go:171","msg":"trace[2139277099] linearizableReadLoop","detail":"{readStateIndex:257; appliedIndex:256; }","duration":"195.050888ms","start":"2026-04-18T22:12:07.929658Z","end":"2026-04-18T22:12:08.124709Z","steps":["trace[2139277099] 'read index received' (duration: 18.906238ms)","trace[2139277099] 'applied index is now lower than readState.Index' (duration: 176.14335ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:08.124889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.207874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:08.124931Z","caller":"traceutil/trace.go:171","msg":"trace[865099480] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"202.173366ms","start":"2026-04-18T22:12:07.922722Z","end":"2026-04-18T22:12:08.124895Z","steps":["trace[865099480] 'process raft request' (duration: 201.865724ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:08.124965Z","caller":"traceutil/trace.go:171","msg":"trace[1144424330] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:249; }","duration":"195.326129ms","start":"2026-04-18T22:12:07.929626Z","end":"2026-04-18T22:12:08.124952Z","steps":["trace[1144424330] 'agreement among raft nodes before linearized reading' (duration: 195.211984ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:08.430583Z","caller":"traceutil/trace.go:171","msg":"trace[1034900320] linearizableReadLoop","detail":"{readStateIndex:262; appliedIndex:260; }","duration":"122.181995ms","start":"2026-04-18T22:12:08.308373Z","end":"2026-04-18T22:12:08.430555Z","steps":["trace[1034900320] 'read index received' (duration: 120.658196ms)","trace[1034900320] 'applied index is now lower than readState.Index' (duration: 1.522609ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:08.430646Z","caller":"traceutil/trace.go:171","msg":"trace[1905495040] transaction","detail":"{read_only:false; number_of_response:0; response_revision:252; }","duration":"136.279316ms","start":"2026-04-18T22:12:08.294334Z","end":"2026-04-18T22:12:08.430613Z","steps":["trace[1905495040] 'process raft request' (duration: 134.762246ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:08.430682Z","caller":"traceutil/trace.go:171","msg":"trace[1320628080] transaction","detail":"{read_only:false; number_of_response:0; response_revision:252; }","duration":"135.494415ms","start":"2026-04-18T22:12:08.295153Z","end":"2026-04-18T22:12:08.430647Z","steps":["trace[1320628080] 'process raft request' (duration: 135.36957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:08.430774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.93196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-04-18T22:12:08.430813Z","caller":"traceutil/trace.go:171","msg":"trace[1542953124] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:252; }","duration":"138.013773ms","start":"2026-04-18T22:12:08.292787Z","end":"2026-04-18T22:12:08.430801Z","steps":["trace[1542953124] 'agreement among raft nodes before linearized reading' (duration: 137.913769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:08.43093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.152777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:08.430974Z","caller":"traceutil/trace.go:171","msg":"trace[1698982705] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:252; }","duration":"119.23431ms","start":"2026-04-18T22:12:08.311723Z","end":"2026-04-18T22:12:08.430957Z","steps":["trace[1698982705] 'agreement among raft nodes before linearized reading' (duration: 119.146527ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:08.552339Z","caller":"traceutil/trace.go:171","msg":"trace[156786304] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"115.127299ms","start":"2026-04-18T22:12:08.43718Z","end":"2026-04-18T22:12:08.552307Z","steps":["trace[156786304] 'process raft request' (duration: 114.296717ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:08.552487Z","caller":"traceutil/trace.go:171","msg":"trace[140322184] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"104.960372ms","start":"2026-04-18T22:12:08.447463Z","end":"2026-04-18T22:12:08.552423Z","steps":["trace[140322184] 'process raft request' (duration: 104.798536ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:08.574114Z","caller":"traceutil/trace.go:171","msg":"trace[2098562617] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"110.181316ms","start":"2026-04-18T22:12:08.463898Z","end":"2026-04-18T22:12:08.574079Z","steps":["trace[2098562617] 'process raft request' (duration: 110.063982ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:08.743489Z","caller":"traceutil/trace.go:171","msg":"trace[372360086] linearizableReadLoop","detail":"{readStateIndex:269; appliedIndex:268; }","duration":"132.7968ms","start":"2026-04-18T22:12:08.610661Z","end":"2026-04-18T22:12:08.743458Z","steps":["trace[372360086] 'read index received' (duration: 54.355035ms)","trace[372360086] 'applied index is now lower than readState.Index' (duration: 78.440665ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:08.743625Z","caller":"traceutil/trace.go:171","msg":"trace[2126519193] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"134.09719ms","start":"2026-04-18T22:12:08.609516Z","end":"2026-04-18T22:12:08.743613Z","steps":["trace[2126519193] 'process raft request' (duration: 55.53052ms)","trace[2126519193] 'compare' (duration: 78.258908ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:08.743911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.224096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-18T22:12:08.743988Z","caller":"traceutil/trace.go:171","msg":"trace[978600568] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:259; }","duration":"133.32276ms","start":"2026-04-18T22:12:08.610647Z","end":"2026-04-18T22:12:08.74397Z","steps":["trace[978600568] 'agreement among raft nodes before linearized reading' (duration: 133.210715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:08.978688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.497672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:12:08.978805Z","caller":"traceutil/trace.go:171","msg":"trace[1499103427] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"141.314802ms","start":"2026-04-18T22:12:08.837471Z","end":"2026-04-18T22:12:08.978786Z","steps":["trace[1499103427] 'process raft request' (duration: 13.166045ms)","trace[1499103427] 'compare' (duration: 127.363707ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:09.010357Z","caller":"traceutil/trace.go:171","msg":"trace[204639140] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"167.823259ms","start":"2026-04-18T22:12:08.842518Z","end":"2026-04-18T22:12:09.010342Z","steps":["trace[204639140] 'process raft request' (duration: 167.400632ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:09.165723Z","caller":"traceutil/trace.go:171","msg":"trace[2116205285] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"148.204838ms","start":"2026-04-18T22:12:09.017489Z","end":"2026-04-18T22:12:09.165694Z","steps":["trace[2116205285] 'process raft request' (duration: 83.035859ms)","trace[2116205285] 'compare' (duration: 65.047884ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:09.166417Z","caller":"traceutil/trace.go:171","msg":"trace[1364448216] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"146.805232ms","start":"2026-04-18T22:12:09.019598Z","end":"2026-04-18T22:12:09.166403Z","steps":["trace[1364448216] 'process raft request' (duration: 146.714449ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:09.359324Z","caller":"traceutil/trace.go:171","msg":"trace[1050469849] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"180.199119ms","start":"2026-04-18T22:12:09.179098Z","end":"2026-04-18T22:12:09.359297Z","steps":["trace[1050469849] 'process raft request' (duration: 139.639142ms)","trace[1050469849] 'compare' (duration: 40.449232ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:09.359482Z","caller":"traceutil/trace.go:171","msg":"trace[1639082073] linearizableReadLoop","detail":"{readStateIndex:278; appliedIndex:277; }","duration":"132.885277ms","start":"2026-04-18T22:12:09.226586Z","end":"2026-04-18T22:12:09.359472Z","steps":["trace[1639082073] 'read index received' (duration: 92.078461ms)","trace[1639082073] 'applied index is now lower than readState.Index' (duration: 40.805846ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:09.359555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.956481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:09.359635Z","caller":"traceutil/trace.go:171","msg":"trace[53066732] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:0; response_revision:268; }","duration":"133.057065ms","start":"2026-04-18T22:12:09.226546Z","end":"2026-04-18T22:12:09.359603Z","steps":["trace[53066732] 'agreement among raft nodes before linearized reading' (duration: 132.966941ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:10.334502Z","caller":"traceutil/trace.go:171","msg":"trace[1657507310] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:293; }","duration":"155.878553ms","start":"2026-04-18T22:12:10.178588Z","end":"2026-04-18T22:12:10.334466Z","steps":["trace[1657507310] 'read index received' (duration: 145.486986ms)","trace[1657507310] 'applied index is now lower than readState.Index' (duration: 10.390947ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:10.334593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.985287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:10.334736Z","caller":"traceutil/trace.go:171","msg":"trace[2061213116] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:0; response_revision:284; }","duration":"156.176304ms","start":"2026-04-18T22:12:10.178551Z","end":"2026-04-18T22:12:10.334728Z","steps":["trace[2061213116] 'agreement among raft nodes before linearized reading' (duration: 155.996827ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:10.334952Z","caller":"traceutil/trace.go:171","msg":"trace[671371095] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"168.593251ms","start":"2026-04-18T22:12:10.16631Z","end":"2026-04-18T22:12:10.334903Z","steps":["trace[671371095] 'process raft request' (duration: 157.748017ms)","trace[671371095] 'compare' (duration: 10.327484ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:12.369107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.895721ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-18T22:12:12.369238Z","caller":"traceutil/trace.go:171","msg":"trace[175921919] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"339.512116ms","start":"2026-04-18T22:12:12.0297Z","end":"2026-04-18T22:12:12.369212Z","steps":["trace[175921919] 'process raft request' (duration: 51.443718ms)","trace[175921919] 'compare' (duration: 287.760056ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:12.369338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:12.029674Z","time spent":"339.62593ms","remote":"127.0.0.1:50850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":205,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:12:12.498556Z","caller":"traceutil/trace.go:171","msg":"trace[1530747855] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:307; }","duration":"123.086988ms","start":"2026-04-18T22:12:12.375437Z","end":"2026-04-18T22:12:12.498524Z","steps":["trace[1530747855] 'read index received' (duration: 112.619557ms)","trace[1530747855] 'applied index is now lower than readState.Index' (duration: 10.466311ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:12.498668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.216152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-18T22:12:12.498675Z","caller":"traceutil/trace.go:171","msg":"trace[104066124] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"124.348897ms","start":"2026-04-18T22:12:12.374309Z","end":"2026-04-18T22:12:12.498658Z","steps":["trace[104066124] 'process raft request' (duration: 113.788953ms)","trace[104066124] 'compare' (duration: 10.319385ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:12.498701Z","caller":"traceutil/trace.go:171","msg":"trace[1757402296] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:298; }","duration":"123.282365ms","start":"2026-04-18T22:12:12.375407Z","end":"2026-04-18T22:12:12.49869Z","steps":["trace[1757402296] 'agreement among raft nodes before linearized reading' (duration: 123.206362ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:12.682682Z","caller":"traceutil/trace.go:171","msg":"trace[1094091939] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:309; }","duration":"111.141068ms","start":"2026-04-18T22:12:12.571512Z","end":"2026-04-18T22:12:12.682653Z","steps":["trace[1094091939] 'read index received' (duration: 37.118675ms)","trace[1094091939] 'applied index is now lower than readState.Index' (duration: 74.021213ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:12.682941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.400149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-04-18T22:12:12.683046Z","caller":"traceutil/trace.go:171","msg":"trace[90138339] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:300; }","duration":"111.548864ms","start":"2026-04-18T22:12:12.571484Z","end":"2026-04-18T22:12:12.683033Z","steps":["trace[90138339] 'agreement among raft nodes before linearized reading' (duration: 111.376468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:12.6831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.666638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-04-18T22:12:12.683207Z","caller":"traceutil/trace.go:171","msg":"trace[1322836607] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:300; }","duration":"100.826144ms","start":"2026-04-18T22:12:12.582356Z","end":"2026-04-18T22:12:12.683182Z","steps":["trace[1322836607] 'agreement among raft nodes before linearized reading' (duration: 100.584815ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:12.682839Z","caller":"traceutil/trace.go:171","msg":"trace[349494399] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"140.414467ms","start":"2026-04-18T22:12:12.542382Z","end":"2026-04-18T22:12:12.682797Z","steps":["trace[349494399] 'process raft request' (duration: 66.271799ms)","trace[349494399] 'compare' (duration: 73.859337ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:12.934926Z","caller":"traceutil/trace.go:171","msg":"trace[1985293499] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:312; }","duration":"156.879173ms","start":"2026-04-18T22:12:12.778018Z","end":"2026-04-18T22:12:12.934898Z","steps":["trace[1985293499] 'read index received' (duration: 85.401789ms)","trace[1985293499] 'applied index is now lower than readState.Index' (duration: 71.476304ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:12.935039Z","caller":"traceutil/trace.go:171","msg":"trace[1813702554] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"163.107928ms","start":"2026-04-18T22:12:12.771883Z","end":"2026-04-18T22:12:12.934991Z","steps":["trace[1813702554] 'process raft request' (duration: 91.613993ms)","trace[1813702554] 'compare' (duration: 71.273526ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:12.935209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.170154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-18T22:12:12.935286Z","caller":"traceutil/trace.go:171","msg":"trace[814986242] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:303; }","duration":"157.278699ms","start":"2026-04-18T22:12:12.777967Z","end":"2026-04-18T22:12:12.935246Z","steps":["trace[814986242] 'agreement among raft nodes before linearized reading' (duration: 157.140223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:12.935362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.159103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-18T22:12:12.935472Z","caller":"traceutil/trace.go:171","msg":"trace[1485893009] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:303; }","duration":"106.362172ms","start":"2026-04-18T22:12:12.829085Z","end":"2026-04-18T22:12:12.935447Z","steps":["trace[1485893009] 'agreement among raft nodes before linearized reading' (duration: 106.106742ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.195152Z","caller":"traceutil/trace.go:171","msg":"trace[1604501239] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"251.685157ms","start":"2026-04-18T22:12:12.943438Z","end":"2026-04-18T22:12:13.195123Z","steps":["trace[1604501239] 'process raft request' (duration: 235.365647ms)","trace[1604501239] 'compare' (duration: 15.954886ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:13.195322Z","caller":"traceutil/trace.go:171","msg":"trace[966101160] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"250.692339ms","start":"2026-04-18T22:12:12.944593Z","end":"2026-04-18T22:12:13.195285Z","steps":["trace[966101160] 'process raft request' (duration: 250.46165ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.195441Z","caller":"traceutil/trace.go:171","msg":"trace[1592044498] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"250.46038ms","start":"2026-04-18T22:12:12.944948Z","end":"2026-04-18T22:12:13.195408Z","steps":["trace[1592044498] 'process raft request' (duration: 250.201269ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.195728Z","caller":"traceutil/trace.go:171","msg":"trace[995113111] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:314; }","duration":"217.018928ms","start":"2026-04-18T22:12:12.978688Z","end":"2026-04-18T22:12:13.195707Z","steps":["trace[995113111] 'read index received' (duration: 200.095464ms)","trace[995113111] 'applied index is now lower than readState.Index' (duration: 16.922174ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:13.195804Z","caller":"traceutil/trace.go:171","msg":"trace[768387973] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"220.976663ms","start":"2026-04-18T22:12:12.974808Z","end":"2026-04-18T22:12:13.195785Z","steps":["trace[768387973] 'process raft request' (duration: 220.419061ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:13.195978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.279108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-04-18T22:12:13.196037Z","caller":"traceutil/trace.go:171","msg":"trace[1478798560] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:308; }","duration":"217.389153ms","start":"2026-04-18T22:12:12.978638Z","end":"2026-04-18T22:12:13.196027Z","steps":["trace[1478798560] 'agreement among raft nodes before linearized reading' (duration: 217.250747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:13.196233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.224094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-04-18T22:12:13.196332Z","caller":"traceutil/trace.go:171","msg":"trace[2035479133] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:308; }","duration":"167.335498ms","start":"2026-04-18T22:12:13.028978Z","end":"2026-04-18T22:12:13.196314Z","steps":["trace[2035479133] 'agreement among raft nodes before linearized reading' (duration: 167.140221ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:13.196599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.835686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-18T22:12:13.196651Z","caller":"traceutil/trace.go:171","msg":"trace[2042047171] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:308; }","duration":"116.92393ms","start":"2026-04-18T22:12:13.079714Z","end":"2026-04-18T22:12:13.196637Z","steps":["trace[2042047171] 'agreement among raft nodes before linearized reading' (duration: 116.847097ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.402865Z","caller":"traceutil/trace.go:171","msg":"trace[1300513066] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"201.829772ms","start":"2026-04-18T22:12:13.201013Z","end":"2026-04-18T22:12:13.402843Z","steps":["trace[1300513066] 'process raft request' (duration: 105.166878ms)","trace[1300513066] 'compare' (duration: 96.223967ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:13.402897Z","caller":"traceutil/trace.go:171","msg":"trace[157200722] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"200.99562ms","start":"2026-04-18T22:12:13.201884Z","end":"2026-04-18T22:12:13.40288Z","steps":["trace[157200722] 'process raft request' (duration: 200.701138ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.402921Z","caller":"traceutil/trace.go:171","msg":"trace[1490519027] transaction","detail":"{read_only:false; response_revision:315; number_of_response:1; }","duration":"196.68572ms","start":"2026-04-18T22:12:13.206209Z","end":"2026-04-18T22:12:13.402894Z","steps":["trace[1490519027] 'process raft request' (duration: 196.526864ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.402872Z","caller":"traceutil/trace.go:171","msg":"trace[1378943413] linearizableReadLoop","detail":"{readStateIndex:328; appliedIndex:319; }","duration":"173.326254ms","start":"2026-04-18T22:12:13.229522Z","end":"2026-04-18T22:12:13.402849Z","steps":["trace[1378943413] 'read index received' (duration: 76.632928ms)","trace[1378943413] 'applied index is now lower than readState.Index' (duration: 96.692646ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:13.403044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.155875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4196"} {"level":"info","ts":"2026-04-18T22:12:13.403088Z","caller":"traceutil/trace.go:171","msg":"trace[552333962] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:318; }","duration":"202.280461ms","start":"2026-04-18T22:12:13.200796Z","end":"2026-04-18T22:12:13.403076Z","steps":["trace[552333962] 'agreement among raft nodes before linearized reading' (duration: 202.119404ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.403132Z","caller":"traceutil/trace.go:171","msg":"trace[130922878] transaction","detail":"{read_only:false; response_revision:317; number_of_response:1; }","duration":"196.290076ms","start":"2026-04-18T22:12:13.206818Z","end":"2026-04-18T22:12:13.403108Z","steps":["trace[130922878] 'process raft request' (duration: 195.963653ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.40332Z","caller":"traceutil/trace.go:171","msg":"trace[236311546] transaction","detail":"{read_only:false; response_revision:316; number_of_response:1; }","duration":"196.695351ms","start":"2026-04-18T22:12:13.206605Z","end":"2026-04-18T22:12:13.4033Z","steps":["trace[236311546] 'process raft request' (duration: 196.15589ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.403494Z","caller":"traceutil/trace.go:171","msg":"trace[1734913863] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"196.401609ms","start":"2026-04-18T22:12:13.207081Z","end":"2026-04-18T22:12:13.403483Z","steps":["trace[1734913863] 'process raft request' (duration: 195.734273ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.403511Z","caller":"traceutil/trace.go:171","msg":"trace[1262400246] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"198.639077ms","start":"2026-04-18T22:12:13.204859Z","end":"2026-04-18T22:12:13.403498Z","steps":["trace[1262400246] 'process raft request' (duration: 197.769993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:13.403638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.855334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-18T22:12:13.403689Z","caller":"traceutil/trace.go:171","msg":"trace[538745086] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:318; }","duration":"174.945477ms","start":"2026-04-18T22:12:13.228728Z","end":"2026-04-18T22:12:13.403674Z","steps":["trace[538745086] 'agreement among raft nodes before linearized reading' (duration: 174.814012ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.403181Z","caller":"traceutil/trace.go:171","msg":"trace[2110630355] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"198.107837ms","start":"2026-04-18T22:12:13.205057Z","end":"2026-04-18T22:12:13.403164Z","steps":["trace[2110630355] 'process raft request' (duration: 197.602777ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.404669Z","caller":"traceutil/trace.go:171","msg":"trace[1673766093] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"198.443309ms","start":"2026-04-18T22:12:13.20621Z","end":"2026-04-18T22:12:13.404654Z","steps":["trace[1673766093] 'process raft request' (duration: 196.493163ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.425675Z","caller":"traceutil/trace.go:171","msg":"trace[483074508] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"192.996626ms","start":"2026-04-18T22:12:13.232666Z","end":"2026-04-18T22:12:13.425662Z","steps":["trace[483074508] 'process raft request' (duration: 192.926774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:13.425844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.433718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-04-18T22:12:13.425922Z","caller":"traceutil/trace.go:171","msg":"trace[1462436157] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:319; }","duration":"146.564693ms","start":"2026-04-18T22:12:13.279341Z","end":"2026-04-18T22:12:13.425905Z","steps":["trace[1462436157] 'agreement among raft nodes before linearized reading' (duration: 146.369695ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.542722Z","caller":"traceutil/trace.go:171","msg":"trace[1613527989] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"111.696605ms","start":"2026-04-18T22:12:13.430987Z","end":"2026-04-18T22:12:13.542684Z","steps":["trace[1613527989] 'process raft request' (duration: 110.669374ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.724126Z","caller":"traceutil/trace.go:171","msg":"trace[1784499423] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:342; }","duration":"148.604053ms","start":"2026-04-18T22:12:13.575491Z","end":"2026-04-18T22:12:13.724095Z","steps":["trace[1784499423] 'read index received' (duration: 67.237819ms)","trace[1784499423] 'applied index is now lower than readState.Index' (duration: 81.364894ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:13.724363Z","caller":"traceutil/trace.go:171","msg":"trace[1340480351] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"159.531412ms","start":"2026-04-18T22:12:13.564782Z","end":"2026-04-18T22:12:13.724314Z","steps":["trace[1340480351] 'process raft request' (duration: 159.127237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:13.724788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.28021ms","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-04-18T22:12:13.725005Z","caller":"traceutil/trace.go:171","msg":"trace[1939515462] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:336; }","duration":"149.496009ms","start":"2026-04-18T22:12:13.575486Z","end":"2026-04-18T22:12:13.724982Z","steps":["trace[1939515462] 'agreement among raft nodes before linearized reading' (duration: 148.883965ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.724976Z","caller":"traceutil/trace.go:171","msg":"trace[616168688] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"171.093386ms","start":"2026-04-18T22:12:13.553185Z","end":"2026-04-18T22:12:13.724278Z","steps":["trace[616168688] 'process raft request' (duration: 89.520804ms)","trace[616168688] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/pods/kube-system/kube-proxy-ffxcq; req_size:4444; } (duration: 80.918137ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:13.725013Z","caller":"traceutil/trace.go:171","msg":"trace[301889830] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"157.4473ms","start":"2026-04-18T22:12:13.56697Z","end":"2026-04-18T22:12:13.724417Z","steps":["trace[301889830] 'process raft request' (duration: 157.012803ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.725085Z","caller":"traceutil/trace.go:171","msg":"trace[507991749] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"156.884959ms","start":"2026-04-18T22:12:13.56818Z","end":"2026-04-18T22:12:13.725065Z","steps":["trace[507991749] 'process raft request' (duration: 155.878069ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:13.72615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.889082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-04-18T22:12:13.726224Z","caller":"traceutil/trace.go:171","msg":"trace[1752530470] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:336; }","duration":"125.032498ms","start":"2026-04-18T22:12:13.601165Z","end":"2026-04-18T22:12:13.726198Z","steps":["trace[1752530470] 'agreement among raft nodes before linearized reading' (duration: 124.847431ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.889308Z","caller":"traceutil/trace.go:171","msg":"trace[1036718933] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"114.418851ms","start":"2026-04-18T22:12:13.774862Z","end":"2026-04-18T22:12:13.889281Z","steps":["trace[1036718933] 'process raft request' (duration: 51.562234ms)","trace[1036718933] 'compare' (duration: 62.605697ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:13.91459Z","caller":"traceutil/trace.go:171","msg":"trace[766351824] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"139.326459ms","start":"2026-04-18T22:12:13.775223Z","end":"2026-04-18T22:12:13.914549Z","steps":["trace[766351824] 'process raft request' (duration: 139.083499ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.914638Z","caller":"traceutil/trace.go:171","msg":"trace[464269970] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"137.173114ms","start":"2026-04-18T22:12:13.777437Z","end":"2026-04-18T22:12:13.91461Z","steps":["trace[464269970] 'process raft request' (duration: 137.119162ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:13.914655Z","caller":"traceutil/trace.go:171","msg":"trace[1176123046] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"138.961934ms","start":"2026-04-18T22:12:13.775654Z","end":"2026-04-18T22:12:13.914616Z","steps":["trace[1176123046] 'process raft request' (duration: 138.829789ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:15.925662Z","caller":"traceutil/trace.go:171","msg":"trace[806444558] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"147.76164ms","start":"2026-04-18T22:12:15.777876Z","end":"2026-04-18T22:12:15.925638Z","steps":["trace[806444558] 'process raft request' (duration: 147.606374ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:15.957117Z","caller":"traceutil/trace.go:171","msg":"trace[1706787422] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:369; }","duration":"142.956261ms","start":"2026-04-18T22:12:15.814141Z","end":"2026-04-18T22:12:15.957098Z","steps":["trace[1706787422] 'read index received' (duration: 111.443722ms)","trace[1706787422] 'applied index is now lower than readState.Index' (duration: 31.511569ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:15.957156Z","caller":"traceutil/trace.go:171","msg":"trace[931949191] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"175.920947ms","start":"2026-04-18T22:12:15.781216Z","end":"2026-04-18T22:12:15.957137Z","steps":["trace[931949191] 'process raft request' (duration: 175.75636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:15.957259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.092736ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:15.957387Z","caller":"traceutil/trace.go:171","msg":"trace[474722955] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:359; }","duration":"143.234732ms","start":"2026-04-18T22:12:15.814135Z","end":"2026-04-18T22:12:15.95737Z","steps":["trace[474722955] 'agreement among raft nodes before linearized reading' (duration: 143.060585ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:16.136568Z","caller":"traceutil/trace.go:171","msg":"trace[297180566] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"170.429285ms","start":"2026-04-18T22:12:15.966111Z","end":"2026-04-18T22:12:16.13654Z","steps":["trace[297180566] 'process raft request' (duration: 122.373035ms)","trace[297180566] 'compare' (duration: 47.914455ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:18.654117Z","caller":"traceutil/trace.go:171","msg":"trace[1778107252] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"134.805661ms","start":"2026-04-18T22:12:18.51928Z","end":"2026-04-18T22:12:18.654085Z","steps":["trace[1778107252] 'process raft request' (duration: 134.51612ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:23.01621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.612113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:23.016323Z","caller":"traceutil/trace.go:171","msg":"trace[1322593327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"123.78556ms","start":"2026-04-18T22:12:22.892519Z","end":"2026-04-18T22:12:23.016305Z","steps":["trace[1322593327] 'range keys from in-memory index tree' (duration: 123.491619ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:34.220391Z","caller":"traceutil/trace.go:171","msg":"trace[993437953] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"156.838775ms","start":"2026-04-18T22:12:34.063505Z","end":"2026-04-18T22:12:34.220344Z","steps":["trace[993437953] 'process raft request' (duration: 68.076026ms)","trace[993437953] 'compare' (duration: 88.458797ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:34.469803Z","caller":"traceutil/trace.go:171","msg":"trace[421342390] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"101.951473ms","start":"2026-04-18T22:12:34.367822Z","end":"2026-04-18T22:12:34.469773Z","steps":["trace[421342390] 'process raft request' (duration: 101.069918ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:36.619887Z","caller":"traceutil/trace.go:171","msg":"trace[159032876] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"619.177663ms","start":"2026-04-18T22:12:36.000673Z","end":"2026-04-18T22:12:36.61985Z","steps":["trace[159032876] 'process raft request' (duration: 618.984645ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:36.620038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:36.000651Z","time spent":"619.319938ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:12:36.626878Z","caller":"traceutil/trace.go:171","msg":"trace[1294053067] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"624.895491ms","start":"2026-04-18T22:12:36.001965Z","end":"2026-04-18T22:12:36.62686Z","steps":["trace[1294053067] 'process raft request' (duration: 624.710193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:36.626965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:36.001954Z","time spent":"624.976213ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:12:36.626988Z","caller":"traceutil/trace.go:171","msg":"trace[1232650662] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"620.565679ms","start":"2026-04-18T22:12:36.006375Z","end":"2026-04-18T22:12:36.62694Z","steps":["trace[1232650662] 'process raft request' (duration: 620.383712ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:36.627221Z","caller":"traceutil/trace.go:171","msg":"trace[1054977696] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:494; }","duration":"361.9503ms","start":"2026-04-18T22:12:36.265259Z","end":"2026-04-18T22:12:36.627209Z","steps":["trace[1054977696] 'read index received' (duration: 354.42027ms)","trace[1054977696] 'applied index is now lower than readState.Index' (duration: 7.5295ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:36.627232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:36.006355Z","time spent":"620.789488ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:12:36.627274Z","caller":"traceutil/trace.go:171","msg":"trace[382904794] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"620.34771ms","start":"2026-04-18T22:12:36.006919Z","end":"2026-04-18T22:12:36.627267Z","steps":["trace[382904794] 'process raft request' (duration: 619.863281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:36.627312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:36.00691Z","time spent":"620.379302ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:12:36.627378Z","caller":"traceutil/trace.go:171","msg":"trace[1355182934] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"618.355261ms","start":"2026-04-18T22:12:36.009017Z","end":"2026-04-18T22:12:36.627372Z","steps":["trace[1355182934] 'process raft request' (duration: 617.82443ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:36.62741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:36.009009Z","time spent":"618.385152ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:12:36.627401Z","caller":"traceutil/trace.go:171","msg":"trace[1111469060] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"624.332919ms","start":"2026-04-18T22:12:36.003052Z","end":"2026-04-18T22:12:36.627385Z","steps":["trace[1111469060] 'process raft request' (duration: 623.678143ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:36.627456Z","caller":"traceutil/trace.go:171","msg":"trace[2031313295] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"618.374531ms","start":"2026-04-18T22:12:36.009076Z","end":"2026-04-18T22:12:36.627451Z","steps":["trace[2031313295] 'process raft request' (duration: 617.791578ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:36.627481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:36.009072Z","time spent":"618.396803ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T22:12:36.627476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:36.003043Z","time spent":"624.399761ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:12:36.627527Z","caller":"traceutil/trace.go:171","msg":"trace[1771375529] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"617.903003ms","start":"2026-04-18T22:12:36.009619Z","end":"2026-04-18T22:12:36.627522Z","steps":["trace[1771375529] 'process raft request' (duration: 617.32043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:36.627553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:36.009612Z","time spent":"617.930314ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:12:36.627586Z","caller":"traceutil/trace.go:171","msg":"trace[168989112] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"594.358166ms","start":"2026-04-18T22:12:36.03322Z","end":"2026-04-18T22:12:36.627578Z","steps":["trace[168989112] 'process raft request' (duration: 593.900138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:36.627629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:36.033205Z","time spent":"594.406978ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T22:12:36.627652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.416208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:36.627593Z","caller":"traceutil/trace.go:171","msg":"trace[509573801] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"453.543674ms","start":"2026-04-18T22:12:36.174044Z","end":"2026-04-18T22:12:36.627588Z","steps":["trace[509573801] 'process raft request' (duration: 453.121907ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:36.627671Z","caller":"traceutil/trace.go:171","msg":"trace[292289436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:490; }","duration":"362.473651ms","start":"2026-04-18T22:12:36.265192Z","end":"2026-04-18T22:12:36.627666Z","steps":["trace[292289436] 'agreement among raft nodes before linearized reading' (duration: 362.411518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:36.627688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:36.265174Z","time spent":"362.510152ms","remote":"127.0.0.1:50628","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-04-18T22:12:36.62769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:36.174012Z","time spent":"453.666048ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:12:36.627762Z","caller":"traceutil/trace.go:171","msg":"trace[1957241616] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"613.81434ms","start":"2026-04-18T22:12:36.013942Z","end":"2026-04-18T22:12:36.627757Z","steps":["trace[1957241616] 'process raft request' (duration: 613.081691ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:36.627792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:36.013935Z","time spent":"613.845651ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-18T22:12:36.627759Z","caller":"traceutil/trace.go:171","msg":"trace[1080878844] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"602.021991ms","start":"2026-04-18T22:12:36.025686Z","end":"2026-04-18T22:12:36.627708Z","steps":["trace[1080878844] 'process raft request' (duration: 601.397127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:36.627908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:36.025651Z","time spent":"602.183007ms","remote":"127.0.0.1:50704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-18T22:12:36.637412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.278835ms","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-04-18T22:12:36.637467Z","caller":"traceutil/trace.go:171","msg":"trace[1498407835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:492; }","duration":"353.356878ms","start":"2026-04-18T22:12:36.284098Z","end":"2026-04-18T22:12:36.637455Z","steps":["trace[1498407835] 'agreement among raft nodes before linearized reading' (duration: 353.261314ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:36.637494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:36.284088Z","time spent":"353.399559ms","remote":"127.0.0.1:50896","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-04-18T22:12:39.299015Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537962505078424,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-18T22:12:39.363068Z","caller":"traceutil/trace.go:171","msg":"trace[450770507] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:577; }","duration":"564.545172ms","start":"2026-04-18T22:12:38.798484Z","end":"2026-04-18T22:12:39.363029Z","steps":["trace[450770507] 'read index received' (duration: 564.275222ms)","trace[450770507] 'applied index is now lower than readState.Index' (duration: 268.87µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:39.3632Z","caller":"traceutil/trace.go:171","msg":"trace[682912025] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"703.907601ms","start":"2026-04-18T22:12:38.65928Z","end":"2026-04-18T22:12:39.363188Z","steps":["trace[682912025] 'process raft request' (duration: 703.555487ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:39.363292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.043905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-18T22:12:39.363325Z","caller":"traceutil/trace.go:171","msg":"trace[612941070] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:559; }","duration":"496.119028ms","start":"2026-04-18T22:12:38.867195Z","end":"2026-04-18T22:12:39.363314Z","steps":["trace[612941070] 'agreement among raft nodes before linearized reading' (duration: 496.001593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:39.36335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:38.867176Z","time spent":"496.16851ms","remote":"127.0.0.1:50896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-18T22:12:39.363453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:38.659267Z","time spent":"703.960923ms","remote":"127.0.0.1:50896","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-04-18T22:12:39.363462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.961799ms","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-04-18T22:12:39.363529Z","caller":"traceutil/trace.go:171","msg":"trace[1252814160] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"565.062873ms","start":"2026-04-18T22:12:38.79845Z","end":"2026-04-18T22:12:39.363513Z","steps":["trace[1252814160] 'agreement among raft nodes before linearized reading' (duration: 564.864385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:39.363559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:38.798424Z","time spent":"565.127305ms","remote":"127.0.0.1:50896","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-04-18T22:12:39.363571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.077444ms","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-04-18T22:12:39.363593Z","caller":"traceutil/trace.go:171","msg":"trace[851566125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:559; }","duration":"168.134677ms","start":"2026-04-18T22:12:39.195452Z","end":"2026-04-18T22:12:39.363587Z","steps":["trace[851566125] 'agreement among raft nodes before linearized reading' (duration: 168.071334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:40.074042Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537962505078428,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-18T22:12:40.186604Z","caller":"traceutil/trace.go:171","msg":"trace[39497017] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:578; }","duration":"613.609685ms","start":"2026-04-18T22:12:39.572975Z","end":"2026-04-18T22:12:40.186585Z","steps":["trace[39497017] 'read index received' (duration: 569.940948ms)","trace[39497017] 'applied index is now lower than readState.Index' (duration: 43.667817ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:40.186717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.725749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:40.186745Z","caller":"traceutil/trace.go:171","msg":"trace[873447264] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:562; }","duration":"613.792134ms","start":"2026-04-18T22:12:39.572944Z","end":"2026-04-18T22:12:40.186736Z","steps":["trace[873447264] 'agreement among raft nodes before linearized reading' (duration: 613.708735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:40.186766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:39.572929Z","time spent":"613.831261ms","remote":"127.0.0.1:50850","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" "} {"level":"info","ts":"2026-04-18T22:12:40.186906Z","caller":"traceutil/trace.go:171","msg":"trace[488582108] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"819.090748ms","start":"2026-04-18T22:12:39.367782Z","end":"2026-04-18T22:12:40.186872Z","steps":["trace[488582108] 'process raft request' (duration: 775.126432ms)","trace[488582108] 'compare' (duration: 42.978714ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:40.186927Z","caller":"traceutil/trace.go:171","msg":"trace[1190090057] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"818.081404ms","start":"2026-04-18T22:12:39.36881Z","end":"2026-04-18T22:12:40.186891Z","steps":["trace[1190090057] 'process raft request' (duration: 817.743961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:40.186283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:39.472534Z","time spent":"713.74405ms","remote":"127.0.0.1:58848","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-18T22:12:40.187065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:39.367759Z","time spent":"819.208675ms","remote":"127.0.0.1:50896","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-04-18T22:12:40.187104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:39.368778Z","time spent":"818.254365ms","remote":"127.0.0.1:50896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:12:40.187234Z","caller":"traceutil/trace.go:171","msg":"trace[841379615] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"818.49705ms","start":"2026-04-18T22:12:39.368727Z","end":"2026-04-18T22:12:40.187224Z","steps":["trace[841379615] 'process raft request' (duration: 817.759284ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:40.18729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:39.368706Z","time spent":"818.554876ms","remote":"127.0.0.1:50896","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-04-18T22:12:43.991488Z","caller":"traceutil/trace.go:171","msg":"trace[1002927179] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"248.094125ms","start":"2026-04-18T22:12:43.743373Z","end":"2026-04-18T22:12:43.991467Z","steps":["trace[1002927179] 'read index received' (duration: 247.907489ms)","trace[1002927179] 'applied index is now lower than readState.Index' (duration: 183.946µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:43.991519Z","caller":"traceutil/trace.go:171","msg":"trace[255586962] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"283.008172ms","start":"2026-04-18T22:12:43.708493Z","end":"2026-04-18T22:12:43.991501Z","steps":["trace[255586962] 'process raft request' (duration: 282.849073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:43.991661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.102633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:12:43.991662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.268591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:43.991704Z","caller":"traceutil/trace.go:171","msg":"trace[555125657] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:586; }","duration":"248.161546ms","start":"2026-04-18T22:12:43.74353Z","end":"2026-04-18T22:12:43.991692Z","steps":["trace[555125657] 'agreement among raft nodes before linearized reading' (duration: 248.063959ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:43.991713Z","caller":"traceutil/trace.go:171","msg":"trace[1870834118] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:586; }","duration":"248.348591ms","start":"2026-04-18T22:12:43.743351Z","end":"2026-04-18T22:12:43.991699Z","steps":["trace[1870834118] 'agreement among raft nodes before linearized reading' (duration: 248.236626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:43.99178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.41688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:43.991862Z","caller":"traceutil/trace.go:171","msg":"trace[1268681650] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:586; }","duration":"119.627921ms","start":"2026-04-18T22:12:43.872211Z","end":"2026-04-18T22:12:43.991839Z","steps":["trace[1268681650] 'agreement among raft nodes before linearized reading' (duration: 119.40785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:43.992073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.532464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:43.992117Z","caller":"traceutil/trace.go:171","msg":"trace[442386820] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:586; }","duration":"119.596296ms","start":"2026-04-18T22:12:43.872508Z","end":"2026-04-18T22:12:43.992104Z","steps":["trace[442386820] 'agreement among raft nodes before linearized reading' (duration: 119.535434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:43.992132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.982788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:43.992216Z","caller":"traceutil/trace.go:171","msg":"trace[1561017165] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:586; }","duration":"202.073156ms","start":"2026-04-18T22:12:43.79009Z","end":"2026-04-18T22:12:43.992163Z","steps":["trace[1561017165] 'agreement among raft nodes before linearized reading' (duration: 202.005735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:43.992235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.352198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:43.992281Z","caller":"traceutil/trace.go:171","msg":"trace[1705915222] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:586; }","duration":"224.40373ms","start":"2026-04-18T22:12:43.767854Z","end":"2026-04-18T22:12:43.992257Z","steps":["trace[1705915222] 'agreement among raft nodes before linearized reading' (duration: 224.33448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:43.992396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.218752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:12:43.992414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.445858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:43.992434Z","caller":"traceutil/trace.go:171","msg":"trace[1546312419] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:586; }","duration":"120.279585ms","start":"2026-04-18T22:12:43.872146Z","end":"2026-04-18T22:12:43.992425Z","steps":["trace[1546312419] 'agreement among raft nodes before linearized reading' (duration: 120.219693ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:43.992426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.553121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:43.992528Z","caller":"traceutil/trace.go:171","msg":"trace[1764112703] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:586; }","duration":"224.659047ms","start":"2026-04-18T22:12:43.767828Z","end":"2026-04-18T22:12:43.992487Z","steps":["trace[1764112703] 'agreement among raft nodes before linearized reading' (duration: 224.548481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:43.992558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.462557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:43.992596Z","caller":"traceutil/trace.go:171","msg":"trace[970135403] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:586; }","duration":"201.52003ms","start":"2026-04-18T22:12:43.791052Z","end":"2026-04-18T22:12:43.992572Z","steps":["trace[970135403] 'agreement among raft nodes before linearized reading' (duration: 201.491213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:43.992448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.917225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:43.992678Z","caller":"traceutil/trace.go:171","msg":"trace[1278313452] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:586; }","duration":"224.141396ms","start":"2026-04-18T22:12:43.768527Z","end":"2026-04-18T22:12:43.992668Z","steps":["trace[1278313452] 'agreement among raft nodes before linearized reading' (duration: 223.911156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:43.992683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.909641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-18T22:12:43.9927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.06881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:43.992716Z","caller":"traceutil/trace.go:171","msg":"trace[262232593] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:586; }","duration":"160.993431ms","start":"2026-04-18T22:12:43.831714Z","end":"2026-04-18T22:12:43.992707Z","steps":["trace[262232593] 'agreement among raft nodes before linearized reading' (duration: 160.943137ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:43.992744Z","caller":"traceutil/trace.go:171","msg":"trace[517505436] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:586; }","duration":"161.136291ms","start":"2026-04-18T22:12:43.83159Z","end":"2026-04-18T22:12:43.992727Z","steps":["trace[517505436] 'agreement among raft nodes before linearized reading' (duration: 161.092877ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:43.992454Z","caller":"traceutil/trace.go:171","msg":"trace[574809989] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:586; }","duration":"248.484973ms","start":"2026-04-18T22:12:43.743957Z","end":"2026-04-18T22:12:43.992442Z","steps":["trace[574809989] 'agreement among raft nodes before linearized reading' (duration: 248.426381ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:43.992425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.404942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:43.992863Z","caller":"traceutil/trace.go:171","msg":"trace[513660589] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:586; }","duration":"202.857541ms","start":"2026-04-18T22:12:43.79Z","end":"2026-04-18T22:12:43.992857Z","steps":["trace[513660589] 'agreement among raft nodes before linearized reading' (duration: 202.404822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:44.383445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.70559ms","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-04-18T22:12:44.383524Z","caller":"traceutil/trace.go:171","msg":"trace[1021273499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"165.812046ms","start":"2026-04-18T22:12:44.217698Z","end":"2026-04-18T22:12:44.38351Z","steps":["trace[1021273499] 'range keys from in-memory index tree' (duration: 165.589635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:44.383482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.999998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:44.383574Z","caller":"traceutil/trace.go:171","msg":"trace[1939492302] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"122.133291ms","start":"2026-04-18T22:12:44.261423Z","end":"2026-04-18T22:12:44.383556Z","steps":["trace[1939492302] 'range keys from in-memory index tree' (duration: 121.926167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:44.383724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.230612ms","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-04-18T22:12:44.383814Z","caller":"traceutil/trace.go:171","msg":"trace[809240618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"165.378723ms","start":"2026-04-18T22:12:44.218415Z","end":"2026-04-18T22:12:44.383794Z","steps":["trace[809240618] 'range keys from in-memory index tree' (duration: 165.01654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:44.38382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.554418ms","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-04-18T22:12:44.383885Z","caller":"traceutil/trace.go:171","msg":"trace[1185487141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"166.708439ms","start":"2026-04-18T22:12:44.217159Z","end":"2026-04-18T22:12:44.383867Z","steps":["trace[1185487141] 'range keys from in-memory index tree' (duration: 166.403528ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:44.815043Z","caller":"traceutil/trace.go:171","msg":"trace[621777193] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"427.486119ms","start":"2026-04-18T22:12:44.387501Z","end":"2026-04-18T22:12:44.814987Z","steps":["trace[621777193] 'process raft request' (duration: 427.332439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:44.815332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:44.387481Z","time spent":"427.63311ms","remote":"127.0.0.1:50896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:12:45.765326Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13710537962505078535,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-18T22:12:45.83028Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.013971708s","expected-duration":"1s"} {"level":"info","ts":"2026-04-18T22:12:45.830841Z","caller":"traceutil/trace.go:171","msg":"trace[1159875603] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.442811521s","start":"2026-04-18T22:12:44.387999Z","end":"2026-04-18T22:12:45.83081Z","steps":["trace[1159875603] 'process raft request' (duration: 1.442522238s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:45.830869Z","caller":"traceutil/trace.go:171","msg":"trace[2071506006] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.433200809s","start":"2026-04-18T22:12:44.397651Z","end":"2026-04-18T22:12:45.830852Z","steps":["trace[2071506006] 'process raft request' (duration: 1.43311558s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:45.831028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:44.387974Z","time spent":"1.442933206s","remote":"127.0.0.1:50896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-18T22:12:45.831131Z","caller":"traceutil/trace.go:171","msg":"trace[688302850] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.441664961s","start":"2026-04-18T22:12:44.389325Z","end":"2026-04-18T22:12:45.83099Z","steps":["trace[688302850] 'process raft request' (duration: 1.441378878s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:45.831328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:44.389313Z","time spent":"1.441927358s","remote":"127.0.0.1:50896","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-04-18T22:12:45.831386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:44.397608Z","time spent":"1.433304575s","remote":"127.0.0.1:50838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:12:46.898932Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.068249546s","expected-duration":"1s"} {"level":"info","ts":"2026-04-18T22:12:46.943772Z","caller":"traceutil/trace.go:171","msg":"trace[1436757464] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:608; }","duration":"1.679496449s","start":"2026-04-18T22:12:45.264247Z","end":"2026-04-18T22:12:46.943743Z","steps":["trace[1436757464] 'read index received' (duration: 566.487999ms)","trace[1436757464] 'applied index is now lower than readState.Index' (duration: 1.11300733s)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:12:46.943929Z","caller":"traceutil/trace.go:171","msg":"trace[96956634] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"2.047005493s","start":"2026-04-18T22:12:44.896911Z","end":"2026-04-18T22:12:46.943916Z","steps":["trace[96956634] 'process raft request' (duration: 2.04670733s)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:12:46.943938Z","caller":"traceutil/trace.go:171","msg":"trace[1381488458] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"2.531182125s","start":"2026-04-18T22:12:44.412733Z","end":"2026-04-18T22:12:46.943915Z","steps":["trace[1381488458] 'process raft request' (duration: 2.48658947s)","trace[1381488458] 'compare' (duration: 43.779527ms)"],"step_count":2} {"level":"warn","ts":"2026-04-18T22:12:46.944049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:44.412708Z","time spent":"2.531279653s","remote":"127.0.0.1:50748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:12:46.944161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.558741754s","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-04-18T22:12:46.944175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.179295ms","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-04-18T22:12:46.944222Z","caller":"traceutil/trace.go:171","msg":"trace[1291473281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"1.558859669s","start":"2026-04-18T22:12:45.385348Z","end":"2026-04-18T22:12:46.944208Z","steps":["trace[1291473281] 'agreement among raft nodes before linearized reading' (duration: 1.558674582s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:46.944204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.679950981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:12:46.944241Z","caller":"traceutil/trace.go:171","msg":"trace[1194693090] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:592; }","duration":"124.283151ms","start":"2026-04-18T22:12:46.819936Z","end":"2026-04-18T22:12:46.944219Z","steps":["trace[1194693090] 'agreement among raft nodes before linearized reading' (duration: 124.089336ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:46.944254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:45.385326Z","time spent":"1.558922621s","remote":"127.0.0.1:50896","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-04-18T22:12:46.944286Z","caller":"traceutil/trace.go:171","msg":"trace[800047721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"1.680080555s","start":"2026-04-18T22:12:45.264187Z","end":"2026-04-18T22:12:46.944268Z","steps":["trace[800047721] 'agreement among raft nodes before linearized reading' (duration: 1.679949512s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:46.944333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:45.264165Z","time spent":"1.680158995s","remote":"127.0.0.1:50628","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-04-18T22:12:46.944207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:44.896882Z","time spent":"2.047088322s","remote":"127.0.0.1:50896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:12:46.944851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.212472254s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.44\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-18T22:12:46.944975Z","caller":"traceutil/trace.go:171","msg":"trace[1758277067] range","detail":"{range_begin:/registry/masterleases/199.204.45.44; range_end:; response_count:1; response_revision:592; }","duration":"1.212632253s","start":"2026-04-18T22:12:45.732328Z","end":"2026-04-18T22:12:46.94496Z","steps":["trace[1758277067] 'agreement among raft nodes before linearized reading' (duration: 1.211820955s)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:12:46.946033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:12:45.73231Z","time spent":"1.213703308s","remote":"127.0.0.1:50682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.44\" "} {"level":"info","ts":"2026-04-18T22:12:48.787462Z","caller":"traceutil/trace.go:171","msg":"trace[390612404] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"123.400463ms","start":"2026-04-18T22:12:48.663307Z","end":"2026-04-18T22:12:48.786708Z","steps":["trace[390612404] 'process raft request' (duration: 123.294716ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:00.495372Z","caller":"traceutil/trace.go:171","msg":"trace[1608087369] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"174.536006ms","start":"2026-04-18T22:13:00.320813Z","end":"2026-04-18T22:13:00.495349Z","steps":["trace[1608087369] 'process raft request' (duration: 174.403838ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:03.17018Z","caller":"traceutil/trace.go:171","msg":"trace[904795760] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"100.534033ms","start":"2026-04-18T22:13:03.069613Z","end":"2026-04-18T22:13:03.170147Z","steps":["trace[904795760] 'process raft request' (duration: 100.306884ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:03.673742Z","caller":"traceutil/trace.go:171","msg":"trace[2115682367] linearizableReadLoop","detail":"{readStateIndex:783; appliedIndex:782; }","duration":"409.23639ms","start":"2026-04-18T22:13:03.264482Z","end":"2026-04-18T22:13:03.673718Z","steps":["trace[2115682367] 'read index received' (duration: 404.507574ms)","trace[2115682367] 'applied index is now lower than readState.Index' (duration: 4.727886ms)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:13:03.673799Z","caller":"traceutil/trace.go:171","msg":"trace[1227982605] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"499.076455ms","start":"2026-04-18T22:13:03.174666Z","end":"2026-04-18T22:13:03.673743Z","steps":["trace[1227982605] 'process raft request' (duration: 498.183127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:03.673947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:03.174644Z","time spent":"499.23221ms","remote":"127.0.0.1:50896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-18T22:13:03.674231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.358649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:13:03.674285Z","caller":"traceutil/trace.go:171","msg":"trace[1265365384] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:758; }","duration":"409.836585ms","start":"2026-04-18T22:13:03.264436Z","end":"2026-04-18T22:13:03.674272Z","steps":["trace[1265365384] 'agreement among raft nodes before linearized reading' (duration: 409.369728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:03.674323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-18T22:13:03.264416Z","time spent":"409.89539ms","remote":"127.0.0.1:50628","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-04-18T22:13:03.676923Z","caller":"traceutil/trace.go:171","msg":"trace[2009681225] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"168.689448ms","start":"2026-04-18T22:13:03.508223Z","end":"2026-04-18T22:13:03.676912Z","steps":["trace[2009681225] 'process raft request' (duration: 168.511804ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:13:08.624325Z","caller":"traceutil/trace.go:171","msg":"trace[75898250] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"108.845343ms","start":"2026-04-18T22:13:08.515431Z","end":"2026-04-18T22:13:08.624276Z","steps":["trace[75898250] 'read index received' (duration: 108.690926ms)","trace[75898250] 'applied index is now lower than readState.Index' (duration: 153.747µs)"],"step_count":2} {"level":"info","ts":"2026-04-18T22:13:08.624545Z","caller":"traceutil/trace.go:171","msg":"trace[399380398] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"114.021142ms","start":"2026-04-18T22:13:08.510514Z","end":"2026-04-18T22:13:08.624536Z","steps":["trace[399380398] 'process raft request' (duration: 113.660072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-18T22:13:08.624762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.307475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-18T22:13:08.624847Z","caller":"traceutil/trace.go:171","msg":"trace[533479112] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:811; }","duration":"109.431084ms","start":"2026-04-18T22:13:08.515396Z","end":"2026-04-18T22:13:08.624827Z","steps":["trace[533479112] 'agreement among raft nodes before linearized reading' (duration: 109.193064ms)"],"step_count":1} {"level":"info","ts":"2026-04-18T22:14:52.837611Z","caller":"traceutil/trace.go:171","msg":"trace[1580797657] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"201.404832ms","start":"2026-04-18T22:14:52.636105Z","end":"2026-04-18T22:14:52.83751Z","steps":["trace[1580797657] 'process raft request' (duration: 201.162722ms)"],"step_count":1}