{"level":"warn","ts":"2026-07-30T21:00:59.46655Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T21:00:59.466737Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.248: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.248:2380","--initial-cluster=instance=https://199.204.45.248:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.248:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.248: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-07-30T21:00:59.466879Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-30T21:00:59.466903Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-07-30T21:00:59.466938Z","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-07-30T21:00:59.468347Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"]} {"level":"info","ts":"2026-07-30T21:00:59.468498Z","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.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.248: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-07-30T21:00:59.52007Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"51.17036ms"} {"level":"info","ts":"2026-07-30T21:00:59.540195Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39582465ceaa2f67","cluster-id":"87fd8ddedde3be45"} {"level":"info","ts":"2026-07-30T21:00:59.540322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T21:00:59.540355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 0"} {"level":"info","ts":"2026-07-30T21:00:59.540382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39582465ceaa2f67 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T21:00:59.540397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became follower at term 1"} {"level":"info","ts":"2026-07-30T21:00:59.540449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"warn","ts":"2026-07-30T21:00:59.677172Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T21:00:59.695708Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T21:00:59.697805Z","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-07-30T21:00:59.708377Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39582465ceaa2f67","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T21:00:59.71094Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39582465ceaa2f67","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T21:00:59.711068Z","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-07-30T21:00:59.711102Z","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-07-30T21:00:59.711114Z","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-07-30T21:00:59.71145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 switched to configuration voters=(4132092677789986663)"} {"level":"info","ts":"2026-07-30T21:00:59.711549Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","added-peer-id":"39582465ceaa2f67","added-peer-peer-urls":["https://199.204.45.248:2380"]} {"level":"info","ts":"2026-07-30T21:00:59.715922Z","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-07-30T21:00:59.716197Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-07-30T21:00:59.716223Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.248:2380"} {"level":"info","ts":"2026-07-30T21:00:59.716981Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T21:00:59.716143Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39582465ceaa2f67","initial-advertise-peer-urls":["https://199.204.45.248:2380"],"listen-peer-urls":["https://199.204.45.248:2380"],"advertise-client-urls":["https://199.204.45.248:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.248:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T21:01:00.141514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T21:01:00.141634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T21:01:00.141669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgPreVoteResp from 39582465ceaa2f67 at term 1"} {"level":"info","ts":"2026-07-30T21:01:00.14171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became candidate at term 2"} {"level":"info","ts":"2026-07-30T21:01:00.141727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 received MsgVoteResp from 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-07-30T21:01:00.141748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39582465ceaa2f67 became leader at term 2"} {"level":"info","ts":"2026-07-30T21:01:00.141766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39582465ceaa2f67 elected leader 39582465ceaa2f67 at term 2"} {"level":"info","ts":"2026-07-30T21:01:00.304362Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:01:00.304571Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39582465ceaa2f67","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.248:2379]}","request-path":"/0/members/39582465ceaa2f67/attributes","cluster-id":"87fd8ddedde3be45","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T21:01:00.304648Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T21:01:00.304686Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T21:01:00.30492Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T21:01:00.304937Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T21:01:00.307744Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.248:2379"} {"level":"info","ts":"2026-07-30T21:01:00.308017Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T21:01:00.444493Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87fd8ddedde3be45","local-member-id":"39582465ceaa2f67","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:01:00.444701Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:01:00.444823Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T21:01:03.038143Z","caller":"traceutil/trace.go:171","msg":"trace[782263983] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"160.202679ms","start":"2026-07-30T21:01:02.877887Z","end":"2026-07-30T21:01:03.03809Z","steps":["trace[782263983] 'process raft request' (duration: 135.586446ms)","trace[782263983] 'compare' (duration: 24.327585ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:03.043245Z","caller":"traceutil/trace.go:171","msg":"trace[1927193866] transaction","detail":"{read_only:false; number_of_response:0; response_revision:99; }","duration":"119.904172ms","start":"2026-07-30T21:01:02.92331Z","end":"2026-07-30T21:01:03.043214Z","steps":["trace[1927193866] 'process raft request' (duration: 119.786089ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:03.307769Z","caller":"traceutil/trace.go:171","msg":"trace[1562816457] transaction","detail":"{read_only:false; response_revision:102; number_of_response:1; }","duration":"190.759064ms","start":"2026-07-30T21:01:03.116974Z","end":"2026-07-30T21:01:03.307733Z","steps":["trace[1562816457] 'process raft request' (duration: 97.89145ms)","trace[1562816457] 'compare' (duration: 92.574006ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:03.482743Z","caller":"traceutil/trace.go:171","msg":"trace[1380996767] transaction","detail":"{read_only:false; response_revision:105; number_of_response:1; }","duration":"101.914129ms","start":"2026-07-30T21:01:03.380791Z","end":"2026-07-30T21:01:03.482705Z","steps":["trace[1380996767] 'process raft request' (duration: 101.784495ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:06.135931Z","caller":"traceutil/trace.go:171","msg":"trace[296468378] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"181.913035ms","start":"2026-07-30T21:01:05.953993Z","end":"2026-07-30T21:01:06.135906Z","steps":["trace[296468378] 'read index received' (duration: 27.196003ms)","trace[296468378] 'applied index is now lower than readState.Index' (duration: 154.715402ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:06.136198Z","caller":"traceutil/trace.go:171","msg":"trace[1939347777] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"187.209289ms","start":"2026-07-30T21:01:05.948941Z","end":"2026-07-30T21:01:06.136151Z","steps":["trace[1939347777] 'process raft request' (duration: 130.186221ms)","trace[1939347777] 'compare' (duration: 56.562745ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:06.1364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.399459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-30T21:01:06.136459Z","caller":"traceutil/trace.go:171","msg":"trace[1915959985] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:278; }","duration":"182.499162ms","start":"2026-07-30T21:01:05.953947Z","end":"2026-07-30T21:01:06.136447Z","steps":["trace[1915959985] 'agreement among raft nodes before linearized reading' (duration: 182.403469ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:06.304834Z","caller":"traceutil/trace.go:171","msg":"trace[1274533231] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"108.894957ms","start":"2026-07-30T21:01:06.195909Z","end":"2026-07-30T21:01:06.304804Z","steps":["trace[1274533231] 'process raft request' (duration: 95.403503ms)","trace[1274533231] 'compare' (duration: 12.667401ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:06.305046Z","caller":"traceutil/trace.go:171","msg":"trace[316377294] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"109.108292ms","start":"2026-07-30T21:01:06.195908Z","end":"2026-07-30T21:01:06.305017Z","steps":["trace[316377294] 'process raft request' (duration: 108.373453ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:10.791678Z","caller":"traceutil/trace.go:171","msg":"trace[2136559770] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"101.119286ms","start":"2026-07-30T21:01:10.690541Z","end":"2026-07-30T21:01:10.79166Z","steps":["trace[2136559770] 'process raft request' (duration: 100.955422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:11.5233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.843699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-9hsgn\" ","response":"range_response_count:1 size:4466"} {"level":"info","ts":"2026-07-30T21:01:11.523389Z","caller":"traceutil/trace.go:171","msg":"trace[1856976780] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-9hsgn; range_end:; response_count:1; response_revision:391; }","duration":"110.980112ms","start":"2026-07-30T21:01:11.412387Z","end":"2026-07-30T21:01:11.523367Z","steps":["trace[1856976780] 'range keys from in-memory index tree' (duration: 110.697025ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:16.756184Z","caller":"traceutil/trace.go:171","msg":"trace[848648019] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"169.895181ms","start":"2026-07-30T21:01:16.586261Z","end":"2026-07-30T21:01:16.756156Z","steps":["trace[848648019] 'process raft request' (duration: 110.398997ms)","trace[848648019] 'compare' (duration: 59.412632ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:41.070945Z","caller":"traceutil/trace.go:171","msg":"trace[2086751800] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"125.949888ms","start":"2026-07-30T21:01:40.944966Z","end":"2026-07-30T21:01:41.070916Z","steps":["trace[2086751800] 'process raft request' (duration: 124.93295ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:41.082501Z","caller":"traceutil/trace.go:171","msg":"trace[1234033577] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"134.799788ms","start":"2026-07-30T21:01:40.947678Z","end":"2026-07-30T21:01:41.082478Z","steps":["trace[1234033577] 'process raft request' (duration: 134.216963ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:41.082582Z","caller":"traceutil/trace.go:171","msg":"trace[1378316927] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"118.632419ms","start":"2026-07-30T21:01:40.963944Z","end":"2026-07-30T21:01:41.082577Z","steps":["trace[1378316927] 'process raft request' (duration: 118.056273ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:41.08263Z","caller":"traceutil/trace.go:171","msg":"trace[24932536] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"118.586497ms","start":"2026-07-30T21:01:40.964038Z","end":"2026-07-30T21:01:41.082625Z","steps":["trace[24932536] 'process raft request' (duration: 117.996031ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:41.082675Z","caller":"traceutil/trace.go:171","msg":"trace[415127920] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"113.472799ms","start":"2026-07-30T21:01:40.969198Z","end":"2026-07-30T21:01:41.082671Z","steps":["trace[415127920] 'process raft request' (duration: 112.863212ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:41.082804Z","caller":"traceutil/trace.go:171","msg":"trace[1404255978] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"106.657793ms","start":"2026-07-30T21:01:40.976141Z","end":"2026-07-30T21:01:41.082798Z","steps":["trace[1404255978] 'process raft request' (duration: 105.944374ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:41.082876Z","caller":"traceutil/trace.go:171","msg":"trace[1236922142] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"104.418492ms","start":"2026-07-30T21:01:40.978453Z","end":"2026-07-30T21:01:41.082871Z","steps":["trace[1236922142] 'process raft request' (duration: 103.656561ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:41.082944Z","caller":"traceutil/trace.go:171","msg":"trace[1842281337] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"105.485021ms","start":"2026-07-30T21:01:40.977454Z","end":"2026-07-30T21:01:41.082939Z","steps":["trace[1842281337] 'process raft request' (duration: 104.683879ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:41.514726Z","caller":"traceutil/trace.go:171","msg":"trace[848675375] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"351.778584ms","start":"2026-07-30T21:01:41.162899Z","end":"2026-07-30T21:01:41.514678Z","steps":["trace[848675375] 'process raft request' (duration: 342.07526ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:41.514871Z","caller":"traceutil/trace.go:171","msg":"trace[842774528] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"321.664134ms","start":"2026-07-30T21:01:41.193185Z","end":"2026-07-30T21:01:41.514849Z","steps":["trace[842774528] 'process raft request' (duration: 321.619293ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:41.515007Z","caller":"traceutil/trace.go:171","msg":"trace[96871959] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"351.96011ms","start":"2026-07-30T21:01:41.163025Z","end":"2026-07-30T21:01:41.514985Z","steps":["trace[96871959] 'process raft request' (duration: 351.6112ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:41.51506Z","caller":"traceutil/trace.go:171","msg":"trace[160720593] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"340.386794ms","start":"2026-07-30T21:01:41.174646Z","end":"2026-07-30T21:01:41.515033Z","steps":["trace[160720593] 'process raft request' (duration: 340.081856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:41.516459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:41.162879Z","time spent":"352.258148ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:01:41.516802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:41.193164Z","time spent":"322.002684ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:01:41.517006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:41.163017Z","time spent":"352.244577ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:01:41.517193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:41.174628Z","time spent":"340.731874ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:41.873281Z","caller":"traceutil/trace.go:171","msg":"trace[96674691] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"113.384596ms","start":"2026-07-30T21:01:41.759859Z","end":"2026-07-30T21:01:41.873243Z","steps":["trace[96674691] 'process raft request' (duration: 91.11355ms)","trace[96674691] 'compare' (duration: 22.034ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:42.540101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.017906ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:01:42.540356Z","caller":"traceutil/trace.go:171","msg":"trace[1344744646] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"669.363096ms","start":"2026-07-30T21:01:41.870952Z","end":"2026-07-30T21:01:42.540315Z","steps":["trace[1344744646] 'process raft request' (duration: 462.875398ms)","trace[1344744646] 'compare' (duration: 205.714827ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:42.540422Z","caller":"traceutil/trace.go:171","msg":"trace[1535905424] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"653.180116ms","start":"2026-07-30T21:01:41.887222Z","end":"2026-07-30T21:01:42.540402Z","steps":["trace[1535905424] 'process raft request' (duration: 653.034492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:42.540507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:41.887191Z","time spent":"653.272148ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:01:42.540548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:41.870926Z","time spent":"669.526211ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:01:42.540585Z","caller":"traceutil/trace.go:171","msg":"trace[1447905420] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:612; }","duration":"556.909066ms","start":"2026-07-30T21:01:41.983653Z","end":"2026-07-30T21:01:42.540562Z","steps":["trace[1447905420] 'read index received' (duration: 350.188901ms)","trace[1447905420] 'applied index is now lower than readState.Index' (duration: 206.718625ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:42.540778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.146842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:01:42.540779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.121272ms","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-07-30T21:01:42.540819Z","caller":"traceutil/trace.go:171","msg":"trace[1031791527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:589; }","duration":"374.214214ms","start":"2026-07-30T21:01:42.166592Z","end":"2026-07-30T21:01:42.540807Z","steps":["trace[1031791527] 'agreement among raft nodes before linearized reading' (duration: 374.145172ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:42.540826Z","caller":"traceutil/trace.go:171","msg":"trace[1579501967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:589; }","duration":"557.263126ms","start":"2026-07-30T21:01:41.98355Z","end":"2026-07-30T21:01:42.540813Z","steps":["trace[1579501967] 'agreement among raft nodes before linearized reading' (duration: 557.147833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:42.540852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:42.166575Z","time spent":"374.270425ms","remote":"127.0.0.1:42894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:01:42.54086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:41.983523Z","time spent":"557.329777ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T21:01:42.5409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.600418ms","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-07-30T21:01:42.540933Z","caller":"traceutil/trace.go:171","msg":"trace[2106019816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"100.67067ms","start":"2026-07-30T21:01:42.440255Z","end":"2026-07-30T21:01:42.540925Z","steps":["trace[2106019816] 'agreement among raft nodes before linearized reading' (duration: 100.610168ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:42.541013Z","caller":"traceutil/trace.go:171","msg":"trace[930202188] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"649.606349ms","start":"2026-07-30T21:01:41.891399Z","end":"2026-07-30T21:01:42.541005Z","steps":["trace[930202188] 'process raft request' (duration: 648.943851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:42.541036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.94983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-30T21:01:42.541055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:41.891382Z","time spent":"649.65143ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18032,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:42.541064Z","caller":"traceutil/trace.go:171","msg":"trace[1014062473] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"118.015342ms","start":"2026-07-30T21:01:42.42304Z","end":"2026-07-30T21:01:42.541056Z","steps":["trace[1014062473] 'agreement among raft nodes before linearized reading' (duration: 117.96138ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:46.976933Z","caller":"traceutil/trace.go:171","msg":"trace[1216722417] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"177.345107ms","start":"2026-07-30T21:01:46.799556Z","end":"2026-07-30T21:01:46.976901Z","steps":["trace[1216722417] 'process raft request' (duration: 177.08272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:49.110831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.24998321s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:01:49.111038Z","caller":"traceutil/trace.go:171","msg":"trace[1352930098] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"1.786852992s","start":"2026-07-30T21:01:47.324129Z","end":"2026-07-30T21:01:49.110982Z","steps":["trace[1352930098] 'process raft request' (duration: 536.221984ms)","trace[1352930098] 'compare' (duration: 1.249742564s)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:49.110968Z","caller":"traceutil/trace.go:171","msg":"trace[1444204603] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"1.608622431s","start":"2026-07-30T21:01:47.502323Z","end":"2026-07-30T21:01:49.110946Z","steps":["trace[1444204603] 'read index received' (duration: 358.119387ms)","trace[1444204603] 'applied index is now lower than readState.Index' (duration: 1.250501344s)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:49.111154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:47.324099Z","time spent":"1.787003806s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:01:49.111224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.608893778s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mhh6h\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-30T21:01:49.111291Z","caller":"traceutil/trace.go:171","msg":"trace[154073070] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mhh6h; range_end:; response_count:1; response_revision:630; }","duration":"1.608985981s","start":"2026-07-30T21:01:47.502283Z","end":"2026-07-30T21:01:49.111269Z","steps":["trace[154073070] 'agreement among raft nodes before linearized reading' (duration: 1.608797356s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:49.11135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:47.502255Z","time spent":"1.609082234s","remote":"127.0.0.1:43058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-mhh6h\" "} {"level":"warn","ts":"2026-07-30T21:01:49.611204Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874441393543495,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:01:50.112121Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874441393543495,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:01:50.16839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999824807s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-30T21:01:50.168492Z","caller":"traceutil/trace.go:171","msg":"trace[404670472] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999977511s","start":"2026-07-30T21:01:48.168475Z","end":"2026-07-30T21:01:50.168452Z","steps":["trace[404670472] 'agreement among raft nodes before linearized reading' (duration: 1.999859918s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:50.168548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:48.168449Z","time spent":"2.000088974s","remote":"127.0.0.1:42894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/30 21:01:50 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-30T21:01:50.612995Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874441393543495,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:01:50.748608Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.243125169s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-30T21:01:51.590652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.102272ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:01:51.590812Z","caller":"traceutil/trace.go:171","msg":"trace[1035105578] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"3.985532241s","start":"2026-07-30T21:01:47.605237Z","end":"2026-07-30T21:01:51.59077Z","steps":["trace[1035105578] 'process raft request' (duration: 3.144243304s)","trace[1035105578] 'compare' (duration: 839.611141ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:51.590877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:47.605213Z","time spent":"3.985640414s","remote":"127.0.0.1:42994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:01:52.072066Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.323070549s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-30T21:01:52.168831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999827478s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-30T21:01:52.168942Z","caller":"traceutil/trace.go:171","msg":"trace[1056556199] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999999873s","start":"2026-07-30T21:01:50.168919Z","end":"2026-07-30T21:01:52.168919Z","steps":["trace[1056556199] 'agreement among raft nodes before linearized reading' (duration: 1.999863309s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:52.169008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:50.168895Z","time spent":"2.000095635s","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/30 21:01:52 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-30T21:01:52.335904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.44242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:01:52.336323Z","caller":"traceutil/trace.go:171","msg":"trace[491577171] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"3.211547696s","start":"2026-07-30T21:01:49.124705Z","end":"2026-07-30T21:01:52.336253Z","steps":["trace[491577171] 'process raft request' (duration: 2.947647083s)","trace[491577171] 'compare' (duration: 263.214884ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:52.336568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:49.12465Z","time spent":"3.211788892s","remote":"127.0.0.1:43058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:01:52.444492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.439252ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-30T21:01:52.444919Z","caller":"traceutil/trace.go:171","msg":"trace[2108334150] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"270.932004ms","start":"2026-07-30T21:01:52.173955Z","end":"2026-07-30T21:01:52.444887Z","steps":["trace[2108334150] 'process raft request' (duration: 270.699968ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:52.445069Z","caller":"traceutil/trace.go:171","msg":"trace[523105013] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:657; }","duration":"3.333863075s","start":"2026-07-30T21:01:49.111138Z","end":"2026-07-30T21:01:52.445001Z","steps":["trace[523105013] 'read index received' (duration: 1.637963488s)","trace[523105013] 'applied index is now lower than readState.Index' (duration: 1.695897446s)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:52.445388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.46449216s","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-07-30T21:01:52.445437Z","caller":"traceutil/trace.go:171","msg":"trace[730527125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"3.464598653s","start":"2026-07-30T21:01:48.980825Z","end":"2026-07-30T21:01:52.445423Z","steps":["trace[730527125] 'agreement among raft nodes before linearized reading' (duration: 3.464361897s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:52.445475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:48.980804Z","time spent":"3.464662524s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T21:01:52.445485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.864237239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:01:52.445578Z","caller":"traceutil/trace.go:171","msg":"trace[498383962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"3.864343242s","start":"2026-07-30T21:01:48.581185Z","end":"2026-07-30T21:01:52.445528Z","steps":["trace[498383962] 'agreement among raft nodes before linearized reading' (duration: 3.864128386s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:52.44563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:48.581165Z","time spent":"3.864452755s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T21:01:52.445729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.204718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:01:52.445773Z","caller":"traceutil/trace.go:171","msg":"trace[118662236] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:633; }","duration":"786.29094ms","start":"2026-07-30T21:01:51.659468Z","end":"2026-07-30T21:01:52.445759Z","steps":["trace[118662236] 'agreement among raft nodes before linearized reading' (duration: 786.217588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:52.445805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:51.659441Z","time spent":"786.357892ms","remote":"127.0.0.1:43070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-30T21:01:52.445905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.856087268s","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-07-30T21:01:52.445955Z","caller":"traceutil/trace.go:171","msg":"trace[1727518270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"3.856159179s","start":"2026-07-30T21:01:48.589784Z","end":"2026-07-30T21:01:52.445944Z","steps":["trace[1727518270] 'agreement among raft nodes before linearized reading' (duration: 3.856039216s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:52.445992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:48.589773Z","time spent":"3.856211671s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T21:01:52.446006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.198802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:01:52.446039Z","caller":"traceutil/trace.go:171","msg":"trace[1782532237] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"267.247944ms","start":"2026-07-30T21:01:52.178782Z","end":"2026-07-30T21:01:52.44603Z","steps":["trace[1782532237] 'agreement among raft nodes before linearized reading' (duration: 267.194922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:52.446242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.856501409s","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-07-30T21:01:52.446295Z","caller":"traceutil/trace.go:171","msg":"trace[261827857] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"3.856654723s","start":"2026-07-30T21:01:48.589626Z","end":"2026-07-30T21:01:52.446281Z","steps":["trace[261827857] 'agreement among raft nodes before linearized reading' (duration: 3.856500329s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:52.446346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:48.589601Z","time spent":"3.856732285s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T21:01:53.946608Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.412723858s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T21:01:53.954253Z","caller":"traceutil/trace.go:171","msg":"trace[87040255] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.502898492s","start":"2026-07-30T21:01:52.451331Z","end":"2026-07-30T21:01:53.954229Z","steps":["trace[87040255] 'process raft request' (duration: 1.495433499s)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:53.954267Z","caller":"traceutil/trace.go:171","msg":"trace[1927664682] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.502277326s","start":"2026-07-30T21:01:52.451951Z","end":"2026-07-30T21:01:53.954228Z","steps":["trace[1927664682] 'process raft request' (duration: 1.502118682s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:53.954379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:52.451298Z","time spent":"1.503015135s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:53.954356Z","caller":"traceutil/trace.go:171","msg":"trace[544593514] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.502292786s","start":"2026-07-30T21:01:52.452025Z","end":"2026-07-30T21:01:53.954318Z","steps":["trace[544593514] 'process raft request' (duration: 1.502151942s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:53.954429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:52.451934Z","time spent":"1.50242405s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:01:53.954506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:52.452016Z","time spent":"1.50241242s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:01:54.668587Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874441393543501,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:01:55.096456Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.1437916s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T21:01:55.099471Z","caller":"traceutil/trace.go:171","msg":"trace[1552975181] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"2.239720416s","start":"2026-07-30T21:01:52.859728Z","end":"2026-07-30T21:01:55.099449Z","steps":["trace[1552975181] 'process raft request' (duration: 2.238018179s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:55.099618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:52.859705Z","time spent":"2.239838709s","remote":"127.0.0.1:42994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:55.099668Z","caller":"traceutil/trace.go:171","msg":"trace[633803728] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"2.134368139s","start":"2026-07-30T21:01:52.965266Z","end":"2026-07-30T21:01:55.099634Z","steps":["trace[633803728] 'process raft request' (duration: 2.134128082s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:55.099775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.79645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:01:55.09982Z","caller":"traceutil/trace.go:171","msg":"trace[936982326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"931.895453ms","start":"2026-07-30T21:01:54.167914Z","end":"2026-07-30T21:01:55.099809Z","steps":["trace[936982326] 'agreement among raft nodes before linearized reading' (duration: 931.79302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:55.099857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:54.167892Z","time spent":"931.957375ms","remote":"127.0.0.1:42894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:01:55.099848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:52.965241Z","time spent":"2.134500613s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:55.099675Z","caller":"traceutil/trace.go:171","msg":"trace[1876747839] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:665; }","duration":"931.616955ms","start":"2026-07-30T21:01:54.167958Z","end":"2026-07-30T21:01:55.099575Z","steps":["trace[1876747839] 'read index received' (duration: 928.646904ms)","trace[1876747839] 'applied index is now lower than readState.Index' (duration: 2.968061ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:55.100225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.580484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:01:55.100263Z","caller":"traceutil/trace.go:171","msg":"trace[1339076393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"561.643926ms","start":"2026-07-30T21:01:54.538607Z","end":"2026-07-30T21:01:55.100251Z","steps":["trace[1339076393] 'agreement among raft nodes before linearized reading' (duration: 561.573534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:55.10029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:54.538589Z","time spent":"561.694428ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T21:01:55.100572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.443869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T21:01:55.101604Z","caller":"traceutil/trace.go:171","msg":"trace[2053594141] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:639; }","duration":"541.500438ms","start":"2026-07-30T21:01:54.560084Z","end":"2026-07-30T21:01:55.101584Z","steps":["trace[2053594141] 'agreement among raft nodes before linearized reading' (duration: 540.144671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:55.101668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:54.560062Z","time spent":"541.590391ms","remote":"127.0.0.1:42934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-07-30T21:01:57.425754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.123036464s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:01:57.425902Z","caller":"traceutil/trace.go:171","msg":"trace[80569284] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:646; }","duration":"1.123208749s","start":"2026-07-30T21:01:56.302673Z","end":"2026-07-30T21:01:57.425881Z","steps":["trace[80569284] 'range keys from in-memory index tree' (duration: 1.123015284s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:57.426611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.667068111s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:01:57.426745Z","caller":"traceutil/trace.go:171","msg":"trace[228643230] linearizableReadLoop","detail":"{readStateIndex:677; appliedIndex:675; }","duration":"1.467637243s","start":"2026-07-30T21:01:55.959097Z","end":"2026-07-30T21:01:57.426734Z","steps":["trace[228643230] 'read index received' (duration: 195.772088ms)","trace[228643230] 'applied index is now lower than readState.Index' (duration: 1.271863975s)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:57.426837Z","caller":"traceutil/trace.go:171","msg":"trace[408829388] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.581028839s","start":"2026-07-30T21:01:55.845799Z","end":"2026-07-30T21:01:57.426828Z","steps":["trace[408829388] 'process raft request' (duration: 1.580882265s)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:57.426836Z","caller":"traceutil/trace.go:171","msg":"trace[1500805112] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"1.71260406s","start":"2026-07-30T21:01:55.714193Z","end":"2026-07-30T21:01:57.426797Z","steps":["trace[1500805112] 'process raft request' (duration: 45.291712ms)","trace[1500805112] 'compare' (duration: 1.666627049s)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:57.426891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:55.845742Z","time spent":"1.581124802s","remote":"127.0.0.1:42976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:01:57.426969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:55.714168Z","time spent":"1.712734924s","remote":"127.0.0.1:43196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-30T21:01:57.427238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.468041864s","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-07-30T21:01:57.427422Z","caller":"traceutil/trace.go:171","msg":"trace[1346789593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:648; }","duration":"1.468247519s","start":"2026-07-30T21:01:55.959148Z","end":"2026-07-30T21:01:57.427396Z","steps":["trace[1346789593] 'agreement among raft nodes before linearized reading' (duration: 1.467868879s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:57.427279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.260916287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:01:57.427476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.468377623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-30T21:01:57.4275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:55.959143Z","time spent":"1.468339741s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T21:01:57.427513Z","caller":"traceutil/trace.go:171","msg":"trace[1439240126] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:648; }","duration":"1.468446825s","start":"2026-07-30T21:01:55.959054Z","end":"2026-07-30T21:01:57.427501Z","steps":["trace[1439240126] 'agreement among raft nodes before linearized reading' (duration: 1.468304091s)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:57.427509Z","caller":"traceutil/trace.go:171","msg":"trace[368371155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"1.261177013s","start":"2026-07-30T21:01:56.166308Z","end":"2026-07-30T21:01:57.427485Z","steps":["trace[368371155] 'agreement among raft nodes before linearized reading' (duration: 1.260926337s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:57.427502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.468117876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-30T21:01:57.427537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.208596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-30T21:01:57.427556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:55.959034Z","time spent":"1.468514386s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T21:01:57.427576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:56.166289Z","time spent":"1.261266376s","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T21:01:57.427582Z","caller":"traceutil/trace.go:171","msg":"trace[1819799787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:648; }","duration":"1.468221689s","start":"2026-07-30T21:01:55.95934Z","end":"2026-07-30T21:01:57.427562Z","steps":["trace[1819799787] 'agreement among raft nodes before linearized reading' (duration: 1.467937601s)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:57.427594Z","caller":"traceutil/trace.go:171","msg":"trace[366341857] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:648; }","duration":"309.295748ms","start":"2026-07-30T21:01:57.118287Z","end":"2026-07-30T21:01:57.427582Z","steps":["trace[366341857] 'agreement among raft nodes before linearized reading' (duration: 309.177765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:57.427434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"894.168775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mhh6h\" ","response":"range_response_count:1 size:17247"} {"level":"warn","ts":"2026-07-30T21:01:57.427632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:57.118261Z","time spent":"309.362949ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T21:01:57.427632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:55.959332Z","time spent":"1.46829196s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-30T21:01:57.427659Z","caller":"traceutil/trace.go:171","msg":"trace[1736944022] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mhh6h; range_end:; response_count:1; response_revision:648; }","duration":"894.428892ms","start":"2026-07-30T21:01:56.53322Z","end":"2026-07-30T21:01:57.427649Z","steps":["trace[1736944022] 'agreement among raft nodes before linearized reading' (duration: 894.0028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:57.427698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:56.533204Z","time spent":"894.483904ms","remote":"127.0.0.1:43058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17270,"request content":"key:\"/registry/pods/kube-system/cilium-mhh6h\" "} {"level":"warn","ts":"2026-07-30T21:01:57.867772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.130394ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:01:57.868073Z","caller":"traceutil/trace.go:171","msg":"trace[1461304006] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"435.260266ms","start":"2026-07-30T21:01:57.432755Z","end":"2026-07-30T21:01:57.868015Z","steps":["trace[1461304006] 'process raft request' (duration: 312.811783ms)","trace[1461304006] 'compare' (duration: 121.96784ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:01:57.86823Z","caller":"traceutil/trace.go:171","msg":"trace[2074391284] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"434.436144ms","start":"2026-07-30T21:01:57.433772Z","end":"2026-07-30T21:01:57.868208Z","steps":["trace[2074391284] 'process raft request' (duration: 434.123006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:57.868452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:57.433722Z","time spent":"434.67238ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:57.868928Z","caller":"traceutil/trace.go:171","msg":"trace[1141862294] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"425.662285ms","start":"2026-07-30T21:01:57.443253Z","end":"2026-07-30T21:01:57.868916Z","steps":["trace[1141862294] 'process raft request' (duration: 425.584873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:57.868934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:57.432719Z","time spent":"435.444401ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:57.868935Z","caller":"traceutil/trace.go:171","msg":"trace[1907454001] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"432.190972ms","start":"2026-07-30T21:01:57.436716Z","end":"2026-07-30T21:01:57.868907Z","steps":["trace[1907454001] 'process raft request' (duration: 431.38935ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:57.869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:57.443236Z","time spent":"425.729667ms","remote":"127.0.0.1:43058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17416,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:01:57.869101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:57.436708Z","time spent":"432.316186ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:01:57.869557Z","caller":"traceutil/trace.go:171","msg":"trace[2100704512] linearizableReadLoop","detail":"{readStateIndex:680; appliedIndex:677; }","duration":"433.701283ms","start":"2026-07-30T21:01:57.435796Z","end":"2026-07-30T21:01:57.869497Z","steps":["trace[2100704512] 'read index received' (duration: 72.203525ms)","trace[2100704512] 'applied index is now lower than readState.Index' (duration: 361.494638ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:01:57.869691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.886189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:01:57.869742Z","caller":"traceutil/trace.go:171","msg":"trace[745904892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:653; }","duration":"433.977661ms","start":"2026-07-30T21:01:57.435751Z","end":"2026-07-30T21:01:57.869729Z","steps":["trace[745904892] 'agreement among raft nodes before linearized reading' (duration: 433.846788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:57.869743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.501283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:01:57.869776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:57.435736Z","time spent":"434.033132ms","remote":"127.0.0.1:42894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T21:01:57.869791Z","caller":"traceutil/trace.go:171","msg":"trace[386474224] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:653; }","duration":"431.562186ms","start":"2026-07-30T21:01:57.438211Z","end":"2026-07-30T21:01:57.869773Z","steps":["trace[386474224] 'agreement among raft nodes before linearized reading' (duration: 431.489764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:01:57.869828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:01:57.438204Z","time spent":"431.615657ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-07-30T21:01:58.40468Z","caller":"traceutil/trace.go:171","msg":"trace[1722136089] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"149.359115ms","start":"2026-07-30T21:01:58.255285Z","end":"2026-07-30T21:01:58.404644Z","steps":["trace[1722136089] 'process raft request' (duration: 148.582834ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:01:59.12441Z","caller":"traceutil/trace.go:171","msg":"trace[1772293992] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"134.203002ms","start":"2026-07-30T21:01:58.990171Z","end":"2026-07-30T21:01:59.124374Z","steps":["trace[1772293992] 'process raft request' (duration: 133.263317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:05.791115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.383664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/instance\" ","response":"range_response_count:1 size:2075"} {"level":"warn","ts":"2026-07-30T21:02:05.79115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.61774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/cilium-mhh6h.18c72d0fe83d7f64\" ","response":"range_response_count:1 size:795"} {"level":"info","ts":"2026-07-30T21:02:05.791212Z","caller":"traceutil/trace.go:171","msg":"trace[1117844583] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/instance; range_end:; response_count:1; response_revision:766; }","duration":"132.514957ms","start":"2026-07-30T21:02:05.658675Z","end":"2026-07-30T21:02:05.79119Z","steps":["trace[1117844583] 'range keys from in-memory index tree' (duration: 132.23347ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:05.791233Z","caller":"traceutil/trace.go:171","msg":"trace[831489222] range","detail":"{range_begin:/registry/events/kube-system/cilium-mhh6h.18c72d0fe83d7f64; range_end:; response_count:1; response_revision:766; }","duration":"168.754173ms","start":"2026-07-30T21:02:05.622458Z","end":"2026-07-30T21:02:05.791212Z","steps":["trace[831489222] 'range keys from in-memory index tree' (duration: 168.439914ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:09.645597Z","caller":"traceutil/trace.go:171","msg":"trace[1228783944] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"290.25086ms","start":"2026-07-30T21:02:09.355314Z","end":"2026-07-30T21:02:09.645564Z","steps":["trace[1228783944] 'process raft request' (duration: 290.026514ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:12.816258Z","caller":"traceutil/trace.go:171","msg":"trace[1597591903] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"580.647093ms","start":"2026-07-30T21:02:12.235584Z","end":"2026-07-30T21:02:12.816231Z","steps":["trace[1597591903] 'process raft request' (duration: 580.448738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:12.816434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:12.235556Z","time spent":"580.805087ms","remote":"127.0.0.1:42994","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4622,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T21:02:12.820067Z","caller":"traceutil/trace.go:171","msg":"trace[652030036] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"125.084004ms","start":"2026-07-30T21:02:12.694955Z","end":"2026-07-30T21:02:12.820039Z","steps":["trace[652030036] 'process raft request' (duration: 124.9227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:14.205356Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874441393543936,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T21:02:14.375223Z","caller":"traceutil/trace.go:171","msg":"trace[250749045] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"669.879042ms","start":"2026-07-30T21:02:13.70528Z","end":"2026-07-30T21:02:14.375159Z","steps":["trace[250749045] 'read index received' (duration: 669.507992ms)","trace[250749045] 'applied index is now lower than readState.Index' (duration: 366.09µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:14.375315Z","caller":"traceutil/trace.go:171","msg":"trace[1675580624] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"670.495029ms","start":"2026-07-30T21:02:13.704777Z","end":"2026-07-30T21:02:14.375272Z","steps":["trace[1675580624] 'process raft request' (duration: 670.142819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:14.37549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:13.704746Z","time spent":"670.648843ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:02:14.375571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.572038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:02:14.375626Z","caller":"traceutil/trace.go:171","msg":"trace[655878607] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:820; }","duration":"581.691812ms","start":"2026-07-30T21:02:13.793915Z","end":"2026-07-30T21:02:14.375607Z","steps":["trace[655878607] 'agreement among raft nodes before linearized reading' (duration: 581.492917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:14.375676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:13.793897Z","time spent":"581.766293ms","remote":"127.0.0.1:43218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:02:14.375703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.377786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-30T21:02:14.375788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.439598ms","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-07-30T21:02:14.375833Z","caller":"traceutil/trace.go:171","msg":"trace[562386553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:820; }","duration":"379.51933ms","start":"2026-07-30T21:02:13.996298Z","end":"2026-07-30T21:02:14.375818Z","steps":["trace[562386553] 'agreement among raft nodes before linearized reading' (duration: 379.325904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:14.375837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.877068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:02:14.375842Z","caller":"traceutil/trace.go:171","msg":"trace[1997459700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:820; }","duration":"670.54979ms","start":"2026-07-30T21:02:13.705256Z","end":"2026-07-30T21:02:14.375806Z","steps":["trace[1997459700] 'agreement among raft nodes before linearized reading' (duration: 670.096598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:14.375865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:13.996271Z","time spent":"379.588451ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T21:02:14.375892Z","caller":"traceutil/trace.go:171","msg":"trace[1072181290] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:820; }","duration":"207.96535ms","start":"2026-07-30T21:02:14.167904Z","end":"2026-07-30T21:02:14.375869Z","steps":["trace[1072181290] 'agreement among raft nodes before linearized reading' (duration: 207.895148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:14.375934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:13.705189Z","time spent":"670.729885ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T21:02:14.376014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.910135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:02:14.37605Z","caller":"traceutil/trace.go:171","msg":"trace[1839279966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:820; }","duration":"413.982477ms","start":"2026-07-30T21:02:13.962057Z","end":"2026-07-30T21:02:14.376039Z","steps":["trace[1839279966] 'agreement among raft nodes before linearized reading' (duration: 413.898634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:14.376083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:13.962025Z","time spent":"414.050569ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T21:02:14.375655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.340987ms","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-07-30T21:02:14.376171Z","caller":"traceutil/trace.go:171","msg":"trace[2104251471] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:820; }","duration":"378.888052ms","start":"2026-07-30T21:02:13.997265Z","end":"2026-07-30T21:02:14.376153Z","steps":["trace[2104251471] 'agreement among raft nodes before linearized reading' (duration: 378.177192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:14.376221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:13.997251Z","time spent":"378.955464ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T21:02:36.235581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.145966ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:02:36.235744Z","caller":"traceutil/trace.go:171","msg":"trace[1806669158] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"257.831537ms","start":"2026-07-30T21:02:35.977886Z","end":"2026-07-30T21:02:36.235718Z","steps":["trace[1806669158] 'read index received' (duration: 45.401µs)","trace[1806669158] 'applied index is now lower than readState.Index' (duration: 257.784056ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:36.236209Z","caller":"traceutil/trace.go:171","msg":"trace[482310302] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"374.79435ms","start":"2026-07-30T21:02:35.86136Z","end":"2026-07-30T21:02:36.236154Z","steps":["trace[482310302] 'process raft request' (duration: 40.923314ms)","trace[482310302] 'compare' (duration: 332.873578ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:02:36.236872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:35.861331Z","time spent":"375.394186ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:02:36.236501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.620228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:02:36.237513Z","caller":"traceutil/trace.go:171","msg":"trace[1033027121] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:988; }","duration":"259.665657ms","start":"2026-07-30T21:02:35.97782Z","end":"2026-07-30T21:02:36.237486Z","steps":["trace[1033027121] 'agreement among raft nodes before linearized reading' (duration: 258.520546ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:36.686822Z","caller":"traceutil/trace.go:171","msg":"trace[1034826167] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"116.450289ms","start":"2026-07-30T21:02:36.570345Z","end":"2026-07-30T21:02:36.686795Z","steps":["trace[1034826167] 'process raft request' (duration: 45.032495ms)","trace[1034826167] 'compare' (duration: 71.25754ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:36.687169Z","caller":"traceutil/trace.go:171","msg":"trace[858003010] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"116.549992ms","start":"2026-07-30T21:02:36.570608Z","end":"2026-07-30T21:02:36.687158Z","steps":["trace[858003010] 'process raft request' (duration: 116.153851ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:36.687754Z","caller":"traceutil/trace.go:171","msg":"trace[468800831] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"116.13143ms","start":"2026-07-30T21:02:36.571597Z","end":"2026-07-30T21:02:36.687728Z","steps":["trace[468800831] 'process raft request' (duration: 115.424601ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:43.926541Z","caller":"traceutil/trace.go:171","msg":"trace[1943757864] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"111.031531ms","start":"2026-07-30T21:02:43.815489Z","end":"2026-07-30T21:02:43.926521Z","steps":["trace[1943757864] 'process raft request' (duration: 110.896887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.231619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.531294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-30T21:02:49.23171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.601747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-30T21:02:49.231706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.519759ms","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-07-30T21:02:49.231751Z","caller":"traceutil/trace.go:171","msg":"trace[241009759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1034; }","duration":"457.761571ms","start":"2026-07-30T21:02:48.773964Z","end":"2026-07-30T21:02:49.231726Z","steps":["trace[241009759] 'range keys from in-memory index tree' (duration: 457.340969ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:49.231791Z","caller":"traceutil/trace.go:171","msg":"trace[679245317] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1034; }","duration":"459.71022ms","start":"2026-07-30T21:02:48.77206Z","end":"2026-07-30T21:02:49.231771Z","steps":["trace[679245317] 'range keys from in-memory index tree' (duration: 459.454023ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:49.231805Z","caller":"traceutil/trace.go:171","msg":"trace[13373625] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1034; }","duration":"468.698984ms","start":"2026-07-30T21:02:48.763087Z","end":"2026-07-30T21:02:49.231786Z","steps":["trace[13373625] 'range keys from in-memory index tree' (duration: 468.266321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.231818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:48.773956Z","time spent":"457.851354ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T21:02:49.231837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:48.77205Z","time spent":"459.777032ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T21:02:49.231852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:48.76306Z","time spent":"468.783647ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T21:02:49.813605Z","caller":"traceutil/trace.go:171","msg":"trace[214511247] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"577.315272ms","start":"2026-07-30T21:02:49.23627Z","end":"2026-07-30T21:02:49.813585Z","steps":["trace[214511247] 'process raft request' (duration: 577.173267ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:49.813733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:49.236252Z","time spent":"577.411725ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:02:51.010639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.955404ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:02:51.010884Z","caller":"traceutil/trace.go:171","msg":"trace[1218584644] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"1.77318071s","start":"2026-07-30T21:02:49.23768Z","end":"2026-07-30T21:02:51.01086Z","steps":["trace[1218584644] 'process raft request' (duration: 1.773090908s)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:02:51.010936Z","caller":"traceutil/trace.go:171","msg":"trace[1037499827] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"1.774182021s","start":"2026-07-30T21:02:49.236677Z","end":"2026-07-30T21:02:51.010859Z","steps":["trace[1037499827] 'process raft request' (duration: 1.078862296s)","trace[1037499827] 'compare' (duration: 694.698696ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:02:51.010965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:49.237659Z","time spent":"1.773265103s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:02:51.011163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:49.236647Z","time spent":"1.774377587s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:02:51.198704Z","caller":"traceutil/trace.go:171","msg":"trace[573925004] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1077; }","duration":"1.030537334s","start":"2026-07-30T21:02:50.168146Z","end":"2026-07-30T21:02:51.198683Z","steps":["trace[573925004] 'read index received' (duration: 147.474151ms)","trace[573925004] 'applied index is now lower than readState.Index' (duration: 883.062353ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:02:51.198816Z","caller":"traceutil/trace.go:171","msg":"trace[718808718] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"1.16604169s","start":"2026-07-30T21:02:50.032764Z","end":"2026-07-30T21:02:51.198806Z","steps":["trace[718808718] 'process raft request' (duration: 1.165763212s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:51.198912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:50.032732Z","time spent":"1.166108872s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:02:51.198913Z","caller":"traceutil/trace.go:171","msg":"trace[980801993] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"119.772168ms","start":"2026-07-30T21:02:51.07911Z","end":"2026-07-30T21:02:51.198882Z","steps":["trace[980801993] 'process raft request' (duration: 119.540591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:51.199078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030940456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:02:51.199121Z","caller":"traceutil/trace.go:171","msg":"trace[1257348504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1039; }","duration":"1.031022469s","start":"2026-07-30T21:02:50.16809Z","end":"2026-07-30T21:02:51.199112Z","steps":["trace[1257348504] 'agreement among raft nodes before linearized reading' (duration: 1.030946496s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:51.199145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:50.168063Z","time spent":"1.03107654s","remote":"127.0.0.1:42894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:02:51.199154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.002792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:02:51.199183Z","caller":"traceutil/trace.go:171","msg":"trace[560478619] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1039; }","duration":"818.092754ms","start":"2026-07-30T21:02:50.38108Z","end":"2026-07-30T21:02:51.199173Z","steps":["trace[560478619] 'agreement among raft nodes before linearized reading' (duration: 818.030333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:51.199206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:50.381061Z","time spent":"818.138446ms","remote":"127.0.0.1:43036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:02:51.19944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.673977ms","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-07-30T21:02:51.199502Z","caller":"traceutil/trace.go:171","msg":"trace[1024350797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1039; }","duration":"563.78635ms","start":"2026-07-30T21:02:50.635696Z","end":"2026-07-30T21:02:51.199483Z","steps":["trace[1024350797] 'agreement among raft nodes before linearized reading' (duration: 563.617165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:51.199538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:50.635677Z","time spent":"563.853122ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T21:02:51.199576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.635353ms","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-07-30T21:02:51.199601Z","caller":"traceutil/trace.go:171","msg":"trace[1363968078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1039; }","duration":"537.706766ms","start":"2026-07-30T21:02:50.661887Z","end":"2026-07-30T21:02:51.199594Z","steps":["trace[1363968078] 'agreement among raft nodes before linearized reading' (duration: 537.616723ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:02:51.19962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:02:50.661861Z","time spent":"537.754067ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T21:02:57.057792Z","caller":"traceutil/trace.go:171","msg":"trace[852080963] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"217.344529ms","start":"2026-07-30T21:02:56.840425Z","end":"2026-07-30T21:02:57.057769Z","steps":["trace[852080963] 'process raft request' (duration: 217.215825ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:03.500957Z","caller":"traceutil/trace.go:171","msg":"trace[1027159299] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1121; }","duration":"165.108579ms","start":"2026-07-30T21:03:03.335822Z","end":"2026-07-30T21:03:03.500931Z","steps":["trace[1027159299] 'read index received' (duration: 56.442429ms)","trace[1027159299] 'applied index is now lower than readState.Index' (duration: 108.66514ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:03.501018Z","caller":"traceutil/trace.go:171","msg":"trace[74716137] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"169.374588ms","start":"2026-07-30T21:03:03.331602Z","end":"2026-07-30T21:03:03.500977Z","steps":["trace[74716137] 'process raft request' (duration: 121.866071ms)","trace[74716137] 'compare' (duration: 47.359163ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:03:03.501227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.371407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-30T21:03:03.501257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.155825ms","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-07-30T21:03:03.501306Z","caller":"traceutil/trace.go:171","msg":"trace[2092958538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1078; }","duration":"165.494161ms","start":"2026-07-30T21:03:03.335787Z","end":"2026-07-30T21:03:03.501282Z","steps":["trace[2092958538] 'agreement among raft nodes before linearized reading' (duration: 165.260713ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:03.501315Z","caller":"traceutil/trace.go:171","msg":"trace[1691277875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1078; }","duration":"132.250558ms","start":"2026-07-30T21:03:03.369048Z","end":"2026-07-30T21:03:03.501299Z","steps":["trace[1691277875] 'agreement among raft nodes before linearized reading' (duration: 132.047562ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:03.748303Z","caller":"traceutil/trace.go:171","msg":"trace[1523112522] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"243.788805ms","start":"2026-07-30T21:03:03.50449Z","end":"2026-07-30T21:03:03.748279Z","steps":["trace[1523112522] 'process raft request' (duration: 243.654321ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:03.779144Z","caller":"traceutil/trace.go:171","msg":"trace[451629333] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"274.568402ms","start":"2026-07-30T21:03:03.504557Z","end":"2026-07-30T21:03:03.779125Z","steps":["trace[451629333] 'process raft request' (duration: 274.466579ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:04.989365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.743145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T21:03:04.989449Z","caller":"traceutil/trace.go:171","msg":"trace[1753380463] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:1080; }","duration":"426.89437ms","start":"2026-07-30T21:03:04.562535Z","end":"2026-07-30T21:03:04.98943Z","steps":["trace[1753380463] 'range keys from in-memory index tree' (duration: 426.521108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:04.989495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:04.562515Z","time spent":"426.970513ms","remote":"127.0.0.1:42934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.248\" "} {"level":"warn","ts":"2026-07-30T21:03:04.989567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.22094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:03:04.989597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.278123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:03:04.989656Z","caller":"traceutil/trace.go:171","msg":"trace[871495839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1080; }","duration":"823.388005ms","start":"2026-07-30T21:03:04.16625Z","end":"2026-07-30T21:03:04.989638Z","steps":["trace[871495839] 'range keys from in-memory index tree' (duration: 823.127827ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:04.989672Z","caller":"traceutil/trace.go:171","msg":"trace[1636992947] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1080; }","duration":"936.391347ms","start":"2026-07-30T21:03:04.05326Z","end":"2026-07-30T21:03:04.989651Z","steps":["trace[1636992947] 'count revisions from in-memory index tree' (duration: 936.183131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:04.989702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:04.166234Z","time spent":"823.458727ms","remote":"127.0.0.1:42894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:03:04.989672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.001369185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-30T21:03:04.989726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:04.053243Z","time spent":"936.47232ms","remote":"127.0.0.1:42950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"info","ts":"2026-07-30T21:03:04.989758Z","caller":"traceutil/trace.go:171","msg":"trace[1272884591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1080; }","duration":"1.001492049s","start":"2026-07-30T21:03:03.988248Z","end":"2026-07-30T21:03:04.98974Z","steps":["trace[1272884591] 'range keys from in-memory index tree' (duration: 1.00118323s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:04.989799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:03.988231Z","time spent":"1.001558381s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T21:03:13.252285Z","caller":"traceutil/trace.go:171","msg":"trace[1365345748] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"202.566519ms","start":"2026-07-30T21:03:13.049694Z","end":"2026-07-30T21:03:13.25226Z","steps":["trace[1365345748] 'process raft request' (duration: 201.751354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:15.787666Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874441393544595,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T21:03:15.98997Z","caller":"traceutil/trace.go:171","msg":"trace[1951190542] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"729.859257ms","start":"2026-07-30T21:03:15.260089Z","end":"2026-07-30T21:03:15.989948Z","steps":["trace[1951190542] 'process raft request' (duration: 729.717193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:15.990245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:15.260068Z","time spent":"729.96327ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:03:16.632225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.684511ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:03:16.632325Z","caller":"traceutil/trace.go:171","msg":"trace[812987827] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1115; }","duration":"329.806715ms","start":"2026-07-30T21:03:16.302499Z","end":"2026-07-30T21:03:16.632305Z","steps":["trace[812987827] 'range keys from in-memory index tree' (duration: 329.66592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:16.632911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.040667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:03:16.632981Z","caller":"traceutil/trace.go:171","msg":"trace[1050896421] linearizableReadLoop","detail":"{readStateIndex:1164; appliedIndex:1162; }","duration":"1.34563203s","start":"2026-07-30T21:03:15.287337Z","end":"2026-07-30T21:03:16.632969Z","steps":["trace[1050896421] 'read index received' (duration: 702.471282ms)","trace[1050896421] 'applied index is now lower than readState.Index' (duration: 643.159988ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:16.633055Z","caller":"traceutil/trace.go:171","msg":"trace[1452253328] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"1.09736126s","start":"2026-07-30T21:03:15.535685Z","end":"2026-07-30T21:03:16.633046Z","steps":["trace[1452253328] 'process raft request' (duration: 931.121997ms)","trace[1452253328] 'compare' (duration: 165.712957ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:03:16.633094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:15.535655Z","time spent":"1.097417012s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:03:16.633253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.446737ms","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-07-30T21:03:16.633272Z","caller":"traceutil/trace.go:171","msg":"trace[486079050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1116; }","duration":"756.499219ms","start":"2026-07-30T21:03:15.876766Z","end":"2026-07-30T21:03:16.633266Z","steps":["trace[486079050] 'agreement among raft nodes before linearized reading' (duration: 756.416526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:16.633289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:15.876751Z","time spent":"756.53427ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T21:03:16.633307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190128786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-30T21:03:16.633325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.345973241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-30T21:03:16.633367Z","caller":"traceutil/trace.go:171","msg":"trace[1078820396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1116; }","duration":"1.190234629s","start":"2026-07-30T21:03:15.443121Z","end":"2026-07-30T21:03:16.633356Z","steps":["trace[1078820396] 'agreement among raft nodes before linearized reading' (duration: 1.190055433s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:16.633346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.383775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-30T21:03:16.633358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.692293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:03:16.633395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:15.443101Z","time spent":"1.190287021s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-30T21:03:16.633409Z","caller":"traceutil/trace.go:171","msg":"trace[1585352120] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1116; }","duration":"1.346072523s","start":"2026-07-30T21:03:15.287302Z","end":"2026-07-30T21:03:16.633375Z","steps":["trace[1585352120] 'agreement among raft nodes before linearized reading' (duration: 1.345867667s)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:16.633417Z","caller":"traceutil/trace.go:171","msg":"trace[1867072506] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1116; }","duration":"820.487948ms","start":"2026-07-30T21:03:15.81291Z","end":"2026-07-30T21:03:16.633398Z","steps":["trace[1867072506] 'agreement among raft nodes before linearized reading' (duration: 820.277241ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:16.633432Z","caller":"traceutil/trace.go:171","msg":"trace[503475874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1116; }","duration":"465.804566ms","start":"2026-07-30T21:03:16.167606Z","end":"2026-07-30T21:03:16.633411Z","steps":["trace[503475874] 'agreement among raft nodes before linearized reading' (duration: 465.658382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:16.633506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:15.812893Z","time spent":"820.560601ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T21:03:16.633454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:15.287292Z","time spent":"1.346152976s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-30T21:03:16.633524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:16.167591Z","time spent":"465.920319ms","remote":"127.0.0.1:42894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T21:03:16.846518Z","caller":"traceutil/trace.go:171","msg":"trace[1749537692] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"206.989184ms","start":"2026-07-30T21:03:16.639494Z","end":"2026-07-30T21:03:16.846483Z","steps":["trace[1749537692] 'process raft request' (duration: 204.768567ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:16.846509Z","caller":"traceutil/trace.go:171","msg":"trace[1465697995] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"206.423986ms","start":"2026-07-30T21:03:16.640057Z","end":"2026-07-30T21:03:16.846481Z","steps":["trace[1465697995] 'process raft request' (duration: 206.333273ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:16.847126Z","caller":"traceutil/trace.go:171","msg":"trace[1909617575] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"206.630403ms","start":"2026-07-30T21:03:16.640465Z","end":"2026-07-30T21:03:16.847095Z","steps":["trace[1909617575] 'process raft request' (duration: 205.976123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:18.494866Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874441393544605,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:03:18.635769Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.328590223s","expected-duration":"1s"} {"level":"info","ts":"2026-07-30T21:03:18.636275Z","caller":"traceutil/trace.go:171","msg":"trace[1706754260] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1168; }","duration":"642.069835ms","start":"2026-07-30T21:03:17.994149Z","end":"2026-07-30T21:03:18.636218Z","steps":["trace[1706754260] 'read index received' (duration: 641.823967ms)","trace[1706754260] 'applied index is now lower than readState.Index' (duration: 244.448µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:18.636671Z","caller":"traceutil/trace.go:171","msg":"trace[1260117123] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"1.329586063s","start":"2026-07-30T21:03:17.307064Z","end":"2026-07-30T21:03:18.63665Z","steps":["trace[1260117123] 'process raft request' (duration: 1.328991145s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:18.636768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.380385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:03:18.636852Z","caller":"traceutil/trace.go:171","msg":"trace[764675617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1121; }","duration":"468.57909ms","start":"2026-07-30T21:03:18.168253Z","end":"2026-07-30T21:03:18.636832Z","steps":["trace[764675617] 'agreement among raft nodes before linearized reading' (duration: 468.390205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:18.636844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:17.307043Z","time spent":"1.329698306s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:03:18.636906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:18.168214Z","time spent":"468.674043ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:03:18.636899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.737264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-30T21:03:18.636969Z","caller":"traceutil/trace.go:171","msg":"trace[2104494934] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1121; }","duration":"642.872689ms","start":"2026-07-30T21:03:17.994081Z","end":"2026-07-30T21:03:18.636954Z","steps":["trace[2104494934] 'agreement among raft nodes before linearized reading' (duration: 642.632302ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:18.637007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:17.994055Z","time spent":"642.94366ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-30T21:03:19.017008Z","caller":"traceutil/trace.go:171","msg":"trace[1990486349] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"103.963225ms","start":"2026-07-30T21:03:18.913023Z","end":"2026-07-30T21:03:19.016986Z","steps":["trace[1990486349] 'process raft request' (duration: 103.768489ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:19.017177Z","caller":"traceutil/trace.go:171","msg":"trace[479173246] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"104.14504ms","start":"2026-07-30T21:03:18.913023Z","end":"2026-07-30T21:03:19.017168Z","steps":["trace[479173246] 'process raft request' (duration: 103.932234ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:19.033004Z","caller":"traceutil/trace.go:171","msg":"trace[1195938629] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"119.663393ms","start":"2026-07-30T21:03:18.913317Z","end":"2026-07-30T21:03:19.032981Z","steps":["trace[1195938629] 'process raft request' (duration: 119.553499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:19.654458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.477466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:03:19.654542Z","caller":"traceutil/trace.go:171","msg":"trace[1224369651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1127; }","duration":"486.834717ms","start":"2026-07-30T21:03:19.167688Z","end":"2026-07-30T21:03:19.654523Z","steps":["trace[1224369651] 'range keys from in-memory index tree' (duration: 486.162736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:19.654582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:19.167666Z","time spent":"486.906748ms","remote":"127.0.0.1:42894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T21:03:20.682807Z","caller":"traceutil/trace.go:171","msg":"trace[1156590107] linearizableReadLoop","detail":"{readStateIndex:1176; appliedIndex:1175; }","duration":"177.67391ms","start":"2026-07-30T21:03:20.505106Z","end":"2026-07-30T21:03:20.68278Z","steps":["trace[1156590107] 'read index received' (duration: 160.490417ms)","trace[1156590107] 'applied index is now lower than readState.Index' (duration: 17.181783ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:03:20.682907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.779284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:03:20.682985Z","caller":"traceutil/trace.go:171","msg":"trace[1687600478] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1127; }","duration":"177.910688ms","start":"2026-07-30T21:03:20.505055Z","end":"2026-07-30T21:03:20.682966Z","steps":["trace[1687600478] 'agreement among raft nodes before linearized reading' (duration: 177.767254ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:20.882954Z","caller":"traceutil/trace.go:171","msg":"trace[836683104] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1177; }","duration":"113.869048ms","start":"2026-07-30T21:03:20.769046Z","end":"2026-07-30T21:03:20.882915Z","steps":["trace[836683104] 'read index received' (duration: 19.975168ms)","trace[836683104] 'applied index is now lower than readState.Index' (duration: 93.89284ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:03:20.883178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.116166ms","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-07-30T21:03:20.883217Z","caller":"traceutil/trace.go:171","msg":"trace[484868874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1129; }","duration":"114.203448ms","start":"2026-07-30T21:03:20.769004Z","end":"2026-07-30T21:03:20.883207Z","steps":["trace[484868874] 'agreement among raft nodes before linearized reading' (duration: 114.029042ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:20.88338Z","caller":"traceutil/trace.go:171","msg":"trace[1773972475] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"138.905941ms","start":"2026-07-30T21:03:20.744441Z","end":"2026-07-30T21:03:20.883347Z","steps":["trace[1773972475] 'process raft request' (duration: 44.626979ms)","trace[1773972475] 'compare' (duration: 93.716025ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:21.274621Z","caller":"traceutil/trace.go:171","msg":"trace[1911677374] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"214.08565ms","start":"2026-07-30T21:03:21.060512Z","end":"2026-07-30T21:03:21.274598Z","steps":["trace[1911677374] 'process raft request' (duration: 213.944806ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:21.301255Z","caller":"traceutil/trace.go:171","msg":"trace[182199658] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"240.695421ms","start":"2026-07-30T21:03:21.060539Z","end":"2026-07-30T21:03:21.301234Z","steps":["trace[182199658] 'process raft request' (duration: 240.537886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:21.301638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.463055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:03:21.301694Z","caller":"traceutil/trace.go:171","msg":"trace[1287690968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1133; }","duration":"134.584539ms","start":"2026-07-30T21:03:21.167098Z","end":"2026-07-30T21:03:21.301683Z","steps":["trace[1287690968] 'agreement among raft nodes before linearized reading' (duration: 134.473436ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:21.301467Z","caller":"traceutil/trace.go:171","msg":"trace[910478473] transaction","detail":"{read_only:false; response_revision:1133; number_of_response:1; }","duration":"239.624178ms","start":"2026-07-30T21:03:21.061819Z","end":"2026-07-30T21:03:21.301443Z","steps":["trace[910478473] 'process raft request' (duration: 239.36197ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:21.301467Z","caller":"traceutil/trace.go:171","msg":"trace[893328194] linearizableReadLoop","detail":"{readStateIndex:1182; appliedIndex:1179; }","duration":"134.27774ms","start":"2026-07-30T21:03:21.167162Z","end":"2026-07-30T21:03:21.30144Z","steps":["trace[893328194] 'read index received' (duration: 107.319429ms)","trace[893328194] 'applied index is now lower than readState.Index' (duration: 26.955871ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:03:21.302107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.269389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:03:21.302164Z","caller":"traceutil/trace.go:171","msg":"trace[33078758] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1133; }","duration":"111.356531ms","start":"2026-07-30T21:03:21.190788Z","end":"2026-07-30T21:03:21.302145Z","steps":["trace[33078758] 'agreement among raft nodes before linearized reading' (duration: 111.267759ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:25.002284Z","caller":"traceutil/trace.go:171","msg":"trace[1149411291] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1191; }","duration":"108.183684ms","start":"2026-07-30T21:03:24.894055Z","end":"2026-07-30T21:03:25.002239Z","steps":["trace[1149411291] 'read index received' (duration: 107.921266ms)","trace[1149411291] 'applied index is now lower than readState.Index' (duration: 260.698µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:25.002346Z","caller":"traceutil/trace.go:171","msg":"trace[1772649582] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"250.719526ms","start":"2026-07-30T21:03:24.751595Z","end":"2026-07-30T21:03:25.002314Z","steps":["trace[1772649582] 'process raft request' (duration: 250.397796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:25.002642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.545636ms","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-07-30T21:03:25.002689Z","caller":"traceutil/trace.go:171","msg":"trace[1301754407] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1142; }","duration":"108.651289ms","start":"2026-07-30T21:03:24.894026Z","end":"2026-07-30T21:03:25.002677Z","steps":["trace[1301754407] 'agreement among raft nodes before linearized reading' (duration: 108.437792ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:25.14036Z","caller":"traceutil/trace.go:171","msg":"trace[425683554] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"133.182466ms","start":"2026-07-30T21:03:25.007149Z","end":"2026-07-30T21:03:25.140332Z","steps":["trace[425683554] 'process raft request' (duration: 133.020921ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:25.161184Z","caller":"traceutil/trace.go:171","msg":"trace[1188403943] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"153.404052ms","start":"2026-07-30T21:03:25.007754Z","end":"2026-07-30T21:03:25.161158Z","steps":["trace[1188403943] 'process raft request' (duration: 153.256678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:25.433736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.917661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:03:25.433834Z","caller":"traceutil/trace.go:171","msg":"trace[1753401607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1144; }","duration":"119.075307ms","start":"2026-07-30T21:03:25.314735Z","end":"2026-07-30T21:03:25.43381Z","steps":["trace[1753401607] 'range keys from in-memory index tree' (duration: 118.662354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:25.433921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.825009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T21:03:25.43401Z","caller":"traceutil/trace.go:171","msg":"trace[1094218390] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1144; }","duration":"119.984884ms","start":"2026-07-30T21:03:25.314007Z","end":"2026-07-30T21:03:25.433992Z","steps":["trace[1094218390] 'range keys from in-memory index tree' (duration: 119.359235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:25.43499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.788281ms","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-07-30T21:03:25.435041Z","caller":"traceutil/trace.go:171","msg":"trace[225067076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1144; }","duration":"147.925525ms","start":"2026-07-30T21:03:25.287102Z","end":"2026-07-30T21:03:25.435028Z","steps":["trace[225067076] 'range keys from in-memory index tree' (duration: 146.981696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:39.663373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.677962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-30T21:03:39.66339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.176736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T21:03:39.663437Z","caller":"traceutil/trace.go:171","msg":"trace[2028548305] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1241; }","duration":"145.781005ms","start":"2026-07-30T21:03:39.517641Z","end":"2026-07-30T21:03:39.663422Z","steps":["trace[2028548305] 'range keys from in-memory index tree' (duration: 145.507798ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:39.663471Z","caller":"traceutil/trace.go:171","msg":"trace[649826449] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1241; }","duration":"146.3225ms","start":"2026-07-30T21:03:39.517127Z","end":"2026-07-30T21:03:39.66345Z","steps":["trace[649826449] 'range keys from in-memory index tree' (duration: 145.930989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:39.663518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.681229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:03:39.663615Z","caller":"traceutil/trace.go:171","msg":"trace[1605210017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1241; }","duration":"495.791681ms","start":"2026-07-30T21:03:39.167786Z","end":"2026-07-30T21:03:39.663577Z","steps":["trace[1605210017] 'range keys from in-memory index tree' (duration: 495.616606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:39.663659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:39.167773Z","time spent":"495.878104ms","remote":"127.0.0.1:42892","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:03:39.663808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.609236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-30T21:03:39.663889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.042533ms","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-07-30T21:03:39.663979Z","caller":"traceutil/trace.go:171","msg":"trace[1893316465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1241; }","duration":"149.182298ms","start":"2026-07-30T21:03:39.514783Z","end":"2026-07-30T21:03:39.663966Z","steps":["trace[1893316465] 'range keys from in-memory index tree' (duration: 148.888669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:39.664012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.580562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-30T21:03:39.664039Z","caller":"traceutil/trace.go:171","msg":"trace[714499011] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1241; }","duration":"409.642683ms","start":"2026-07-30T21:03:39.254389Z","end":"2026-07-30T21:03:39.664031Z","steps":["trace[714499011] 'range keys from in-memory index tree' (duration: 409.443219ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:39.663839Z","caller":"traceutil/trace.go:171","msg":"trace[338559777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1241; }","duration":"408.670067ms","start":"2026-07-30T21:03:39.255161Z","end":"2026-07-30T21:03:39.663831Z","steps":["trace[338559777] 'range keys from in-memory index tree' (duration: 408.474842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:39.664127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:39.255145Z","time spent":"408.968616ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-30T21:03:39.664074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:39.25437Z","time spent":"409.683005ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-30T21:03:41.665613Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874441393544890,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T21:03:41.915751Z","caller":"traceutil/trace.go:171","msg":"trace[1010585745] linearizableReadLoop","detail":"{readStateIndex:1302; appliedIndex:1301; }","duration":"750.989085ms","start":"2026-07-30T21:03:41.164744Z","end":"2026-07-30T21:03:41.915734Z","steps":["trace[1010585745] 'read index received' (duration: 750.889392ms)","trace[1010585745] 'applied index is now lower than readState.Index' (duration: 99.173µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:41.915932Z","caller":"traceutil/trace.go:171","msg":"trace[1461056821] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"842.266392ms","start":"2026-07-30T21:03:41.073643Z","end":"2026-07-30T21:03:41.91591Z","steps":["trace[1461056821] 'process raft request' (duration: 841.977494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:41.915992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.905755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-30T21:03:41.915977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.218512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:03:41.916062Z","caller":"traceutil/trace.go:171","msg":"trace[1193650777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"751.354616ms","start":"2026-07-30T21:03:41.164692Z","end":"2026-07-30T21:03:41.916047Z","steps":["trace[1193650777] 'agreement among raft nodes before linearized reading' (duration: 751.16006ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:41.916086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.018704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-30T21:03:41.916082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.288245ms","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-07-30T21:03:41.91612Z","caller":"traceutil/trace.go:171","msg":"trace[1458305558] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1247; }","duration":"180.076025ms","start":"2026-07-30T21:03:41.736035Z","end":"2026-07-30T21:03:41.916111Z","steps":["trace[1458305558] 'agreement among raft nodes before linearized reading' (duration: 179.988043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:41.916059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:41.073624Z","time spent":"842.360435ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:03:41.916144Z","caller":"traceutil/trace.go:171","msg":"trace[1926598038] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1247; }","duration":"179.373767ms","start":"2026-07-30T21:03:41.736753Z","end":"2026-07-30T21:03:41.916127Z","steps":["trace[1926598038] 'agreement among raft nodes before linearized reading' (duration: 179.180831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:41.916096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:41.164675Z","time spent":"751.414897ms","remote":"127.0.0.1:42894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T21:03:41.916017Z","caller":"traceutil/trace.go:171","msg":"trace[1928552532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1247; }","duration":"230.979357ms","start":"2026-07-30T21:03:41.68503Z","end":"2026-07-30T21:03:41.91601Z","steps":["trace[1928552532] 'agreement among raft nodes before linearized reading' (duration: 230.880594ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:41.916281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.104996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-30T21:03:41.916338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.601245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T21:03:41.916401Z","caller":"traceutil/trace.go:171","msg":"trace[793017376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1247; }","duration":"180.265581ms","start":"2026-07-30T21:03:41.736105Z","end":"2026-07-30T21:03:41.916371Z","steps":["trace[793017376] 'agreement among raft nodes before linearized reading' (duration: 179.885721ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:41.916427Z","caller":"traceutil/trace.go:171","msg":"trace[279802390] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1247; }","duration":"178.723689ms","start":"2026-07-30T21:03:41.737682Z","end":"2026-07-30T21:03:41.916406Z","steps":["trace[279802390] 'agreement among raft nodes before linearized reading' (duration: 178.39645ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:42.666809Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874441393544894,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T21:03:42.806526Z","caller":"traceutil/trace.go:171","msg":"trace[1995046077] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"886.305287ms","start":"2026-07-30T21:03:41.920193Z","end":"2026-07-30T21:03:42.806498Z","steps":["trace[1995046077] 'process raft request' (duration: 830.04075ms)","trace[1995046077] 'compare' (duration: 56.123613ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:03:42.806664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:41.920172Z","time spent":"886.42108ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:03:42.832024Z","caller":"traceutil/trace.go:171","msg":"trace[1910919624] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1302; }","duration":"665.511066ms","start":"2026-07-30T21:03:42.166469Z","end":"2026-07-30T21:03:42.83198Z","steps":["trace[1910919624] 'read index received' (duration: 583.85165ms)","trace[1910919624] 'applied index is now lower than readState.Index' (duration: 81.658556ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:03:42.832155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.66478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:03:42.832187Z","caller":"traceutil/trace.go:171","msg":"trace[808606669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1253; }","duration":"665.735332ms","start":"2026-07-30T21:03:42.166441Z","end":"2026-07-30T21:03:42.832176Z","steps":["trace[808606669] 'agreement among raft nodes before linearized reading' (duration: 665.66426ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:42.832193Z","caller":"traceutil/trace.go:171","msg":"trace[416020699] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"910.998297ms","start":"2026-07-30T21:03:41.921173Z","end":"2026-07-30T21:03:42.832171Z","steps":["trace[416020699] 'process raft request' (duration: 910.74398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:42.832218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:42.166426Z","time spent":"665.784993ms","remote":"127.0.0.1:42894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:03:42.832284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:41.921164Z","time spent":"911.071649ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:03:42.832394Z","caller":"traceutil/trace.go:171","msg":"trace[561280279] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"131.465787ms","start":"2026-07-30T21:03:42.700917Z","end":"2026-07-30T21:03:42.832383Z","steps":["trace[561280279] 'process raft request' (duration: 131.028885ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:42.832497Z","caller":"traceutil/trace.go:171","msg":"trace[651335906] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"911.911141ms","start":"2026-07-30T21:03:41.920558Z","end":"2026-07-30T21:03:42.832469Z","steps":["trace[651335906] 'process raft request' (duration: 911.293244ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:42.832498Z","caller":"traceutil/trace.go:171","msg":"trace[492047559] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"912.009784ms","start":"2026-07-30T21:03:41.920426Z","end":"2026-07-30T21:03:42.832435Z","steps":["trace[492047559] 'process raft request' (duration: 911.292285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:42.832632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:41.920551Z","time spent":"912.006213ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:03:42.832648Z","caller":"traceutil/trace.go:171","msg":"trace[1135705825] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"911.406168ms","start":"2026-07-30T21:03:41.920613Z","end":"2026-07-30T21:03:42.832019Z","steps":["trace[1135705825] 'process raft request' (duration: 911.272284ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:42.832979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:41.920582Z","time spent":"912.22312ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:03:42.832917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:41.920402Z","time spent":"912.326112ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:03:54.929207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:54.568154Z","time spent":"361.04747ms","remote":"127.0.0.1:42934","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-30T21:03:55.219858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.26275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:03:55.220135Z","caller":"traceutil/trace.go:171","msg":"trace[694641607] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1345; }","duration":"272.398732ms","start":"2026-07-30T21:03:54.947719Z","end":"2026-07-30T21:03:55.220118Z","steps":["trace[694641607] 'read index received' (duration: 88.810193ms)","trace[694641607] 'applied index is now lower than readState.Index' (duration: 183.583919ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:55.220229Z","caller":"traceutil/trace.go:171","msg":"trace[784311128] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"288.093846ms","start":"2026-07-30T21:03:54.932124Z","end":"2026-07-30T21:03:55.220218Z","steps":["trace[784311128] 'process raft request' (duration: 104.386084ms)","trace[784311128] 'compare' (duration: 182.838208ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:03:55.220577Z","caller":"traceutil/trace.go:171","msg":"trace[230130929] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"285.522366ms","start":"2026-07-30T21:03:54.935044Z","end":"2026-07-30T21:03:55.220566Z","steps":["trace[230130929] 'process raft request' (duration: 284.91972ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:55.220721Z","caller":"traceutil/trace.go:171","msg":"trace[1209588399] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"285.169727ms","start":"2026-07-30T21:03:54.935543Z","end":"2026-07-30T21:03:55.220713Z","steps":["trace[1209588399] 'process raft request' (duration: 284.53352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:55.220911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.197154ms","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-07-30T21:03:55.220973Z","caller":"traceutil/trace.go:171","msg":"trace[2110046941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1290; }","duration":"273.325477ms","start":"2026-07-30T21:03:54.947639Z","end":"2026-07-30T21:03:55.220965Z","steps":["trace[2110046941] 'agreement among raft nodes before linearized reading' (duration: 273.183553ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:55.22118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.88533ms","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-07-30T21:03:55.221243Z","caller":"traceutil/trace.go:171","msg":"trace[476805521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1290; }","duration":"268.007644ms","start":"2026-07-30T21:03:54.953222Z","end":"2026-07-30T21:03:55.22123Z","steps":["trace[476805521] 'agreement among raft nodes before linearized reading' (duration: 267.87179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:55.221774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.134208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-30T21:03:55.221819Z","caller":"traceutil/trace.go:171","msg":"trace[1162803860] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1290; }","duration":"268.21923ms","start":"2026-07-30T21:03:54.953591Z","end":"2026-07-30T21:03:55.22181Z","steps":["trace[1162803860] 'agreement among raft nodes before linearized reading' (duration: 268.119057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:55.756622Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874441393545013,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:03:56.256797Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874441393545013,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-30T21:03:56.491845Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.237697732s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-30T21:03:56.609924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.228131ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:03:56.610076Z","caller":"traceutil/trace.go:171","msg":"trace[710136995] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1291; }","duration":"307.395095ms","start":"2026-07-30T21:03:56.302662Z","end":"2026-07-30T21:03:56.610057Z","steps":["trace[710136995] 'range keys from in-memory index tree' (duration: 307.207351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:56.61051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.204196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T21:03:56.61075Z","caller":"traceutil/trace.go:171","msg":"trace[1521346219] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"1.381694233s","start":"2026-07-30T21:03:55.229029Z","end":"2026-07-30T21:03:56.610724Z","steps":["trace[1521346219] 'process raft request' (duration: 1.381621321s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:56.610833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:55.229017Z","time spent":"1.381775765s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:03:56.611421Z","caller":"traceutil/trace.go:171","msg":"trace[918570456] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"1.384058677s","start":"2026-07-30T21:03:55.227325Z","end":"2026-07-30T21:03:56.611383Z","steps":["trace[918570456] 'process raft request' (duration: 1.264841994s)","trace[918570456] 'compare' (duration: 118.023481ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:03:56.611623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:55.227298Z","time spent":"1.384227051s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:03:57.00226Z","caller":"traceutil/trace.go:171","msg":"trace[765160734] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1349; }","duration":"1.745982913s","start":"2026-07-30T21:03:55.256253Z","end":"2026-07-30T21:03:57.002236Z","steps":["trace[765160734] 'read index received' (duration: 1.235889003s)","trace[765160734] 'applied index is now lower than readState.Index' (duration: 510.09278ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:03:57.002439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.746169437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-30T21:03:57.002428Z","caller":"traceutil/trace.go:171","msg":"trace[764177639] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"984.240279ms","start":"2026-07-30T21:03:56.018132Z","end":"2026-07-30T21:03:57.002373Z","steps":["trace[764177639] 'process raft request' (duration: 983.89923ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:57.002497Z","caller":"traceutil/trace.go:171","msg":"trace[194179951] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1294; }","duration":"1.74626017s","start":"2026-07-30T21:03:55.25621Z","end":"2026-07-30T21:03:57.002471Z","steps":["trace[194179951] 'agreement among raft nodes before linearized reading' (duration: 1.746161497s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:57.002543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:55.256196Z","time spent":"1.746332532s","remote":"127.0.0.1:43148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-07-30T21:03:57.00259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.534363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:03:57.002666Z","caller":"traceutil/trace.go:171","msg":"trace[1615254786] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1294; }","duration":"910.676847ms","start":"2026-07-30T21:03:56.091969Z","end":"2026-07-30T21:03:57.002645Z","steps":["trace[1615254786] 'agreement among raft nodes before linearized reading' (duration: 910.546193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:57.002687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:56.018097Z","time spent":"984.440355ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:03:57.002709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:56.091939Z","time spent":"910.759739ms","remote":"127.0.0.1:43314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-30T21:03:57.002906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"834.959507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:03:57.002938Z","caller":"traceutil/trace.go:171","msg":"trace[196610421] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1294; }","duration":"835.046688ms","start":"2026-07-30T21:03:56.167881Z","end":"2026-07-30T21:03:57.002928Z","steps":["trace[196610421] 'agreement among raft nodes before linearized reading' (duration: 835.003107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:57.002963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:56.167851Z","time spent":"835.10723ms","remote":"127.0.0.1:42894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-30T21:03:59.383278Z","caller":"traceutil/trace.go:171","msg":"trace[290845734] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"763.611443ms","start":"2026-07-30T21:03:58.619644Z","end":"2026-07-30T21:03:59.383255Z","steps":["trace[290845734] 'process raft request' (duration: 763.465969ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:59.38344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:58.619623Z","time spent":"763.729406ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:03:59.908112Z","caller":"traceutil/trace.go:171","msg":"trace[1939317226] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"1.288205414s","start":"2026-07-30T21:03:58.619864Z","end":"2026-07-30T21:03:59.908069Z","steps":["trace[1939317226] 'process raft request' (duration: 1.287565386s)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:59.908316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:58.619847Z","time spent":"1.288361017s","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:03:59.908532Z","caller":"traceutil/trace.go:171","msg":"trace[1665322802] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"898.620372ms","start":"2026-07-30T21:03:59.009876Z","end":"2026-07-30T21:03:59.908497Z","steps":["trace[1665322802] 'process raft request' (duration: 897.81569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:59.908747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:59.009849Z","time spent":"898.781857ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:03:59.909483Z","caller":"traceutil/trace.go:171","msg":"trace[1690010815] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"780.472817ms","start":"2026-07-30T21:03:59.128987Z","end":"2026-07-30T21:03:59.909459Z","steps":["trace[1690010815] 'process raft request' (duration: 779.447829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:59.909589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:59.128964Z","time spent":"780.55852ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:03:59.90976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"913.800452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:03:59.909822Z","caller":"traceutil/trace.go:171","msg":"trace[978798941] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1301; }","duration":"913.909224ms","start":"2026-07-30T21:03:58.995898Z","end":"2026-07-30T21:03:59.909807Z","steps":["trace[978798941] 'agreement among raft nodes before linearized reading' (duration: 913.807662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:59.909919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:58.995876Z","time spent":"914.027277ms","remote":"127.0.0.1:43154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"info","ts":"2026-07-30T21:03:59.909654Z","caller":"traceutil/trace.go:171","msg":"trace[1198065938] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1355; }","duration":"913.422401ms","start":"2026-07-30T21:03:58.995945Z","end":"2026-07-30T21:03:59.909367Z","steps":["trace[1198065938] 'read index received' (duration: 387.158525ms)","trace[1198065938] 'applied index is now lower than readState.Index' (duration: 526.262565ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:03:59.911012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.935262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-30T21:03:59.910919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.5604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T21:03:59.911016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.268233ms","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-07-30T21:03:59.911053Z","caller":"traceutil/trace.go:171","msg":"trace[1692610855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1301; }","duration":"669.021364ms","start":"2026-07-30T21:03:59.242023Z","end":"2026-07-30T21:03:59.911044Z","steps":["trace[1692610855] 'agreement among raft nodes before linearized reading' (duration: 668.87075ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:59.911075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:59.242003Z","time spent":"669.068704ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-30T21:03:59.91108Z","caller":"traceutil/trace.go:171","msg":"trace[699492267] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1301; }","duration":"642.355684ms","start":"2026-07-30T21:03:59.268711Z","end":"2026-07-30T21:03:59.911067Z","steps":["trace[699492267] 'agreement among raft nodes before linearized reading' (duration: 642.18193ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:03:59.911079Z","caller":"traceutil/trace.go:171","msg":"trace[1738442595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"583.745984ms","start":"2026-07-30T21:03:59.327313Z","end":"2026-07-30T21:03:59.911059Z","steps":["trace[1738442595] 'agreement among raft nodes before linearized reading' (duration: 583.551059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:59.911114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:59.268699Z","time spent":"642.408146ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-30T21:03:59.911133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:59.327297Z","time spent":"583.821557ms","remote":"127.0.0.1:42894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-30T21:03:59.911868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.240246ms","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-07-30T21:03:59.911931Z","caller":"traceutil/trace.go:171","msg":"trace[835674522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1301; }","duration":"670.347609ms","start":"2026-07-30T21:03:59.241568Z","end":"2026-07-30T21:03:59.911916Z","steps":["trace[835674522] 'agreement among raft nodes before linearized reading' (duration: 670.154894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:03:59.911977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:59.24152Z","time spent":"670.444881ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-30T21:04:00.316432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.168271ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-30T21:04:00.413961Z","caller":"traceutil/trace.go:171","msg":"trace[1349691387] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"497.654344ms","start":"2026-07-30T21:03:59.916278Z","end":"2026-07-30T21:04:00.413932Z","steps":["trace[1349691387] 'process raft request' (duration: 497.374446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:00.414196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:59.91626Z","time spent":"497.814079ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:04:00.414321Z","caller":"traceutil/trace.go:171","msg":"trace[197933874] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1360; }","duration":"248.56381ms","start":"2026-07-30T21:04:00.165728Z","end":"2026-07-30T21:04:00.414292Z","steps":["trace[197933874] 'read index received' (duration: 247.893612ms)","trace[197933874] 'applied index is now lower than readState.Index' (duration: 669.638µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:04:00.414411Z","caller":"traceutil/trace.go:171","msg":"trace[1240456785] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"498.042764ms","start":"2026-07-30T21:03:59.916346Z","end":"2026-07-30T21:04:00.414389Z","steps":["trace[1240456785] 'process raft request' (duration: 497.53185ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:00.414461Z","caller":"traceutil/trace.go:171","msg":"trace[2073935323] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"498.070906ms","start":"2026-07-30T21:03:59.916379Z","end":"2026-07-30T21:04:00.41445Z","steps":["trace[2073935323] 'process raft request' (duration: 497.767438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:00.41448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:59.916339Z","time spent":"498.106447ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:04:00.414604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:03:59.916375Z","time spent":"498.112617ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:04:00.414803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.070154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:04:00.414899Z","caller":"traceutil/trace.go:171","msg":"trace[1709504829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"249.220687ms","start":"2026-07-30T21:04:00.165657Z","end":"2026-07-30T21:04:00.414878Z","steps":["trace[1709504829] 'agreement among raft nodes before linearized reading' (duration: 249.068063ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:01.880963Z","caller":"traceutil/trace.go:171","msg":"trace[845848350] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1363; }","duration":"285.140975ms","start":"2026-07-30T21:04:01.595796Z","end":"2026-07-30T21:04:01.880937Z","steps":["trace[845848350] 'read index received' (duration: 284.895429ms)","trace[845848350] 'applied index is now lower than readState.Index' (duration: 244.706µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:04:01.88135Z","caller":"traceutil/trace.go:171","msg":"trace[1212628732] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"486.656307ms","start":"2026-07-30T21:04:01.39468Z","end":"2026-07-30T21:04:01.881336Z","steps":["trace[1212628732] 'process raft request' (duration: 486.103352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:01.881473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:01.394637Z","time spent":"486.75221ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:04:01.881874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.075211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:95971"} {"level":"info","ts":"2026-07-30T21:04:01.881948Z","caller":"traceutil/trace.go:171","msg":"trace[644671492] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1305; }","duration":"286.170954ms","start":"2026-07-30T21:04:01.595739Z","end":"2026-07-30T21:04:01.88191Z","steps":["trace[644671492] 'agreement among raft nodes before linearized reading' (duration: 285.958808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:01.882142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.293127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-30T21:04:01.882198Z","caller":"traceutil/trace.go:171","msg":"trace[355524505] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1305; }","duration":"286.390709ms","start":"2026-07-30T21:04:01.595792Z","end":"2026-07-30T21:04:01.882182Z","steps":["trace[355524505] 'agreement among raft nodes before linearized reading' (duration: 286.298257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:01.882558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.70983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:04:01.882601Z","caller":"traceutil/trace.go:171","msg":"trace[1725092305] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1305; }","duration":"269.803822ms","start":"2026-07-30T21:04:01.612787Z","end":"2026-07-30T21:04:01.882591Z","steps":["trace[1725092305] 'agreement among raft nodes before linearized reading' (duration: 269.73751ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:02.322589Z","caller":"traceutil/trace.go:171","msg":"trace[1537001262] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"405.52338ms","start":"2026-07-30T21:04:01.917041Z","end":"2026-07-30T21:04:02.322565Z","steps":["trace[1537001262] 'process raft request' (duration: 405.474498ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:02.322886Z","caller":"traceutil/trace.go:171","msg":"trace[1146839288] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"408.328776ms","start":"2026-07-30T21:04:01.914528Z","end":"2026-07-30T21:04:02.322857Z","steps":["trace[1146839288] 'process raft request' (duration: 326.094169ms)","trace[1146839288] 'compare' (duration: 81.777304ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:04:02.323018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:01.914513Z","time spent":"408.430248ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:04:02.323159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:01.917025Z","time spent":"405.665943ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:04:02.542469Z","caller":"traceutil/trace.go:171","msg":"trace[1904833030] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"117.61538ms","start":"2026-07-30T21:04:02.424643Z","end":"2026-07-30T21:04:02.542258Z","steps":["trace[1904833030] 'process raft request' (duration: 117.309092ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:02.542493Z","caller":"traceutil/trace.go:171","msg":"trace[1838268777] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"116.905061ms","start":"2026-07-30T21:04:02.425562Z","end":"2026-07-30T21:04:02.542467Z","steps":["trace[1838268777] 'process raft request' (duration: 116.530741ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:04.170721Z","caller":"traceutil/trace.go:171","msg":"trace[1259134170] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"132.973054ms","start":"2026-07-30T21:04:04.037733Z","end":"2026-07-30T21:04:04.170706Z","steps":["trace[1259134170] 'process raft request' (duration: 49.749361ms)","trace[1259134170] 'compare' (duration: 83.120431ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:04:04.464851Z","caller":"traceutil/trace.go:171","msg":"trace[1469808125] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"200.979797ms","start":"2026-07-30T21:04:04.263844Z","end":"2026-07-30T21:04:04.464824Z","steps":["trace[1469808125] 'process raft request' (duration: 200.824453ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:04.465245Z","caller":"traceutil/trace.go:171","msg":"trace[254699283] linearizableReadLoop","detail":"{readStateIndex:1374; appliedIndex:1374; }","duration":"137.562288ms","start":"2026-07-30T21:04:04.327568Z","end":"2026-07-30T21:04:04.46513Z","steps":["trace[254699283] 'read index received' (duration: 137.549637ms)","trace[254699283] 'applied index is now lower than readState.Index' (duration: 10.321µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:04:04.4655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.916018ms","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-07-30T21:04:04.46558Z","caller":"traceutil/trace.go:171","msg":"trace[2030989483] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1315; }","duration":"138.04312ms","start":"2026-07-30T21:04:04.327523Z","end":"2026-07-30T21:04:04.465566Z","steps":["trace[2030989483] 'agreement among raft nodes before linearized reading' (duration: 137.789134ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:04.676993Z","caller":"traceutil/trace.go:171","msg":"trace[330468577] linearizableReadLoop","detail":"{readStateIndex:1377; appliedIndex:1374; }","duration":"196.141176ms","start":"2026-07-30T21:04:04.480816Z","end":"2026-07-30T21:04:04.676958Z","steps":["trace[330468577] 'read index received' (duration: 110.048186ms)","trace[330468577] 'applied index is now lower than readState.Index' (duration: 86.09144ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:04:04.677115Z","caller":"traceutil/trace.go:171","msg":"trace[1626961809] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"207.249666ms","start":"2026-07-30T21:04:04.469838Z","end":"2026-07-30T21:04:04.677087Z","steps":["trace[1626961809] 'process raft request' (duration: 206.935007ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:04.67736Z","caller":"traceutil/trace.go:171","msg":"trace[340031410] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"206.082475ms","start":"2026-07-30T21:04:04.471263Z","end":"2026-07-30T21:04:04.677346Z","steps":["trace[340031410] 'process raft request' (duration: 205.629313ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:04.677364Z","caller":"traceutil/trace.go:171","msg":"trace[203244310] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"347.828986ms","start":"2026-07-30T21:04:04.329521Z","end":"2026-07-30T21:04:04.67735Z","steps":["trace[203244310] 'process raft request' (duration: 261.379036ms)","trace[203244310] 'compare' (duration: 85.234387ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:04:04.677741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:04.329482Z","time spent":"348.140494ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:04:04.677785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.743565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-30T21:04:04.677849Z","caller":"traceutil/trace.go:171","msg":"trace[1826392689] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1318; }","duration":"156.871978ms","start":"2026-07-30T21:04:04.520962Z","end":"2026-07-30T21:04:04.677834Z","steps":["trace[1826392689] 'agreement among raft nodes before linearized reading' (duration: 156.758715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:04.678079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.876824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-30T21:04:04.67819Z","caller":"traceutil/trace.go:171","msg":"trace[1765827108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1318; }","duration":"132.023048ms","start":"2026-07-30T21:04:04.54615Z","end":"2026-07-30T21:04:04.678173Z","steps":["trace[1765827108] 'agreement among raft nodes before linearized reading' (duration: 131.771851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:04.678362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.358945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.248\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-30T21:04:04.678534Z","caller":"traceutil/trace.go:171","msg":"trace[421599797] range","detail":"{range_begin:/registry/masterleases/199.204.45.248; range_end:; response_count:1; response_revision:1318; }","duration":"110.431196ms","start":"2026-07-30T21:04:04.567967Z","end":"2026-07-30T21:04:04.678399Z","steps":["trace[421599797] 'agreement among raft nodes before linearized reading' (duration: 110.257012ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:04.67863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.812621ms","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-07-30T21:04:04.678682Z","caller":"traceutil/trace.go:171","msg":"trace[2010303705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1318; }","duration":"197.884654ms","start":"2026-07-30T21:04:04.480788Z","end":"2026-07-30T21:04:04.678673Z","steps":["trace[2010303705] 'agreement among raft nodes before linearized reading' (duration: 196.788924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:04.679105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.307813ms","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-07-30T21:04:04.679139Z","caller":"traceutil/trace.go:171","msg":"trace[2047395394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1318; }","duration":"133.418196ms","start":"2026-07-30T21:04:04.545712Z","end":"2026-07-30T21:04:04.679131Z","steps":["trace[2047395394] 'agreement among raft nodes before linearized reading' (duration: 132.389948ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:04.963101Z","caller":"traceutil/trace.go:171","msg":"trace[1320503399] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"277.381777ms","start":"2026-07-30T21:04:04.68569Z","end":"2026-07-30T21:04:04.963072Z","steps":["trace[1320503399] 'process raft request' (duration: 277.344156ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:04.96316Z","caller":"traceutil/trace.go:171","msg":"trace[1888009749] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"277.923671ms","start":"2026-07-30T21:04:04.685205Z","end":"2026-07-30T21:04:04.963128Z","steps":["trace[1888009749] 'process raft request' (duration: 277.794098ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:04.963118Z","caller":"traceutil/trace.go:171","msg":"trace[801296607] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"277.920051ms","start":"2026-07-30T21:04:04.685164Z","end":"2026-07-30T21:04:04.963084Z","steps":["trace[801296607] 'process raft request' (duration: 277.674004ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:05.725786Z","caller":"traceutil/trace.go:171","msg":"trace[1598549305] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"847.685239ms","start":"2026-07-30T21:04:04.878071Z","end":"2026-07-30T21:04:05.725756Z","steps":["trace[1598549305] 'process raft request' (duration: 784.144026ms)","trace[1598549305] 'compare' (duration: 63.43032ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:04:05.725923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:04:04.878043Z","time spent":"847.808541ms","remote":"127.0.0.1:42934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:04:06.032964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.205167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:04:06.033037Z","caller":"traceutil/trace.go:171","msg":"trace[1402101914] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1322; }","duration":"180.342201ms","start":"2026-07-30T21:04:05.852677Z","end":"2026-07-30T21:04:06.033019Z","steps":["trace[1402101914] 'count revisions from in-memory index tree' (duration: 180.098955ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:06.291018Z","caller":"traceutil/trace.go:171","msg":"trace[397630525] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1382; }","duration":"193.217847ms","start":"2026-07-30T21:04:06.097777Z","end":"2026-07-30T21:04:06.290995Z","steps":["trace[397630525] 'read index received' (duration: 193.042143ms)","trace[397630525] 'applied index is now lower than readState.Index' (duration: 174.714µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:04:06.291049Z","caller":"traceutil/trace.go:171","msg":"trace[106866805] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"253.01449ms","start":"2026-07-30T21:04:06.038009Z","end":"2026-07-30T21:04:06.291023Z","steps":["trace[106866805] 'process raft request' (duration: 252.841225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:06.291173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.381232ms","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":"warn","ts":"2026-07-30T21:04:06.29121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.812864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:04:06.291236Z","caller":"traceutil/trace.go:171","msg":"trace[2016012944] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1323; }","duration":"124.876746ms","start":"2026-07-30T21:04:06.166353Z","end":"2026-07-30T21:04:06.29123Z","steps":["trace[2016012944] 'agreement among raft nodes before linearized reading' (duration: 124.819124ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:06.291212Z","caller":"traceutil/trace.go:171","msg":"trace[1433324517] 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:1323; }","duration":"193.477625ms","start":"2026-07-30T21:04:06.097723Z","end":"2026-07-30T21:04:06.291201Z","steps":["trace[1433324517] 'agreement among raft nodes before linearized reading' (duration: 193.396473ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:12.440933Z","caller":"traceutil/trace.go:171","msg":"trace[774286617] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"120.348334ms","start":"2026-07-30T21:04:12.320558Z","end":"2026-07-30T21:04:12.440906Z","steps":["trace[774286617] 'process raft request' (duration: 120.162939ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:15.283421Z","caller":"traceutil/trace.go:171","msg":"trace[1329238802] linearizableReadLoop","detail":"{readStateIndex:1456; appliedIndex:1453; }","duration":"118.866434ms","start":"2026-07-30T21:04:15.164531Z","end":"2026-07-30T21:04:15.283398Z","steps":["trace[1329238802] 'read index received' (duration: 29.332791ms)","trace[1329238802] 'applied index is now lower than readState.Index' (duration: 89.532783ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:04:15.283465Z","caller":"traceutil/trace.go:171","msg":"trace[1350929088] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"182.723535ms","start":"2026-07-30T21:04:15.10071Z","end":"2026-07-30T21:04:15.283433Z","steps":["trace[1350929088] 'process raft request' (duration: 134.810164ms)","trace[1350929088] 'compare' (duration: 47.691795ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:04:15.283521Z","caller":"traceutil/trace.go:171","msg":"trace[707142679] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"182.036896ms","start":"2026-07-30T21:04:15.101442Z","end":"2026-07-30T21:04:15.283478Z","steps":["trace[707142679] 'process raft request' (duration: 181.911903ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:04:15.283533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.991927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:04:15.283621Z","caller":"traceutil/trace.go:171","msg":"trace[411549399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1394; }","duration":"119.132571ms","start":"2026-07-30T21:04:15.164475Z","end":"2026-07-30T21:04:15.283608Z","steps":["trace[411549399] 'agreement among raft nodes before linearized reading' (duration: 119.012228ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:19.385469Z","caller":"traceutil/trace.go:171","msg":"trace[1120688799] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"174.691509ms","start":"2026-07-30T21:04:19.210753Z","end":"2026-07-30T21:04:19.385445Z","steps":["trace[1120688799] 'process raft request' (duration: 174.241787ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:04:21.457518Z","caller":"traceutil/trace.go:171","msg":"trace[1300174323] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"131.398922ms","start":"2026-07-30T21:04:21.326093Z","end":"2026-07-30T21:04:21.457492Z","steps":["trace[1300174323] 'process raft request' (duration: 131.134445ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:05:04.982892Z","caller":"traceutil/trace.go:171","msg":"trace[1407343947] transaction","detail":"{read_only:false; response_revision:1635; number_of_response:1; }","duration":"102.621564ms","start":"2026-07-30T21:05:04.88021Z","end":"2026-07-30T21:05:04.982832Z","steps":["trace[1407343947] 'process raft request' (duration: 102.392538ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:05:05.224953Z","caller":"traceutil/trace.go:171","msg":"trace[1777057266] transaction","detail":"{read_only:false; response_revision:1636; number_of_response:1; }","duration":"237.47857ms","start":"2026-07-30T21:05:04.987448Z","end":"2026-07-30T21:05:05.224927Z","steps":["trace[1777057266] 'process raft request' (duration: 195.068003ms)","trace[1777057266] 'compare' (duration: 42.226722ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:05:05.225276Z","caller":"traceutil/trace.go:171","msg":"trace[1023230253] transaction","detail":"{read_only:false; response_revision:1637; number_of_response:1; }","duration":"183.792648ms","start":"2026-07-30T21:05:05.041444Z","end":"2026-07-30T21:05:05.225237Z","steps":["trace[1023230253] 'process raft request' (duration: 183.440308ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:05:11.550302Z","caller":"traceutil/trace.go:171","msg":"trace[567061136] transaction","detail":"{read_only:false; response_revision:1664; number_of_response:1; }","duration":"223.44418ms","start":"2026-07-30T21:05:11.326833Z","end":"2026-07-30T21:05:11.550277Z","steps":["trace[567061136] 'process raft request' (duration: 133.418417ms)","trace[567061136] 'compare' (duration: 89.942801ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:05:11.747395Z","caller":"traceutil/trace.go:171","msg":"trace[2083739198] transaction","detail":"{read_only:false; response_revision:1665; number_of_response:1; }","duration":"136.095299ms","start":"2026-07-30T21:05:11.611265Z","end":"2026-07-30T21:05:11.747361Z","steps":["trace[2083739198] 'process raft request' (duration: 124.242019ms)","trace[2083739198] 'compare' (duration: 11.651114ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:05:31.661034Z","caller":"traceutil/trace.go:171","msg":"trace[965419019] transaction","detail":"{read_only:false; response_revision:1754; number_of_response:1; }","duration":"145.129923ms","start":"2026-07-30T21:05:31.515857Z","end":"2026-07-30T21:05:31.660987Z","steps":["trace[965419019] 'process raft request' (duration: 144.832425ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:05:38.326486Z","caller":"traceutil/trace.go:171","msg":"trace[175850276] transaction","detail":"{read_only:false; response_revision:1788; number_of_response:1; }","duration":"105.999886ms","start":"2026-07-30T21:05:38.220458Z","end":"2026-07-30T21:05:38.326458Z","steps":["trace[175850276] 'process raft request' (duration: 105.839621ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:01.927481Z","caller":"traceutil/trace.go:171","msg":"trace[618656737] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"138.071328ms","start":"2026-07-30T21:07:01.789369Z","end":"2026-07-30T21:07:01.92744Z","steps":["trace[618656737] 'process raft request' (duration: 137.906814ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:09.117334Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3415874441393547198,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-30T21:07:09.156602Z","caller":"traceutil/trace.go:171","msg":"trace[923022855] linearizableReadLoop","detail":"{readStateIndex:2297; appliedIndex:2296; }","duration":"540.341671ms","start":"2026-07-30T21:07:08.616233Z","end":"2026-07-30T21:07:09.156575Z","steps":["trace[923022855] 'read index received' (duration: 540.175706ms)","trace[923022855] 'applied index is now lower than readState.Index' (duration: 165.035µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:07:09.156675Z","caller":"traceutil/trace.go:171","msg":"trace[2113175033] transaction","detail":"{read_only:false; response_revision:2201; number_of_response:1; }","duration":"553.817617ms","start":"2026-07-30T21:07:08.602834Z","end":"2026-07-30T21:07:09.156651Z","steps":["trace[2113175033] 'process raft request' (duration: 553.540819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:09.156829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:08.602778Z","time spent":"553.94883ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-30T21:07:09.156962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.513482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-30T21:07:09.157006Z","caller":"traceutil/trace.go:171","msg":"trace[326490042] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2201; }","duration":"373.598763ms","start":"2026-07-30T21:07:08.783396Z","end":"2026-07-30T21:07:09.156995Z","steps":["trace[326490042] 'agreement among raft nodes before linearized reading' (duration: 373.321046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:09.157145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.878105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-30T21:07:09.157228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:08.783375Z","time spent":"373.84232ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-30T21:07:09.157259Z","caller":"traceutil/trace.go:171","msg":"trace[1761886629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2201; }","duration":"541.067451ms","start":"2026-07-30T21:07:08.616169Z","end":"2026-07-30T21:07:09.157236Z","steps":["trace[1761886629] 'agreement among raft nodes before linearized reading' (duration: 540.515096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:09.157482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:08.616141Z","time spent":"541.327558ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-30T21:07:10.635062Z","caller":"traceutil/trace.go:171","msg":"trace[2115245918] linearizableReadLoop","detail":"{readStateIndex:2305; appliedIndex:2304; }","duration":"209.372135ms","start":"2026-07-30T21:07:10.42566Z","end":"2026-07-30T21:07:10.635032Z","steps":["trace[2115245918] 'read index received' (duration: 209.140138ms)","trace[2115245918] 'applied index is now lower than readState.Index' (duration: 230.947µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T21:07:10.635316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.628562ms","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-07-30T21:07:10.635364Z","caller":"traceutil/trace.go:171","msg":"trace[1040458007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2208; }","duration":"209.697663ms","start":"2026-07-30T21:07:10.425654Z","end":"2026-07-30T21:07:10.635352Z","steps":["trace[1040458007] 'agreement among raft nodes before linearized reading' (duration: 209.493788ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:07:10.635679Z","caller":"traceutil/trace.go:171","msg":"trace[1590821840] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"331.09075ms","start":"2026-07-30T21:07:10.304572Z","end":"2026-07-30T21:07:10.635663Z","steps":["trace[1590821840] 'process raft request' (duration: 330.322029ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:07:10.63579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:07:10.304553Z","time spent":"331.163431ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:08:08.561206Z","caller":"traceutil/trace.go:171","msg":"trace[1855491221] transaction","detail":"{read_only:false; response_revision:2471; number_of_response:1; }","duration":"301.132686ms","start":"2026-07-30T21:08:08.260051Z","end":"2026-07-30T21:08:08.561184Z","steps":["trace[1855491221] 'process raft request' (duration: 301.006813ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:08.56143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T21:08:08.259995Z","time spent":"301.361903ms","remote":"127.0.0.1:43132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T21:08:08.562649Z","caller":"traceutil/trace.go:171","msg":"trace[1315178948] transaction","detail":"{read_only:false; response_revision:2473; number_of_response:1; }","duration":"298.30925ms","start":"2026-07-30T21:08:08.264311Z","end":"2026-07-30T21:08:08.56262Z","steps":["trace[1315178948] 'process raft request' (duration: 298.270659ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:08:08.56274Z","caller":"traceutil/trace.go:171","msg":"trace[330555337] transaction","detail":"{read_only:false; response_revision:2472; number_of_response:1; }","duration":"298.437843ms","start":"2026-07-30T21:08:08.264265Z","end":"2026-07-30T21:08:08.562703Z","steps":["trace[330555337] 'process raft request' (duration: 298.165886ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T21:08:34.88933Z","caller":"traceutil/trace.go:171","msg":"trace[195200081] linearizableReadLoop","detail":"{readStateIndex:2733; appliedIndex:2731; }","duration":"208.870765ms","start":"2026-07-30T21:08:34.680424Z","end":"2026-07-30T21:08:34.889295Z","steps":["trace[195200081] 'read index received' (duration: 135.05479ms)","trace[195200081] 'applied index is now lower than readState.Index' (duration: 73.814155ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T21:08:34.889487Z","caller":"traceutil/trace.go:171","msg":"trace[1626390988] transaction","detail":"{read_only:false; response_revision:2618; number_of_response:1; }","duration":"244.964471ms","start":"2026-07-30T21:08:34.644511Z","end":"2026-07-30T21:08:34.889475Z","steps":["trace[1626390988] 'process raft request' (duration: 244.55508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:34.889868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.409731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-30T21:08:34.890179Z","caller":"traceutil/trace.go:171","msg":"trace[204762198] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2618; }","duration":"209.701129ms","start":"2026-07-30T21:08:34.680418Z","end":"2026-07-30T21:08:34.890119Z","steps":["trace[204762198] 'agreement among raft nodes before linearized reading' (duration: 209.213795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T21:08:34.89262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.932006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T21:08:34.892688Z","caller":"traceutil/trace.go:171","msg":"trace[803902714] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2619; }","duration":"179.058189ms","start":"2026-07-30T21:08:34.713616Z","end":"2026-07-30T21:08:34.892674Z","steps":["trace[803902714] 'agreement among raft nodes before linearized reading' (duration: 178.924246ms)"],"step_count":1}