{"level":"warn","ts":"2026-06-25T22:15:46.954633Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-25T22:15:46.954713Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.14:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.14:2380","--initial-cluster=instance=https://199.19.213.14:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.14:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.14:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-25T22:15:46.954772Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-25T22:15:46.954781Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.14:2380"]} {"level":"info","ts":"2026-06-25T22:15:46.954795Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-25T22:15:46.955405Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.14:2379"]} {"level":"info","ts":"2026-06-25T22:15:46.955471Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.14:2380"],"listen-peer-urls":["https://199.19.213.14:2380"],"advertise-client-urls":["https://199.19.213.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.14:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-25T22:15:46.960876Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.208778ms"} {"level":"info","ts":"2026-06-25T22:15:46.968613Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"329f95029c2a91ed","cluster-id":"5fd85759a04d0f9f"} {"level":"info","ts":"2026-06-25T22:15:46.96869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed switched to configuration voters=()"} {"level":"info","ts":"2026-06-25T22:15:46.968717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed became follower at term 0"} {"level":"info","ts":"2026-06-25T22:15:46.968739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 329f95029c2a91ed [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-25T22:15:46.968752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed became follower at term 1"} {"level":"info","ts":"2026-06-25T22:15:46.968788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed switched to configuration voters=(3647798061635899885)"} {"level":"warn","ts":"2026-06-25T22:15:46.977731Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-25T22:15:46.979258Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-25T22:15:46.980943Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-06-25T22:15:46.987377Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"329f95029c2a91ed","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-25T22:15:46.988418Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"329f95029c2a91ed","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-25T22:15:46.988544Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-25T22:15:46.988579Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-25T22:15:46.988596Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-06-25T22:15:46.989991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed switched to configuration voters=(3647798061635899885)"} {"level":"info","ts":"2026-06-25T22:15:46.990293Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5fd85759a04d0f9f","local-member-id":"329f95029c2a91ed","added-peer-id":"329f95029c2a91ed","added-peer-peer-urls":["https://199.19.213.14:2380"]} {"level":"info","ts":"2026-06-25T22:15:46.995376Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-06-25T22:15:46.995443Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.14:2380"} {"level":"info","ts":"2026-06-25T22:15:46.995514Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.14:2380"} {"level":"info","ts":"2026-06-25T22:15:46.995736Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"329f95029c2a91ed","initial-advertise-peer-urls":["https://199.19.213.14:2380"],"listen-peer-urls":["https://199.19.213.14:2380"],"advertise-client-urls":["https://199.19.213.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-25T22:15:46.995791Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-25T22:15:47.369491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed is starting a new election at term 1"} {"level":"info","ts":"2026-06-25T22:15:47.369556Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed became pre-candidate at term 1"} {"level":"info","ts":"2026-06-25T22:15:47.369593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed received MsgPreVoteResp from 329f95029c2a91ed at term 1"} {"level":"info","ts":"2026-06-25T22:15:47.369613Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed became candidate at term 2"} {"level":"info","ts":"2026-06-25T22:15:47.369628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed received MsgVoteResp from 329f95029c2a91ed at term 2"} {"level":"info","ts":"2026-06-25T22:15:47.369648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed became leader at term 2"} {"level":"info","ts":"2026-06-25T22:15:47.369665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 329f95029c2a91ed elected leader 329f95029c2a91ed at term 2"} {"level":"info","ts":"2026-06-25T22:15:47.371015Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"329f95029c2a91ed","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.14:2379]}","request-path":"/0/members/329f95029c2a91ed/attributes","cluster-id":"5fd85759a04d0f9f","publish-timeout":"7s"} {"level":"info","ts":"2026-06-25T22:15:47.371077Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-25T22:15:47.371191Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:15:47.371299Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-25T22:15:47.37134Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-25T22:15:47.371188Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-25T22:15:47.373434Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.14:2379"} {"level":"info","ts":"2026-06-25T22:15:47.374523Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-25T22:15:47.374609Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5fd85759a04d0f9f","local-member-id":"329f95029c2a91ed","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:15:47.374703Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:15:47.374743Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-25T22:15:48.888574Z","caller":"traceutil/trace.go:171","msg":"trace[1455212484] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"116.980569ms","start":"2026-06-25T22:15:48.771558Z","end":"2026-06-25T22:15:48.888538Z","steps":["trace[1455212484] 'process raft request' (duration: 41.578883ms)","trace[1455212484] 'compare' (duration: 75.188562ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:48.888637Z","caller":"traceutil/trace.go:171","msg":"trace[319026519] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"113.864136ms","start":"2026-06-25T22:15:48.774737Z","end":"2026-06-25T22:15:48.888601Z","steps":["trace[319026519] 'process raft request' (duration: 113.730654ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:48.88875Z","caller":"traceutil/trace.go:171","msg":"trace[483293138] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"113.96864ms","start":"2026-06-25T22:15:48.774745Z","end":"2026-06-25T22:15:48.888714Z","steps":["trace[483293138] 'process raft request' (duration: 113.771395ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:48.888736Z","caller":"traceutil/trace.go:171","msg":"trace[1928693658] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"113.469008ms","start":"2026-06-25T22:15:48.775215Z","end":"2026-06-25T22:15:48.888684Z","steps":["trace[1928693658] 'process raft request' (duration: 113.331825ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:48.888886Z","caller":"traceutil/trace.go:171","msg":"trace[1141599134] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"109.884526ms","start":"2026-06-25T22:15:48.778955Z","end":"2026-06-25T22:15:48.88884Z","steps":["trace[1141599134] 'process raft request' (duration: 109.691861ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:48.889043Z","caller":"traceutil/trace.go:171","msg":"trace[823768543] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"111.632892ms","start":"2026-06-25T22:15:48.777398Z","end":"2026-06-25T22:15:48.889031Z","steps":["trace[823768543] 'process raft request' (duration: 111.211642ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:48.889093Z","caller":"traceutil/trace.go:171","msg":"trace[80106149] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"100.587567ms","start":"2026-06-25T22:15:48.788478Z","end":"2026-06-25T22:15:48.889066Z","steps":["trace[80106149] 'process raft request' (duration: 100.230129ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:48.88915Z","caller":"traceutil/trace.go:171","msg":"trace[1199411860] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"114.403228ms","start":"2026-06-25T22:15:48.774735Z","end":"2026-06-25T22:15:48.889138Z","steps":["trace[1199411860] 'process raft request' (duration: 113.848376ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:49.092009Z","caller":"traceutil/trace.go:171","msg":"trace[997725272] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"143.682495ms","start":"2026-06-25T22:15:48.948264Z","end":"2026-06-25T22:15:49.091946Z","steps":["trace[997725272] 'process raft request' (duration: 53.615186ms)","trace[997725272] 'compare' (duration: 89.755062ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:49.093471Z","caller":"traceutil/trace.go:171","msg":"trace[1056711905] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"116.891402ms","start":"2026-06-25T22:15:48.976538Z","end":"2026-06-25T22:15:49.09343Z","steps":["trace[1056711905] 'process raft request' (duration: 116.616277ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:49.093599Z","caller":"traceutil/trace.go:171","msg":"trace[689781806] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"116.829551ms","start":"2026-06-25T22:15:48.976751Z","end":"2026-06-25T22:15:49.093581Z","steps":["trace[689781806] 'process raft request' (duration: 116.582076ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:49.093694Z","caller":"traceutil/trace.go:171","msg":"trace[1044865050] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"114.823499ms","start":"2026-06-25T22:15:48.978839Z","end":"2026-06-25T22:15:49.093662Z","steps":["trace[1044865050] 'process raft request' (duration: 114.773418ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:49.093738Z","caller":"traceutil/trace.go:171","msg":"trace[1111833791] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"115.459733ms","start":"2026-06-25T22:15:48.978244Z","end":"2026-06-25T22:15:49.093704Z","steps":["trace[1111833791] 'process raft request' (duration: 115.28881ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:49.093818Z","caller":"traceutil/trace.go:171","msg":"trace[157321495] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"116.560314ms","start":"2026-06-25T22:15:48.977228Z","end":"2026-06-25T22:15:49.093788Z","steps":["trace[157321495] 'process raft request' (duration: 116.171766ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:49.093861Z","caller":"traceutil/trace.go:171","msg":"trace[1351011155] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"116.110425ms","start":"2026-06-25T22:15:48.977699Z","end":"2026-06-25T22:15:49.09381Z","steps":["trace[1351011155] 'process raft request' (duration: 115.753038ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:49.093976Z","caller":"traceutil/trace.go:171","msg":"trace[541195177] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"115.81797ms","start":"2026-06-25T22:15:48.978146Z","end":"2026-06-25T22:15:49.093964Z","steps":["trace[541195177] 'process raft request' (duration: 115.350741ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:49.094095Z","caller":"traceutil/trace.go:171","msg":"trace[150475418] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"115.240637ms","start":"2026-06-25T22:15:48.978781Z","end":"2026-06-25T22:15:49.094021Z","steps":["trace[150475418] 'process raft request' (duration: 114.807689ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:49.094079Z","caller":"traceutil/trace.go:171","msg":"trace[1961950472] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"115.36233ms","start":"2026-06-25T22:15:48.978704Z","end":"2026-06-25T22:15:49.094066Z","steps":["trace[1961950472] 'process raft request' (duration: 114.85932ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:49.410659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.593981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-25T22:15:49.410841Z","caller":"traceutil/trace.go:171","msg":"trace[351172093] linearizableReadLoop","detail":"{readStateIndex:45; appliedIndex:44; }","duration":"214.899073ms","start":"2026-06-25T22:15:49.195916Z","end":"2026-06-25T22:15:49.410815Z","steps":["trace[351172093] 'read index received' (duration: 86.606558ms)","trace[351172093] 'applied index is now lower than readState.Index' (duration: 128.290495ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:49.410873Z","caller":"traceutil/trace.go:171","msg":"trace[314614047] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"281.809776ms","start":"2026-06-25T22:15:49.129012Z","end":"2026-06-25T22:15:49.410822Z","steps":["trace[314614047] 'process raft request' (duration: 153.299997ms)","trace[314614047] 'compare' (duration: 127.392777ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:15:49.411181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.23904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-25T22:15:49.411231Z","caller":"traceutil/trace.go:171","msg":"trace[1911393292] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:41; }","duration":"215.343392ms","start":"2026-06-25T22:15:49.19587Z","end":"2026-06-25T22:15:49.411213Z","steps":["trace[1911393292] 'agreement among raft nodes before linearized reading' (duration: 215.146058ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:50.968776Z","caller":"traceutil/trace.go:171","msg":"trace[1921594794] transaction","detail":"{read_only:false; response_revision:154; number_of_response:1; }","duration":"102.016089ms","start":"2026-06-25T22:15:50.866724Z","end":"2026-06-25T22:15:50.96874Z","steps":["trace[1921594794] 'process raft request' (duration: 47.822111ms)","trace[1921594794] 'compare' (duration: 54.028285ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:53.285492Z","caller":"traceutil/trace.go:171","msg":"trace[405955905] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"124.261016ms","start":"2026-06-25T22:15:53.161188Z","end":"2026-06-25T22:15:53.285449Z","steps":["trace[405955905] 'process raft request' (duration: 76.718865ms)","trace[405955905] 'compare' (duration: 47.325827ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:53.316195Z","caller":"traceutil/trace.go:171","msg":"trace[888733107] linearizableReadLoop","detail":"{readStateIndex:251; appliedIndex:249; }","duration":"153.056974ms","start":"2026-06-25T22:15:53.163115Z","end":"2026-06-25T22:15:53.316172Z","steps":["trace[888733107] 'read index received' (duration: 74.804459ms)","trace[888733107] 'applied index is now lower than readState.Index' (duration: 78.251335ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:53.316268Z","caller":"traceutil/trace.go:171","msg":"trace[1683359013] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"153.171276ms","start":"2026-06-25T22:15:53.163045Z","end":"2026-06-25T22:15:53.316216Z","steps":["trace[1683359013] 'process raft request' (duration: 153.040743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:53.316374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.247297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-25T22:15:53.316423Z","caller":"traceutil/trace.go:171","msg":"trace[1160049183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:245; }","duration":"153.336509ms","start":"2026-06-25T22:15:53.163072Z","end":"2026-06-25T22:15:53.316409Z","steps":["trace[1160049183] 'agreement among raft nodes before linearized reading' (duration: 153.209497ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:53.322895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.788178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-06-25T22:15:53.323028Z","caller":"traceutil/trace.go:171","msg":"trace[293833501] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:246; }","duration":"121.964502ms","start":"2026-06-25T22:15:53.201035Z","end":"2026-06-25T22:15:53.322999Z","steps":["trace[293833501] 'agreement among raft nodes before linearized reading' (duration: 121.626545ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:53.465662Z","caller":"traceutil/trace.go:171","msg":"trace[1113766169] linearizableReadLoop","detail":"{readStateIndex:255; appliedIndex:252; }","duration":"105.587374ms","start":"2026-06-25T22:15:53.360044Z","end":"2026-06-25T22:15:53.465631Z","steps":["trace[1113766169] 'read index received' (duration: 5.352304ms)","trace[1113766169] 'applied index is now lower than readState.Index' (duration: 100.23407ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:53.465801Z","caller":"traceutil/trace.go:171","msg":"trace[3099152] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"138.44146ms","start":"2026-06-25T22:15:53.327329Z","end":"2026-06-25T22:15:53.465771Z","steps":["trace[3099152] 'process raft request' (duration: 130.095219ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:53.465929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.89213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-25T22:15:53.466014Z","caller":"traceutil/trace.go:171","msg":"trace[44587413] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"138.054683ms","start":"2026-06-25T22:15:53.327771Z","end":"2026-06-25T22:15:53.465826Z","steps":["trace[44587413] 'process raft request' (duration: 137.797978ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:53.466474Z","caller":"traceutil/trace.go:171","msg":"trace[43189008] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:249; }","duration":"106.076334ms","start":"2026-06-25T22:15:53.359925Z","end":"2026-06-25T22:15:53.466002Z","steps":["trace[43189008] 'agreement among raft nodes before linearized reading' (duration: 105.811088ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:55.9193Z","caller":"traceutil/trace.go:171","msg":"trace[1905686306] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"112.056451ms","start":"2026-06-25T22:15:55.807216Z","end":"2026-06-25T22:15:55.919273Z","steps":["trace[1905686306] 'process raft request' (duration: 111.52373ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:56.096936Z","caller":"traceutil/trace.go:171","msg":"trace[2043708807] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"102.77056ms","start":"2026-06-25T22:15:55.99414Z","end":"2026-06-25T22:15:56.096911Z","steps":["trace[2043708807] 'process raft request' (duration: 102.665008ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:57.675682Z","caller":"traceutil/trace.go:171","msg":"trace[252108208] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"134.560363ms","start":"2026-06-25T22:15:57.54109Z","end":"2026-06-25T22:15:57.67565Z","steps":["trace[252108208] 'process raft request' (duration: 69.006029ms)","trace[252108208] 'compare' (duration: 65.335431ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:58.406542Z","caller":"traceutil/trace.go:171","msg":"trace[612902743] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"150.626111ms","start":"2026-06-25T22:15:58.255883Z","end":"2026-06-25T22:15:58.406509Z","steps":["trace[612902743] 'process raft request' (duration: 150.404937ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:59.36074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.71677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"info","ts":"2026-06-25T22:15:59.360838Z","caller":"traceutil/trace.go:171","msg":"trace[1568672680] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:354; }","duration":"489.858412ms","start":"2026-06-25T22:15:58.870961Z","end":"2026-06-25T22:15:59.360819Z","steps":["trace[1568672680] 'range keys from in-memory index tree' (duration: 489.643729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:59.360893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:58.870942Z","time spent":"489.940734ms","remote":"127.0.0.1:47112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6983,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-06-25T22:15:59.546769Z","caller":"traceutil/trace.go:171","msg":"trace[697387564] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"193.75342ms","start":"2026-06-25T22:15:59.352983Z","end":"2026-06-25T22:15:59.546736Z","steps":["trace[697387564] 'process raft request' (duration: 193.568487ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:15:59.992201Z","caller":"traceutil/trace.go:171","msg":"trace[645845193] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:366; }","duration":"506.841023ms","start":"2026-06-25T22:15:59.485314Z","end":"2026-06-25T22:15:59.992155Z","steps":["trace[645845193] 'read index received' (duration: 61.254115ms)","trace[645845193] 'applied index is now lower than readState.Index' (duration: 445.585858ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:15:59.9923Z","caller":"traceutil/trace.go:171","msg":"trace[1359233946] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"620.93627ms","start":"2026-06-25T22:15:59.371326Z","end":"2026-06-25T22:15:59.992262Z","steps":["trace[1359233946] 'process raft request' (duration: 620.602704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:59.992508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.1692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-25T22:15:59.992557Z","caller":"traceutil/trace.go:171","msg":"trace[868673367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:356; }","duration":"248.299753ms","start":"2026-06-25T22:15:59.744239Z","end":"2026-06-25T22:15:59.992539Z","steps":["trace[868673367] 'agreement among raft nodes before linearized reading' (duration: 248.221541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:59.992585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.220667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-25T22:15:59.992675Z","caller":"traceutil/trace.go:171","msg":"trace[1727690567] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:356; }","duration":"312.386141ms","start":"2026-06-25T22:15:59.680262Z","end":"2026-06-25T22:15:59.992648Z","steps":["trace[1727690567] 'agreement among raft nodes before linearized reading' (duration: 312.187217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:59.992725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:59.68023Z","time spent":"312.482832ms","remote":"127.0.0.1:33114","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-06-25T22:15:59.992721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:59.371252Z","time spent":"621.137444ms","remote":"127.0.0.1:47112","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:15:59.992827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.239891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-25T22:15:59.992935Z","caller":"traceutil/trace.go:171","msg":"trace[1413457936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:356; }","duration":"279.421404ms","start":"2026-06-25T22:15:59.713485Z","end":"2026-06-25T22:15:59.992906Z","steps":["trace[1413457936] 'agreement among raft nodes before linearized reading' (duration: 279.20572ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:59.992401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.066467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-25T22:15:59.993452Z","caller":"traceutil/trace.go:171","msg":"trace[849812394] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:356; }","duration":"508.126077ms","start":"2026-06-25T22:15:59.485292Z","end":"2026-06-25T22:15:59.993418Z","steps":["trace[849812394] 'agreement among raft nodes before linearized reading' (duration: 507.028797ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:15:59.993507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:15:59.485218Z","time spent":"508.272659ms","remote":"127.0.0.1:33114","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-06-25T22:16:00.181466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.03818ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:16:00.181729Z","caller":"traceutil/trace.go:171","msg":"trace[1408428619] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"182.628906ms","start":"2026-06-25T22:15:59.999065Z","end":"2026-06-25T22:16:00.181694Z","steps":["trace[1408428619] 'process raft request' (duration: 64.22899ms)","trace[1408428619] 'compare' (duration: 117.832856ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:16:00.182207Z","caller":"traceutil/trace.go:171","msg":"trace[2100087281] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"183.069934ms","start":"2026-06-25T22:15:59.999118Z","end":"2026-06-25T22:16:00.182188Z","steps":["trace[2100087281] 'process raft request' (duration: 182.507594ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:00.182401Z","caller":"traceutil/trace.go:171","msg":"trace[202351299] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"183.314009ms","start":"2026-06-25T22:15:59.999068Z","end":"2026-06-25T22:16:00.182382Z","steps":["trace[202351299] 'process raft request' (duration: 182.614366ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:00.183165Z","caller":"traceutil/trace.go:171","msg":"trace[782293617] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"133.880878ms","start":"2026-06-25T22:16:00.049264Z","end":"2026-06-25T22:16:00.183145Z","steps":["trace[782293617] 'process raft request' (duration: 133.736605ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:01.056979Z","caller":"traceutil/trace.go:171","msg":"trace[1143502831] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"167.593826ms","start":"2026-06-25T22:16:00.889354Z","end":"2026-06-25T22:16:01.056948Z","steps":["trace[1143502831] 'process raft request' (duration: 167.414243ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:02.609331Z","caller":"traceutil/trace.go:171","msg":"trace[1740298449] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"185.062716ms","start":"2026-06-25T22:16:02.424239Z","end":"2026-06-25T22:16:02.609301Z","steps":["trace[1740298449] 'process raft request' (duration: 184.881442ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:04.320282Z","caller":"traceutil/trace.go:171","msg":"trace[1842705895] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"242.517394ms","start":"2026-06-25T22:16:04.077739Z","end":"2026-06-25T22:16:04.320256Z","steps":["trace[1842705895] 'process raft request' (duration: 242.374471ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:04.334764Z","caller":"traceutil/trace.go:171","msg":"trace[362965163] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:384; }","duration":"109.183495ms","start":"2026-06-25T22:16:04.225563Z","end":"2026-06-25T22:16:04.334747Z","steps":["trace[362965163] 'read index received' (duration: 94.564328ms)","trace[362965163] 'applied index is now lower than readState.Index' (duration: 14.618307ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:16:04.334847Z","caller":"traceutil/trace.go:171","msg":"trace[232307852] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"193.357017ms","start":"2026-06-25T22:16:04.141482Z","end":"2026-06-25T22:16:04.334839Z","steps":["trace[232307852] 'process raft request' (duration: 193.190584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:04.334974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.393628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-25T22:16:04.335032Z","caller":"traceutil/trace.go:171","msg":"trace[1562677718] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:373; }","duration":"109.520581ms","start":"2026-06-25T22:16:04.225502Z","end":"2026-06-25T22:16:04.335023Z","steps":["trace[1562677718] 'agreement among raft nodes before linearized reading' (duration: 109.427089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:04.335053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.60439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-25T22:16:04.335083Z","caller":"traceutil/trace.go:171","msg":"trace[198168979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:373; }","duration":"106.65594ms","start":"2026-06-25T22:16:04.228419Z","end":"2026-06-25T22:16:04.335075Z","steps":["trace[198168979] 'agreement among raft nodes before linearized reading' (duration: 106.595189ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:04.335222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.639059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-25T22:16:04.33528Z","caller":"traceutil/trace.go:171","msg":"trace[1202299597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:373; }","duration":"106.988336ms","start":"2026-06-25T22:16:04.228277Z","end":"2026-06-25T22:16:04.335265Z","steps":["trace[1202299597] 'agreement among raft nodes before linearized reading' (duration: 106.615459ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:04.637893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.921694ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:16:04.638099Z","caller":"traceutil/trace.go:171","msg":"trace[627296685] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"298.132124ms","start":"2026-06-25T22:16:04.339946Z","end":"2026-06-25T22:16:04.638079Z","steps":["trace[627296685] 'process raft request' (duration: 298.063123ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:04.63812Z","caller":"traceutil/trace.go:171","msg":"trace[1946828486] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"299.639371ms","start":"2026-06-25T22:16:04.338456Z","end":"2026-06-25T22:16:04.638096Z","steps":["trace[1946828486] 'process raft request' (duration: 40.391072ms)","trace[1946828486] 'compare' (duration: 258.796192ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:16:05.010976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.41464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:16:05.011072Z","caller":"traceutil/trace.go:171","msg":"trace[1205540134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:376; }","duration":"230.579472ms","start":"2026-06-25T22:16:04.78047Z","end":"2026-06-25T22:16:05.01105Z","steps":["trace[1205540134] 'range keys from in-memory index tree' (duration: 230.258157ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:12.651805Z","caller":"traceutil/trace.go:171","msg":"trace[1237817363] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"240.645099ms","start":"2026-06-25T22:16:12.411117Z","end":"2026-06-25T22:16:12.651762Z","steps":["trace[1237817363] 'read index received' (duration: 240.271793ms)","trace[1237817363] 'applied index is now lower than readState.Index' (duration: 370.676µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:16:12.651843Z","caller":"traceutil/trace.go:171","msg":"trace[2005951532] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"287.284081ms","start":"2026-06-25T22:16:12.364534Z","end":"2026-06-25T22:16:12.651818Z","steps":["trace[2005951532] 'process raft request' (duration: 286.948675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:12.652083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.945224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-25T22:16:12.652194Z","caller":"traceutil/trace.go:171","msg":"trace[176656019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:393; }","duration":"241.125967ms","start":"2026-06-25T22:16:12.411041Z","end":"2026-06-25T22:16:12.652167Z","steps":["trace[176656019] 'agreement among raft nodes before linearized reading' (duration: 240.905863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:22.257176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.413501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:16:22.257419Z","caller":"traceutil/trace.go:171","msg":"trace[2133181256] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:462; }","duration":"477.436316ms","start":"2026-06-25T22:16:21.779958Z","end":"2026-06-25T22:16:22.257395Z","steps":["trace[2133181256] 'read index received' (duration: 56.261µs)","trace[2133181256] 'applied index is now lower than readState.Index' (duration: 477.378355ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:16:22.2575Z","caller":"traceutil/trace.go:171","msg":"trace[64839513] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"878.55871ms","start":"2026-06-25T22:16:21.378883Z","end":"2026-06-25T22:16:22.257441Z","steps":["trace[64839513] 'process raft request' (duration: 304.771073ms)","trace[64839513] 'compare' (duration: 573.213909ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:16:22.25768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.697821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T22:16:22.257724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:21.378862Z","time spent":"878.752713ms","remote":"127.0.0.1:46982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:16:22.257791Z","caller":"traceutil/trace.go:171","msg":"trace[270364650] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:445; }","duration":"477.857373ms","start":"2026-06-25T22:16:21.779906Z","end":"2026-06-25T22:16:22.257763Z","steps":["trace[270364650] 'agreement among raft nodes before linearized reading' (duration: 477.592799ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:22.257852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:21.779887Z","time spent":"477.955644ms","remote":"127.0.0.1:46926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-25T22:16:22.956087Z","caller":"traceutil/trace.go:171","msg":"trace[346556598] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:463; }","duration":"194.558838ms","start":"2026-06-25T22:16:22.761496Z","end":"2026-06-25T22:16:22.956055Z","steps":["trace[346556598] 'read index received' (duration: 194.261613ms)","trace[346556598] 'applied index is now lower than readState.Index' (duration: 295.995µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:16:22.956229Z","caller":"traceutil/trace.go:171","msg":"trace[2038484813] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"257.84948ms","start":"2026-06-25T22:16:22.698366Z","end":"2026-06-25T22:16:22.956216Z","steps":["trace[2038484813] 'process raft request' (duration: 257.454624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:22.956334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.226076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-25T22:16:22.956395Z","caller":"traceutil/trace.go:171","msg":"trace[838698346] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:446; }","duration":"187.351767ms","start":"2026-06-25T22:16:22.769029Z","end":"2026-06-25T22:16:22.956381Z","steps":["trace[838698346] 'agreement among raft nodes before linearized reading' (duration: 187.231876ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:22.956559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.721638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-25T22:16:22.956589Z","caller":"traceutil/trace.go:171","msg":"trace[1235393915] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:446; }","duration":"186.767508ms","start":"2026-06-25T22:16:22.769811Z","end":"2026-06-25T22:16:22.956578Z","steps":["trace[1235393915] 'agreement among raft nodes before linearized reading' (duration: 186.718728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:22.956602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.924551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:16:22.956705Z","caller":"traceutil/trace.go:171","msg":"trace[1460237322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:446; }","duration":"176.120964ms","start":"2026-06-25T22:16:22.780553Z","end":"2026-06-25T22:16:22.956674Z","steps":["trace[1460237322] 'agreement among raft nodes before linearized reading' (duration: 175.919591ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:22.956687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.153207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-25T22:16:22.956791Z","caller":"traceutil/trace.go:171","msg":"trace[1278348242] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:446; }","duration":"195.29089ms","start":"2026-06-25T22:16:22.761475Z","end":"2026-06-25T22:16:22.956766Z","steps":["trace[1278348242] 'agreement among raft nodes before linearized reading' (duration: 195.028375ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:32.998549Z","caller":"traceutil/trace.go:171","msg":"trace[150875845] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"840.364929ms","start":"2026-06-25T22:16:32.158162Z","end":"2026-06-25T22:16:32.998527Z","steps":["trace[150875845] 'process raft request' (duration: 840.238446ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:32.998677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:32.15809Z","time spent":"840.523321ms","remote":"127.0.0.1:46996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T22:16:34.217084Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.217472539s","expected-duration":"1s"} {"level":"info","ts":"2026-06-25T22:16:34.22349Z","caller":"traceutil/trace.go:171","msg":"trace[1703823609] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"2.050918678s","start":"2026-06-25T22:16:32.171559Z","end":"2026-06-25T22:16:34.222478Z","steps":["trace[1703823609] 'process raft request' (duration: 2.045854795s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:34.224027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:32.171535Z","time spent":"2.052337037s","remote":"127.0.0.1:46996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14661,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:16:34.228639Z","caller":"traceutil/trace.go:171","msg":"trace[1604449249] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.943603816s","start":"2026-06-25T22:16:32.284997Z","end":"2026-06-25T22:16:34.228601Z","steps":["trace[1604449249] 'process raft request' (duration: 1.939367356s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:34.229019Z","caller":"traceutil/trace.go:171","msg":"trace[989263288] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:611; }","duration":"1.448875017s","start":"2026-06-25T22:16:32.780101Z","end":"2026-06-25T22:16:34.228976Z","steps":["trace[989263288] 'read index received' (duration: 218.342484ms)","trace[989263288] 'applied index is now lower than readState.Index' (duration: 1.230531783s)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:16:34.229014Z","caller":"traceutil/trace.go:171","msg":"trace[666009428] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"1.887401449s","start":"2026-06-25T22:16:32.341562Z","end":"2026-06-25T22:16:34.228963Z","steps":["trace[666009428] 'process raft request' (duration: 1.886976134s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:34.229092Z","caller":"traceutil/trace.go:171","msg":"trace[128921418] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"1.684853443s","start":"2026-06-25T22:16:32.544231Z","end":"2026-06-25T22:16:34.229084Z","steps":["trace[128921418] 'process raft request' (duration: 1.68462454s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:34.229139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:32.544207Z","time spent":"1.684903663s","remote":"127.0.0.1:46996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T22:16:34.229137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:32.284971Z","time spent":"1.944044811s","remote":"127.0.0.1:46996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:16:34.229186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:32.34152Z","time spent":"1.887593021s","remote":"127.0.0.1:46996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:16:34.22928Z","caller":"traceutil/trace.go:171","msg":"trace[1465931802] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"1.485820618s","start":"2026-06-25T22:16:32.743451Z","end":"2026-06-25T22:16:34.229271Z","steps":["trace[1465931802] 'process raft request' (duration: 1.485462112s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:34.229342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:32.743428Z","time spent":"1.485893229s","remote":"127.0.0.1:46996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:16:34.229374Z","caller":"traceutil/trace.go:171","msg":"trace[809578033] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"1.286692619s","start":"2026-06-25T22:16:32.942668Z","end":"2026-06-25T22:16:34.22936Z","steps":["trace[809578033] 'process raft request' (duration: 1.286277772s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:34.229435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:32.942645Z","time spent":"1.28676185s","remote":"127.0.0.1:46996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T22:16:34.229524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.449427395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:16:34.229576Z","caller":"traceutil/trace.go:171","msg":"trace[969635928] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"1.449513366s","start":"2026-06-25T22:16:32.780053Z","end":"2026-06-25T22:16:34.229566Z","steps":["trace[969635928] 'agreement among raft nodes before linearized reading' (duration: 1.449390264s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:34.229607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:32.780033Z","time spent":"1.449568337s","remote":"127.0.0.1:46926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-25T22:16:34.233317Z","caller":"traceutil/trace.go:171","msg":"trace[901231586] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"1.231207602s","start":"2026-06-25T22:16:33.00209Z","end":"2026-06-25T22:16:34.233297Z","steps":["trace[901231586] 'process raft request' (duration: 1.230942288s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:34.233382Z","caller":"traceutil/trace.go:171","msg":"trace[2020705504] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"1.093181995s","start":"2026-06-25T22:16:33.140176Z","end":"2026-06-25T22:16:34.233358Z","steps":["trace[2020705504] 'process raft request' (duration: 1.092976431s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:34.233495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:33.140161Z","time spent":"1.093298287s","remote":"127.0.0.1:46996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-25T22:16:34.233704Z","caller":"traceutil/trace.go:171","msg":"trace[1464518294] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"891.221816ms","start":"2026-06-25T22:16:33.342449Z","end":"2026-06-25T22:16:34.23367Z","steps":["trace[1464518294] 'process raft request' (duration: 890.776979ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:34.233869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:33.342431Z","time spent":"891.341677ms","remote":"127.0.0.1:46996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T22:16:34.2334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:33.00207Z","time spent":"1.231294744s","remote":"127.0.0.1:33404","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-25T22:16:34.234378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.134593024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-25T22:16:34.234414Z","caller":"traceutil/trace.go:171","msg":"trace[1926279486] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:595; }","duration":"1.134647505s","start":"2026-06-25T22:16:33.099757Z","end":"2026-06-25T22:16:34.234404Z","steps":["trace[1926279486] 'agreement among raft nodes before linearized reading' (duration: 1.134558113s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:34.234441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:33.099737Z","time spent":"1.134698636s","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-25T22:16:34.234545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1350266s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-25T22:16:34.234618Z","caller":"traceutil/trace.go:171","msg":"trace[1535986562] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"1.135130061s","start":"2026-06-25T22:16:33.099478Z","end":"2026-06-25T22:16:34.234608Z","steps":["trace[1535986562] 'agreement among raft nodes before linearized reading' (duration: 1.134946129s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:34.234654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:33.099463Z","time spent":"1.135182612s","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-25T22:16:34.234767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.479932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-06-25T22:16:34.234799Z","caller":"traceutil/trace.go:171","msg":"trace[171952248] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:595; }","duration":"295.533273ms","start":"2026-06-25T22:16:33.939257Z","end":"2026-06-25T22:16:34.23479Z","steps":["trace[171952248] 'agreement among raft nodes before linearized reading' (duration: 295.440231ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:34.234916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.211653ms","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-06-25T22:16:34.234941Z","caller":"traceutil/trace.go:171","msg":"trace[1594397510] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:595; }","duration":"437.276214ms","start":"2026-06-25T22:16:33.797658Z","end":"2026-06-25T22:16:34.234934Z","steps":["trace[1594397510] 'agreement among raft nodes before linearized reading' (duration: 437.197813ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:34.234959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:33.797646Z","time spent":"437.308584ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-25T22:16:34.234977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.140026383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-25T22:16:34.235167Z","caller":"traceutil/trace.go:171","msg":"trace[1155554688] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:595; }","duration":"1.140255286s","start":"2026-06-25T22:16:33.094901Z","end":"2026-06-25T22:16:34.235156Z","steps":["trace[1155554688] 'agreement among raft nodes before linearized reading' (duration: 1.139979032s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:34.235243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:33.094877Z","time spent":"1.140355138s","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-25T22:16:34.23568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.218836316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-06-25T22:16:34.235709Z","caller":"traceutil/trace.go:171","msg":"trace[537273113] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:595; }","duration":"1.218903866s","start":"2026-06-25T22:16:33.016797Z","end":"2026-06-25T22:16:34.235701Z","steps":["trace[537273113] 'agreement among raft nodes before linearized reading' (duration: 1.218555511s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:34.235735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:33.016778Z","time spent":"1.218952327s","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-25T22:16:34.235072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.443813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:16:34.236217Z","caller":"traceutil/trace.go:171","msg":"trace[2125708124] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:595; }","duration":"496.629969ms","start":"2026-06-25T22:16:33.739579Z","end":"2026-06-25T22:16:34.236209Z","steps":["trace[2125708124] 'agreement among raft nodes before linearized reading' (duration: 495.473262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:34.236243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:33.739555Z","time spent":"496.681009ms","remote":"127.0.0.1:46996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-06-25T22:16:34.2351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.698472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:16:34.236304Z","caller":"traceutil/trace.go:171","msg":"trace[1046030418] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:595; }","duration":"697.93374ms","start":"2026-06-25T22:16:33.538363Z","end":"2026-06-25T22:16:34.236297Z","steps":["trace[1046030418] 'agreement among raft nodes before linearized reading' (duration: 696.721503ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:34.236322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:33.538347Z","time spent":"697.97033ms","remote":"127.0.0.1:46996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-06-25T22:16:39.6815Z","caller":"traceutil/trace.go:171","msg":"trace[1743834367] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"141.381731ms","start":"2026-06-25T22:16:39.540089Z","end":"2026-06-25T22:16:39.681471Z","steps":["trace[1743834367] 'process raft request' (duration: 134.219033ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:40.599075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.348922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:16:40.599376Z","caller":"traceutil/trace.go:171","msg":"trace[198301456] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"310.473908ms","start":"2026-06-25T22:16:40.288871Z","end":"2026-06-25T22:16:40.599345Z","steps":["trace[198301456] 'compare' (duration: 309.046588ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:40.599447Z","caller":"traceutil/trace.go:171","msg":"trace[208035128] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:693; }","duration":"292.739615ms","start":"2026-06-25T22:16:40.306681Z","end":"2026-06-25T22:16:40.599421Z","steps":["trace[208035128] 'read index received' (duration: 40.25µs)","trace[208035128] 'applied index is now lower than readState.Index' (duration: 292.696645ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:16:40.59949Z","caller":"traceutil/trace.go:171","msg":"trace[1244688048] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"309.559725ms","start":"2026-06-25T22:16:40.289886Z","end":"2026-06-25T22:16:40.599446Z","steps":["trace[1244688048] 'process raft request' (duration: 309.300762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:40.599542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:40.28886Z","time spent":"310.60391ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:16:40.599658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:40.289863Z","time spent":"309.697597ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:16:40.599686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.998038ms","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-06-25T22:16:40.599719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.431014ms","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-06-25T22:16:40.599772Z","caller":"traceutil/trace.go:171","msg":"trace[244287344] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:668; }","duration":"290.503965ms","start":"2026-06-25T22:16:40.30925Z","end":"2026-06-25T22:16:40.599754Z","steps":["trace[244287344] 'agreement among raft nodes before linearized reading' (duration: 290.316842ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:40.600338Z","caller":"traceutil/trace.go:171","msg":"trace[2038167085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:668; }","duration":"293.086841ms","start":"2026-06-25T22:16:40.306639Z","end":"2026-06-25T22:16:40.599726Z","steps":["trace[2038167085] 'agreement among raft nodes before linearized reading' (duration: 292.866377ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:41.057004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.203184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:16:41.057261Z","caller":"traceutil/trace.go:171","msg":"trace[1226429698] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:695; }","duration":"276.418807ms","start":"2026-06-25T22:16:40.780822Z","end":"2026-06-25T22:16:41.05724Z","steps":["trace[1226429698] 'read index received' (duration: 136.891558ms)","trace[1226429698] 'applied index is now lower than readState.Index' (duration: 139.526049ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:16:41.057373Z","caller":"traceutil/trace.go:171","msg":"trace[2033296410] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"452.287679ms","start":"2026-06-25T22:16:40.605076Z","end":"2026-06-25T22:16:41.057364Z","steps":["trace[2033296410] 'process raft request' (duration: 452.066706ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:41.057437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:40.605056Z","time spent":"452.345479ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:16:41.057494Z","caller":"traceutil/trace.go:171","msg":"trace[675119749] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"453.366623ms","start":"2026-06-25T22:16:40.604078Z","end":"2026-06-25T22:16:41.057445Z","steps":["trace[675119749] 'process raft request' (duration: 313.615591ms)","trace[675119749] 'compare' (duration: 138.922241ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:16:41.057684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:40.604059Z","time spent":"453.520655ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:16:41.057691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.850353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:16:41.05779Z","caller":"traceutil/trace.go:171","msg":"trace[1570087246] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:670; }","duration":"276.998326ms","start":"2026-06-25T22:16:40.780772Z","end":"2026-06-25T22:16:41.05777Z","steps":["trace[1570087246] 'agreement among raft nodes before linearized reading' (duration: 276.792543ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:41.272286Z","caller":"traceutil/trace.go:171","msg":"trace[1384346250] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"202.067152ms","start":"2026-06-25T22:16:41.070169Z","end":"2026-06-25T22:16:41.272236Z","steps":["trace[1384346250] 'process raft request' (duration: 163.759981ms)","trace[1384346250] 'compare' (duration: 38.158629ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:16:41.861878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.278725ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-06-25T22:16:41.86201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:41.347758Z","time spent":"514.244347ms","remote":"127.0.0.1:46982","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-25T22:16:43.104746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515235530994490794,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:16:43.313115Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.022487947s","expected-duration":"1s"} {"level":"info","ts":"2026-06-25T22:16:43.3143Z","caller":"traceutil/trace.go:171","msg":"trace[80776142] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:700; }","duration":"709.84441ms","start":"2026-06-25T22:16:42.604429Z","end":"2026-06-25T22:16:43.314274Z","steps":["trace[80776142] 'read index received' (duration: 708.929508ms)","trace[80776142] 'applied index is now lower than readState.Index' (duration: 914.122µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:16:43.314347Z","caller":"traceutil/trace.go:171","msg":"trace[2088923345] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"1.023770195s","start":"2026-06-25T22:16:42.290547Z","end":"2026-06-25T22:16:43.314317Z","steps":["trace[2088923345] 'process raft request' (duration: 1.023548862s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:43.314532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.082473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-25T22:16:43.314599Z","caller":"traceutil/trace.go:171","msg":"trace[796242477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:673; }","duration":"710.205776ms","start":"2026-06-25T22:16:42.604379Z","end":"2026-06-25T22:16:43.314585Z","steps":["trace[796242477] 'agreement among raft nodes before linearized reading' (duration: 709.978652ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:43.314561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.108694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-25T22:16:43.314643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:42.60436Z","time spent":"710.274117ms","remote":"127.0.0.1:33114","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-06-25T22:16:43.314673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.973269ms","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-06-25T22:16:43.314725Z","caller":"traceutil/trace.go:171","msg":"trace[1212796838] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:673; }","duration":"252.05772ms","start":"2026-06-25T22:16:43.062656Z","end":"2026-06-25T22:16:43.314714Z","steps":["trace[1212796838] 'agreement among raft nodes before linearized reading' (duration: 251.843126ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:43.314734Z","caller":"traceutil/trace.go:171","msg":"trace[1784877736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:673; }","duration":"710.281516ms","start":"2026-06-25T22:16:42.604392Z","end":"2026-06-25T22:16:43.314673Z","steps":["trace[1784877736] 'agreement among raft nodes before linearized reading' (duration: 710.050942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:43.314831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:42.60437Z","time spent":"710.449249ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-25T22:16:43.314912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:42.290529Z","time spent":"1.023936077s","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:16:43.314975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.177705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-25T22:16:43.315097Z","caller":"traceutil/trace.go:171","msg":"trace[493577879] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:673; }","duration":"253.370577ms","start":"2026-06-25T22:16:43.0617Z","end":"2026-06-25T22:16:43.31507Z","steps":["trace[493577879] 'agreement among raft nodes before linearized reading' (duration: 252.854081ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:44.104763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515235530994490797,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:16:44.605172Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515235530994490797,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:16:44.668799Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.350543418s","expected-duration":"1s"} {"level":"info","ts":"2026-06-25T22:16:44.725062Z","caller":"traceutil/trace.go:171","msg":"trace[2000848649] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"1.406857097s","start":"2026-06-25T22:16:43.318165Z","end":"2026-06-25T22:16:44.725022Z","steps":["trace[2000848649] 'process raft request' (duration: 1.350924442s)","trace[2000848649] 'compare' (duration: 55.545151ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:16:44.725395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:43.318145Z","time spent":"1.4069975s","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:16:44.731433Z","caller":"traceutil/trace.go:171","msg":"trace[1935420197] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"1.412467452s","start":"2026-06-25T22:16:43.318935Z","end":"2026-06-25T22:16:44.731403Z","steps":["trace[1935420197] 'process raft request' (duration: 1.41232958s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:44.731647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:43.318925Z","time spent":"1.412608344s","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:16:44.731834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12733758s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:16:44.731776Z","caller":"traceutil/trace.go:171","msg":"trace[1329126561] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"1.411997816s","start":"2026-06-25T22:16:43.319702Z","end":"2026-06-25T22:16:44.7317Z","steps":["trace[1329126561] 'process raft request' (duration: 1.411627161s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:44.731362Z","caller":"traceutil/trace.go:171","msg":"trace[62028793] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"1.412830867s","start":"2026-06-25T22:16:43.318459Z","end":"2026-06-25T22:16:44.73129Z","steps":["trace[62028793] 'process raft request' (duration: 1.412443132s)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:44.732178Z","caller":"traceutil/trace.go:171","msg":"trace[917937769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:677; }","duration":"1.127496481s","start":"2026-06-25T22:16:43.604417Z","end":"2026-06-25T22:16:44.731914Z","steps":["trace[917937769] 'agreement among raft nodes before linearized reading' (duration: 1.127338959s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:44.732288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:43.604394Z","time spent":"1.127880207s","remote":"127.0.0.1:46926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-25T22:16:44.732186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:43.318447Z","time spent":"1.413653789s","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:16:44.732214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:43.319692Z","time spent":"1.41232632s","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:16:44.731391Z","caller":"traceutil/trace.go:171","msg":"trace[513109624] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:701; }","duration":"1.126887343s","start":"2026-06-25T22:16:43.604477Z","end":"2026-06-25T22:16:44.731365Z","steps":["trace[513109624] 'read index received' (duration: 1.064635584s)","trace[513109624] 'applied index is now lower than readState.Index' (duration: 62.250829ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:16:44.733144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.569643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:16:44.733198Z","caller":"traceutil/trace.go:171","msg":"trace[582366546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:677; }","duration":"951.660224ms","start":"2026-06-25T22:16:43.781522Z","end":"2026-06-25T22:16:44.733182Z","steps":["trace[582366546] 'agreement among raft nodes before linearized reading' (duration: 951.567743ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:44.73392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:16:43.781504Z","time spent":"952.396663ms","remote":"127.0.0.1:46942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-25T22:16:56.569116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.980922ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-25T22:16:56.569199Z","caller":"traceutil/trace.go:171","msg":"trace[1859719378] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"158.668061ms","start":"2026-06-25T22:16:56.410516Z","end":"2026-06-25T22:16:56.569184Z","steps":["trace[1859719378] 'read index received' (duration: 25.07µs)","trace[1859719378] 'applied index is now lower than readState.Index' (duration: 158.642101ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:16:56.569317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.787973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:16:56.569357Z","caller":"traceutil/trace.go:171","msg":"trace[1290690089] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:756; }","duration":"158.848834ms","start":"2026-06-25T22:16:56.410494Z","end":"2026-06-25T22:16:56.569342Z","steps":["trace[1290690089] 'agreement among raft nodes before linearized reading' (duration: 158.772953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:56.569541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.586531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:16:56.569663Z","caller":"traceutil/trace.go:171","msg":"trace[909996996] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:756; }","duration":"158.749873ms","start":"2026-06-25T22:16:56.410881Z","end":"2026-06-25T22:16:56.569631Z","steps":["trace[909996996] 'agreement among raft nodes before linearized reading' (duration: 158.52946ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:56.569934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.009132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T22:16:56.56994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.687033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:16:56.569975Z","caller":"traceutil/trace.go:171","msg":"trace[515122580] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:756; }","duration":"133.068182ms","start":"2026-06-25T22:16:56.436895Z","end":"2026-06-25T22:16:56.569963Z","steps":["trace[515122580] 'agreement among raft nodes before linearized reading' (duration: 133.006341ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:56.56998Z","caller":"traceutil/trace.go:171","msg":"trace[1975764475] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:756; }","duration":"158.744993ms","start":"2026-06-25T22:16:56.411225Z","end":"2026-06-25T22:16:56.56997Z","steps":["trace[1975764475] 'agreement among raft nodes before linearized reading' (duration: 158.687082ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:16:56.569946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.9586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T22:16:56.569993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.554066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:16:56.570036Z","caller":"traceutil/trace.go:171","msg":"trace[759057693] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:756; }","duration":"133.084022ms","start":"2026-06-25T22:16:56.436932Z","end":"2026-06-25T22:16:56.570016Z","steps":["trace[759057693] 'agreement among raft nodes before linearized reading' (duration: 132.91386ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:16:56.570045Z","caller":"traceutil/trace.go:171","msg":"trace[789454245] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:756; }","duration":"132.633957ms","start":"2026-06-25T22:16:56.437397Z","end":"2026-06-25T22:16:56.570031Z","steps":["trace[789454245] 'agreement among raft nodes before linearized reading' (duration: 132.559806ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:17:01.915821Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515235530994491091,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:17:02.416989Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10515235530994491091,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-25T22:17:02.728424Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.343262821s","expected-duration":"1s"} {"level":"warn","ts":"2026-06-25T22:17:02.728441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.257702ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:17:02.728807Z","caller":"traceutil/trace.go:171","msg":"trace[1509152484] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:792; }","duration":"292.427374ms","start":"2026-06-25T22:17:02.436146Z","end":"2026-06-25T22:17:02.728573Z","steps":["trace[1509152484] 'range keys from in-memory index tree' (duration: 292.235832ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:17:02.728893Z","caller":"traceutil/trace.go:171","msg":"trace[300837086] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"1.343832559s","start":"2026-06-25T22:17:01.385036Z","end":"2026-06-25T22:17:02.728868Z","steps":["trace[300837086] 'process raft request' (duration: 1.343494544s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:17:02.729038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:17:01.385009Z","time spent":"1.34395098s","remote":"127.0.0.1:46982","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:17:02.729639Z","caller":"traceutil/trace.go:171","msg":"trace[109142194] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:826; }","duration":"1.314244806s","start":"2026-06-25T22:17:01.415376Z","end":"2026-06-25T22:17:02.729621Z","steps":["trace[109142194] 'read index received' (duration: 1.313197983s)","trace[109142194] 'applied index is now lower than readState.Index' (duration: 1.045763ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:17:02.729834Z","caller":"traceutil/trace.go:171","msg":"trace[921483043] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"618.045353ms","start":"2026-06-25T22:17:02.111777Z","end":"2026-06-25T22:17:02.729822Z","steps":["trace[921483043] 'process raft request' (duration: 617.737949ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:17:02.729929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:17:02.111737Z","time spent":"618.129984ms","remote":"127.0.0.1:47156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-25T22:17:02.730128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314761294s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-25T22:17:02.73015Z","caller":"traceutil/trace.go:171","msg":"trace[1336077668] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"1.314835195s","start":"2026-06-25T22:17:01.415308Z","end":"2026-06-25T22:17:02.730143Z","steps":["trace[1336077668] 'agreement among raft nodes before linearized reading' (duration: 1.314749753s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:17:02.730168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:17:01.415284Z","time spent":"1.314880817s","remote":"127.0.0.1:33114","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-06-25T22:17:02.730342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.159877948s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-25T22:17:02.730444Z","caller":"traceutil/trace.go:171","msg":"trace[1318598030] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:794; }","duration":"1.16007207s","start":"2026-06-25T22:17:01.570347Z","end":"2026-06-25T22:17:02.730419Z","steps":["trace[1318598030] 'agreement among raft nodes before linearized reading' (duration: 1.159864697s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:17:02.730454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"947.939046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:17:02.730494Z","caller":"traceutil/trace.go:171","msg":"trace[1616642127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"948.024346ms","start":"2026-06-25T22:17:01.782457Z","end":"2026-06-25T22:17:02.730482Z","steps":["trace[1616642127] 'agreement among raft nodes before linearized reading' (duration: 947.972156ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:17:02.730495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:17:01.570316Z","time spent":"1.160167621s","remote":"127.0.0.1:47088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":29,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-06-25T22:17:02.730523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:17:01.782423Z","time spent":"948.094148ms","remote":"127.0.0.1:46942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-25T22:17:02.730663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.414422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-25T22:17:02.73069Z","caller":"traceutil/trace.go:171","msg":"trace[741265873] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:794; }","duration":"916.484423ms","start":"2026-06-25T22:17:01.814196Z","end":"2026-06-25T22:17:02.730681Z","steps":["trace[741265873] 'agreement among raft nodes before linearized reading' (duration: 916.441432ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:17:02.730713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:17:01.814164Z","time spent":"916.539303ms","remote":"127.0.0.1:33098","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-06-25T22:17:02.730918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014862031s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:17:02.730954Z","caller":"traceutil/trace.go:171","msg":"trace[791050823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"1.014997743s","start":"2026-06-25T22:17:01.715946Z","end":"2026-06-25T22:17:02.730944Z","steps":["trace[791050823] 'agreement among raft nodes before linearized reading' (duration: 1.014878741s)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:17:02.730983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:17:01.715917Z","time spent":"1.015059973s","remote":"127.0.0.1:46926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-25T22:17:33.204355Z","caller":"traceutil/trace.go:171","msg":"trace[1012118083] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"152.515273ms","start":"2026-06-25T22:17:33.051819Z","end":"2026-06-25T22:17:33.204334Z","steps":["trace[1012118083] 'process raft request' (duration: 152.096188ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:17:33.204753Z","caller":"traceutil/trace.go:171","msg":"trace[576237555] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"152.829215ms","start":"2026-06-25T22:17:33.051876Z","end":"2026-06-25T22:17:33.204706Z","steps":["trace[576237555] 'process raft request' (duration: 152.432221ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:17:33.204979Z","caller":"traceutil/trace.go:171","msg":"trace[908604345] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"152.643014ms","start":"2026-06-25T22:17:33.052309Z","end":"2026-06-25T22:17:33.204952Z","steps":["trace[908604345] 'process raft request' (duration: 152.28382ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:17:55.601082Z","caller":"traceutil/trace.go:171","msg":"trace[440344161] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"115.821373ms","start":"2026-06-25T22:17:55.485213Z","end":"2026-06-25T22:17:55.601034Z","steps":["trace[440344161] 'process raft request' (duration: 115.588541ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:17:55.613469Z","caller":"traceutil/trace.go:171","msg":"trace[429913609] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"127.182687ms","start":"2026-06-25T22:17:55.48627Z","end":"2026-06-25T22:17:55.613452Z","steps":["trace[429913609] 'process raft request' (duration: 126.711682ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:17:55.613697Z","caller":"traceutil/trace.go:171","msg":"trace[763167644] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"127.195517ms","start":"2026-06-25T22:17:55.486479Z","end":"2026-06-25T22:17:55.613674Z","steps":["trace[763167644] 'process raft request' (duration: 126.583121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:17:56.020741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.767887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:17:56.020837Z","caller":"traceutil/trace.go:171","msg":"trace[1067825957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1103; }","duration":"239.921348ms","start":"2026-06-25T22:17:55.780892Z","end":"2026-06-25T22:17:56.020813Z","steps":["trace[1067825957] 'range keys from in-memory index tree' (duration: 239.618696ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:18:06.589125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.833894ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-25T22:18:07.590033Z","caller":"traceutil/trace.go:171","msg":"trace[819054321] linearizableReadLoop","detail":"{readStateIndex:1187; appliedIndex:1186; }","duration":"118.521408ms","start":"2026-06-25T22:18:07.47149Z","end":"2026-06-25T22:18:07.590012Z","steps":["trace[819054321] 'read index received' (duration: 118.363546ms)","trace[819054321] 'applied index is now lower than readState.Index' (duration: 156.922µs)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:18:07.59014Z","caller":"traceutil/trace.go:171","msg":"trace[1445392851] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"178.308801ms","start":"2026-06-25T22:18:07.411803Z","end":"2026-06-25T22:18:07.590112Z","steps":["trace[1445392851] 'process raft request' (duration: 178.066359ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:18:07.590204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.698879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-25T22:18:07.59023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.134468ms","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-06-25T22:18:07.590282Z","caller":"traceutil/trace.go:171","msg":"trace[1693102980] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1138; }","duration":"118.81707ms","start":"2026-06-25T22:18:07.471418Z","end":"2026-06-25T22:18:07.590235Z","steps":["trace[1693102980] 'agreement among raft nodes before linearized reading' (duration: 118.734279ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:18:07.590325Z","caller":"traceutil/trace.go:171","msg":"trace[1172331483] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1138; }","duration":"103.270098ms","start":"2026-06-25T22:18:07.487026Z","end":"2026-06-25T22:18:07.590296Z","steps":["trace[1172331483] 'agreement among raft nodes before linearized reading' (duration: 103.137017ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:18:09.833893Z","caller":"traceutil/trace.go:171","msg":"trace[1142473172] linearizableReadLoop","detail":"{readStateIndex:1195; appliedIndex:1194; }","duration":"100.78225ms","start":"2026-06-25T22:18:09.733074Z","end":"2026-06-25T22:18:09.833856Z","steps":["trace[1142473172] 'read index received' (duration: 22.585629ms)","trace[1142473172] 'applied index is now lower than readState.Index' (duration: 78.195221ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:18:09.834008Z","caller":"traceutil/trace.go:171","msg":"trace[857769315] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"132.541809ms","start":"2026-06-25T22:18:09.701432Z","end":"2026-06-25T22:18:09.833973Z","steps":["trace[857769315] 'process raft request' (duration: 54.300908ms)","trace[857769315] 'compare' (duration: 77.936367ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:18:09.83481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.699068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-25T22:18:09.83493Z","caller":"traceutil/trace.go:171","msg":"trace[1097724024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1146; }","duration":"101.876949ms","start":"2026-06-25T22:18:09.733029Z","end":"2026-06-25T22:18:09.834906Z","steps":["trace[1097724024] 'agreement among raft nodes before linearized reading' (duration: 101.030312ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:18:10.161174Z","caller":"traceutil/trace.go:171","msg":"trace[1352846057] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"242.300213ms","start":"2026-06-25T22:18:09.918842Z","end":"2026-06-25T22:18:10.161142Z","steps":["trace[1352846057] 'process raft request' (duration: 242.110781ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:18:13.822394Z","caller":"traceutil/trace.go:171","msg":"trace[33726800] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"141.889538ms","start":"2026-06-25T22:18:13.680478Z","end":"2026-06-25T22:18:13.822368Z","steps":["trace[33726800] 'process raft request' (duration: 141.690006ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:18:14.063601Z","caller":"traceutil/trace.go:171","msg":"trace[1868252293] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"120.145508ms","start":"2026-06-25T22:18:13.943431Z","end":"2026-06-25T22:18:14.063577Z","steps":["trace[1868252293] 'process raft request' (duration: 120.093047ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:18:14.063836Z","caller":"traceutil/trace.go:171","msg":"trace[1300867122] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"120.660983ms","start":"2026-06-25T22:18:13.943139Z","end":"2026-06-25T22:18:14.0638Z","steps":["trace[1300867122] 'process raft request' (duration: 104.485866ms)","trace[1300867122] 'compare' (duration: 15.771572ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:18:34.187159Z","caller":"traceutil/trace.go:171","msg":"trace[956395742] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"110.466478ms","start":"2026-06-25T22:18:34.076661Z","end":"2026-06-25T22:18:34.187127Z","steps":["trace[956395742] 'process raft request' (duration: 110.280136ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:18:38.424731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.878583ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-25T22:18:38.424915Z","caller":"traceutil/trace.go:171","msg":"trace[1253621932] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1293; }","duration":"141.029205ms","start":"2026-06-25T22:18:38.283868Z","end":"2026-06-25T22:18:38.424897Z","steps":["trace[1253621932] 'read index received' (duration: 39.972821ms)","trace[1253621932] 'applied index is now lower than readState.Index' (duration: 101.055344ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:18:38.425018Z","caller":"traceutil/trace.go:171","msg":"trace[1548787701] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"147.187011ms","start":"2026-06-25T22:18:38.277806Z","end":"2026-06-25T22:18:38.424993Z","steps":["trace[1548787701] 'process raft request' (duration: 147.03951ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:18:38.425105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.237757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-25T22:18:38.425182Z","caller":"traceutil/trace.go:171","msg":"trace[285698487] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1240; }","duration":"141.339898ms","start":"2026-06-25T22:18:38.283829Z","end":"2026-06-25T22:18:38.425169Z","steps":["trace[285698487] 'agreement among raft nodes before linearized reading' (duration: 141.166876ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:18:38.425179Z","caller":"traceutil/trace.go:171","msg":"trace[17405855] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"148.889587ms","start":"2026-06-25T22:18:38.276259Z","end":"2026-06-25T22:18:38.425148Z","steps":["trace[17405855] 'process raft request' (duration: 47.50136ms)","trace[17405855] 'compare' (duration: 100.751492ms)"],"step_count":2} {"level":"info","ts":"2026-06-25T22:18:48.048651Z","caller":"traceutil/trace.go:171","msg":"trace[692204838] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"183.287706ms","start":"2026-06-25T22:18:47.865331Z","end":"2026-06-25T22:18:48.048619Z","steps":["trace[692204838] 'process raft request' (duration: 183.139154ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:18:56.98099Z","caller":"traceutil/trace.go:171","msg":"trace[739897132] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"885.482244ms","start":"2026-06-25T22:18:56.09548Z","end":"2026-06-25T22:18:56.980962Z","steps":["trace[739897132] 'process raft request' (duration: 885.302002ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:18:56.981139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:18:56.095463Z","time spent":"885.602036ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:18:56.992547Z","caller":"traceutil/trace.go:171","msg":"trace[982319896] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"653.966459ms","start":"2026-06-25T22:18:56.338557Z","end":"2026-06-25T22:18:56.992524Z","steps":["trace[982319896] 'process raft request' (duration: 653.807808ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:18:56.992756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:18:56.338532Z","time spent":"654.134801ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-25T22:18:56.996195Z","caller":"traceutil/trace.go:171","msg":"trace[609591859] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1393; }","duration":"486.594804ms","start":"2026-06-25T22:18:56.509573Z","end":"2026-06-25T22:18:56.996168Z","steps":["trace[609591859] 'read index received' (duration: 471.244595ms)","trace[609591859] 'applied index is now lower than readState.Index' (duration: 15.348769ms)"],"step_count":2} {"level":"warn","ts":"2026-06-25T22:18:56.996475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.900176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-25T22:18:56.996524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.504062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-25T22:18:56.996593Z","caller":"traceutil/trace.go:171","msg":"trace[1136488861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1336; }","duration":"487.057867ms","start":"2026-06-25T22:18:56.50952Z","end":"2026-06-25T22:18:56.996578Z","steps":["trace[1136488861] 'agreement among raft nodes before linearized reading' (duration: 486.812175ms)"],"step_count":1} {"level":"info","ts":"2026-06-25T22:18:56.996684Z","caller":"traceutil/trace.go:171","msg":"trace[169039151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1336; }","duration":"486.654634ms","start":"2026-06-25T22:18:56.509963Z","end":"2026-06-25T22:18:56.996617Z","steps":["trace[169039151] 'agreement among raft nodes before linearized reading' (duration: 486.407032ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:18:56.996749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:18:56.509502Z","time spent":"487.193689ms","remote":"127.0.0.1:33114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-25T22:18:56.997014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.853908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-06-25T22:18:56.997037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.272869ms","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-06-25T22:18:56.997099Z","caller":"traceutil/trace.go:171","msg":"trace[795679877] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1336; }","duration":"393.407841ms","start":"2026-06-25T22:18:56.603677Z","end":"2026-06-25T22:18:56.997085Z","steps":["trace[795679877] 'agreement among raft nodes before linearized reading' (duration: 393.188719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:18:56.997136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:18:56.603666Z","time spent":"393.462122ms","remote":"127.0.0.1:33114","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":"info","ts":"2026-06-25T22:18:56.997083Z","caller":"traceutil/trace.go:171","msg":"trace[649443580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1336; }","duration":"485.941008ms","start":"2026-06-25T22:18:56.511129Z","end":"2026-06-25T22:18:56.99707Z","steps":["trace[649443580] 'agreement among raft nodes before linearized reading' (duration: 485.761157ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:18:56.997194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:18:56.511121Z","time spent":"486.062069ms","remote":"127.0.0.1:33114","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-06-25T22:18:56.997212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.318229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-25T22:18:56.99733Z","caller":"traceutil/trace.go:171","msg":"trace[1365761416] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1336; }","duration":"216.789392ms","start":"2026-06-25T22:18:56.780526Z","end":"2026-06-25T22:18:56.997315Z","steps":["trace[1365761416] 'agreement among raft nodes before linearized reading' (duration: 216.281428ms)"],"step_count":1} {"level":"warn","ts":"2026-06-25T22:18:56.996804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-25T22:18:56.509943Z","time spent":"486.783465ms","remote":"127.0.0.1:33114","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\" "}