{"level":"warn","ts":"2026-03-29T01:51:05.720065Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-29T01:51:05.720278Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.122:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.122:2380","--initial-cluster=instance=https://199.204.45.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.122:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-29T01:51:05.720416Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-29T01:51:05.720429Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-03-29T01:51:05.72046Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-29T01:51:05.72181Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"]} {"level":"info","ts":"2026-03-29T01:51:05.721963Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.122:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-29T01:51:05.757879Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"35.471382ms"} {"level":"info","ts":"2026-03-29T01:51:05.766646Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d2c3f297b56db0c4","cluster-id":"becafec23d27c587"} {"level":"info","ts":"2026-03-29T01:51:05.766784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=()"} {"level":"info","ts":"2026-03-29T01:51:05.766847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 0"} {"level":"info","ts":"2026-03-29T01:51:05.766864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d2c3f297b56db0c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-29T01:51:05.766873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became follower at term 1"} {"level":"info","ts":"2026-03-29T01:51:05.766911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"warn","ts":"2026-03-29T01:51:05.774001Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-29T01:51:05.775936Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-29T01:51:05.777609Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-29T01:51:05.781143Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d2c3f297b56db0c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-29T01:51:05.781415Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d2c3f297b56db0c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-29T01:51:05.782677Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-29T01:51:05.782867Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-29T01:51:05.782894Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-29T01:51:05.785098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 switched to configuration voters=(15187249101821292740)"} {"level":"info","ts":"2026-03-29T01:51:05.785406Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","added-peer-id":"d2c3f297b56db0c4","added-peer-peer-urls":["https://199.204.45.122:2380"]} {"level":"info","ts":"2026-03-29T01:51:05.787067Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-29T01:51:05.78719Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-03-29T01:51:05.787285Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.122:2380"} {"level":"info","ts":"2026-03-29T01:51:05.78744Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d2c3f297b56db0c4","initial-advertise-peer-urls":["https://199.204.45.122:2380"],"listen-peer-urls":["https://199.204.45.122:2380"],"advertise-client-urls":["https://199.204.45.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-29T01:51:05.787498Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-29T01:51:06.768274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 is starting a new election at term 1"} {"level":"info","ts":"2026-03-29T01:51:06.768359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-29T01:51:06.768425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgPreVoteResp from d2c3f297b56db0c4 at term 1"} {"level":"info","ts":"2026-03-29T01:51:06.768445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became candidate at term 2"} {"level":"info","ts":"2026-03-29T01:51:06.768482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 received MsgVoteResp from d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-03-29T01:51:06.768499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d2c3f297b56db0c4 became leader at term 2"} {"level":"info","ts":"2026-03-29T01:51:06.768518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d2c3f297b56db0c4 elected leader d2c3f297b56db0c4 at term 2"} {"level":"info","ts":"2026-03-29T01:51:06.778245Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T01:51:06.801773Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-29T01:51:06.801909Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-29T01:51:06.802333Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-29T01:51:06.802377Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-29T01:51:06.801772Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d2c3f297b56db0c4","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.122:2379]}","request-path":"/0/members/d2c3f297b56db0c4/attributes","cluster-id":"becafec23d27c587","publish-timeout":"7s"} {"level":"info","ts":"2026-03-29T01:51:06.806507Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.122:2379"} {"level":"info","ts":"2026-03-29T01:51:06.807077Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-29T01:51:06.86353Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"becafec23d27c587","local-member-id":"d2c3f297b56db0c4","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T01:51:06.863782Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T01:51:06.863925Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-29T01:51:08.476852Z","caller":"traceutil/trace.go:171","msg":"trace[1681290467] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"111.906008ms","start":"2026-03-29T01:51:08.364885Z","end":"2026-03-29T01:51:08.476791Z","steps":["trace[1681290467] 'process raft request' (duration: 111.714352ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:08.476985Z","caller":"traceutil/trace.go:171","msg":"trace[1958889569] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"108.650384ms","start":"2026-03-29T01:51:08.368296Z","end":"2026-03-29T01:51:08.476946Z","steps":["trace[1958889569] 'process raft request' (duration: 108.366175ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:08.477011Z","caller":"traceutil/trace.go:171","msg":"trace[195761010] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"112.910599ms","start":"2026-03-29T01:51:08.364074Z","end":"2026-03-29T01:51:08.476975Z","steps":["trace[195761010] 'process raft request' (duration: 71.91884ms)","trace[195761010] 'compare' (duration: 40.37619ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:10.55204Z","caller":"traceutil/trace.go:171","msg":"trace[1135405019] linearizableReadLoop","detail":"{readStateIndex:127; appliedIndex:126; }","duration":"175.503462ms","start":"2026-03-29T01:51:10.37651Z","end":"2026-03-29T01:51:10.552014Z","steps":["trace[1135405019] 'read index received' (duration: 94.170772ms)","trace[1135405019] 'applied index is now lower than readState.Index' (duration: 81.3314ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:10.552215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.671678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a12cdeefc9f27e\" ","response":"range_response_count:1 size:654"} {"level":"info","ts":"2026-03-29T01:51:10.552275Z","caller":"traceutil/trace.go:171","msg":"trace[1771078561] range","detail":"{range_begin:/registry/events/default/instance.18a12cdeefc9f27e; range_end:; response_count:1; response_revision:122; }","duration":"175.76581ms","start":"2026-03-29T01:51:10.376497Z","end":"2026-03-29T01:51:10.552262Z","steps":["trace[1771078561] 'agreement among raft nodes before linearized reading' (duration: 175.620506ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:10.552375Z","caller":"traceutil/trace.go:171","msg":"trace[1037971908] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"176.021549ms","start":"2026-03-29T01:51:10.376311Z","end":"2026-03-29T01:51:10.552333Z","steps":["trace[1037971908] 'process raft request' (duration: 94.346597ms)","trace[1037971908] 'compare' (duration: 81.134974ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:12.333652Z","caller":"traceutil/trace.go:171","msg":"trace[1043576498] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"118.019833ms","start":"2026-03-29T01:51:12.215582Z","end":"2026-03-29T01:51:12.333602Z","steps":["trace[1043576498] 'process raft request' (duration: 34.142998ms)","trace[1043576498] 'compare' (duration: 83.670468ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:12.656257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.795119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:12.656405Z","caller":"traceutil/trace.go:171","msg":"trace[2089698124] transaction","detail":"{read_only:false; response_revision:223; number_of_response:1; }","duration":"227.474376ms","start":"2026-03-29T01:51:12.428905Z","end":"2026-03-29T01:51:12.65638Z","steps":["trace[2089698124] 'process raft request' (duration: 41.85194ms)","trace[2089698124] 'compare' (duration: 184.535239ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:12.868981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.344589ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:12.869309Z","caller":"traceutil/trace.go:171","msg":"trace[1939646975] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"116.198501ms","start":"2026-03-29T01:51:12.753079Z","end":"2026-03-29T01:51:12.869278Z","steps":["trace[1939646975] 'process raft request' (duration: 115.988434ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:12.869359Z","caller":"traceutil/trace.go:171","msg":"trace[2111553142] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"115.966273ms","start":"2026-03-29T01:51:12.753171Z","end":"2026-03-29T01:51:12.869138Z","steps":["trace[2111553142] 'process raft request' (duration: 115.934672ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:12.86938Z","caller":"traceutil/trace.go:171","msg":"trace[950482793] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"117.03865ms","start":"2026-03-29T01:51:12.752313Z","end":"2026-03-29T01:51:12.869352Z","steps":["trace[950482793] 'process raft request' (duration: 12.055289ms)","trace[950482793] 'compare' (duration: 104.269846ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:13.028409Z","caller":"traceutil/trace.go:171","msg":"trace[117612025] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"115.212921ms","start":"2026-03-29T01:51:12.913166Z","end":"2026-03-29T01:51:13.028379Z","steps":["trace[117612025] 'process raft request' (duration: 63.518654ms)","trace[117612025] 'compare' (duration: 51.518191ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:13.582577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.314781ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:13.582733Z","caller":"traceutil/trace.go:171","msg":"trace[1088432324] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"144.218299ms","start":"2026-03-29T01:51:13.438484Z","end":"2026-03-29T01:51:13.582703Z","steps":["trace[1088432324] 'process raft request' (duration: 42.697901ms)","trace[1088432324] 'compare' (duration: 101.069752ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:13.758718Z","caller":"traceutil/trace.go:171","msg":"trace[1573925551] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"133.483939ms","start":"2026-03-29T01:51:13.625204Z","end":"2026-03-29T01:51:13.758687Z","steps":["trace[1573925551] 'process raft request' (duration: 80.310097ms)","trace[1573925551] 'compare' (duration: 53.009066ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:13.761812Z","caller":"traceutil/trace.go:171","msg":"trace[462694421] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"117.023411ms","start":"2026-03-29T01:51:13.644759Z","end":"2026-03-29T01:51:13.761782Z","steps":["trace[462694421] 'process raft request' (duration: 116.879706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:13.969357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.801444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:13.969466Z","caller":"traceutil/trace.go:171","msg":"trace[1054244909] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"170.96724ms","start":"2026-03-29T01:51:13.798479Z","end":"2026-03-29T01:51:13.969446Z","steps":["trace[1054244909] 'process raft request' (duration: 63.003961ms)","trace[1054244909] 'compare' (duration: 107.659508ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:14.839431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.995545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:14.839563Z","caller":"traceutil/trace.go:171","msg":"trace[793539955] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"213.914192ms","start":"2026-03-29T01:51:14.625632Z","end":"2026-03-29T01:51:14.839546Z","steps":["trace[793539955] 'process raft request' (duration: 37.74269ms)","trace[793539955] 'compare' (duration: 175.84049ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:15.348241Z","caller":"traceutil/trace.go:171","msg":"trace[1953357756] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"198.493044ms","start":"2026-03-29T01:51:15.149722Z","end":"2026-03-29T01:51:15.348215Z","steps":["trace[1953357756] 'process raft request' (duration: 132.024625ms)","trace[1953357756] 'compare' (duration: 66.278992ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:15.404969Z","caller":"traceutil/trace.go:171","msg":"trace[65283389] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"252.987738ms","start":"2026-03-29T01:51:15.151951Z","end":"2026-03-29T01:51:15.404939Z","steps":["trace[65283389] 'process raft request' (duration: 252.803022ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:15.570177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.919511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:15.570275Z","caller":"traceutil/trace.go:171","msg":"trace[1383098884] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:279; }","duration":"158.825617ms","start":"2026-03-29T01:51:15.411434Z","end":"2026-03-29T01:51:15.570259Z","steps":["trace[1383098884] 'read index received' (duration: 48.673517ms)","trace[1383098884] 'applied index is now lower than readState.Index' (duration: 110.15102ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:15.570394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.948562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-29T01:51:15.570438Z","caller":"traceutil/trace.go:171","msg":"trace[515676967] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:274; }","duration":"159.006324ms","start":"2026-03-29T01:51:15.41142Z","end":"2026-03-29T01:51:15.570426Z","steps":["trace[515676967] 'agreement among raft nodes before linearized reading' (duration: 158.881679ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:15.570572Z","caller":"traceutil/trace.go:171","msg":"trace[742147253] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"160.89921ms","start":"2026-03-29T01:51:15.409606Z","end":"2026-03-29T01:51:15.570505Z","steps":["trace[742147253] 'process raft request' (duration: 50.588485ms)","trace[742147253] 'compare' (duration: 109.773396ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:15.990545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.024001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:15.990863Z","caller":"traceutil/trace.go:171","msg":"trace[644621838] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"325.199942ms","start":"2026-03-29T01:51:15.665616Z","end":"2026-03-29T01:51:15.990815Z","steps":["trace[644621838] 'process raft request' (duration: 40.837419ms)","trace[644621838] 'compare' (duration: 283.887406ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:15.991219Z","caller":"traceutil/trace.go:171","msg":"trace[1569461508] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"324.254218ms","start":"2026-03-29T01:51:15.666519Z","end":"2026-03-29T01:51:15.990773Z","steps":["trace[1569461508] 'process raft request' (duration: 324.141044ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:15.991123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:15.665595Z","time spent":"325.43154ms","remote":"127.0.0.1:60504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:51:15.991399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:15.666497Z","time spent":"324.822389ms","remote":"127.0.0.1:60618","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":324,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:51:18.875049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.737127896s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:18.875265Z","caller":"traceutil/trace.go:171","msg":"trace[762998429] linearizableReadLoop","detail":"{readStateIndex:286; appliedIndex:285; }","duration":"2.393154124s","start":"2026-03-29T01:51:16.48209Z","end":"2026-03-29T01:51:18.875244Z","steps":["trace[762998429] 'read index received' (duration: 41.251µs)","trace[762998429] 'applied index is now lower than readState.Index' (duration: 2.393111343s)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:18.875342Z","caller":"traceutil/trace.go:171","msg":"trace[950327981] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"2.830304251s","start":"2026-03-29T01:51:16.045011Z","end":"2026-03-29T01:51:18.875316Z","steps":["trace[950327981] 'process raft request' (duration: 92.826562ms)","trace[950327981] 'compare' (duration: 2.736737272s)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:18.875423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:16.044996Z","time spent":"2.830391934s","remote":"127.0.0.1:60504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:51:18.875634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.393495447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-29T01:51:18.875674Z","caller":"traceutil/trace.go:171","msg":"trace[1710676604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:280; }","duration":"2.393613161s","start":"2026-03-29T01:51:16.48205Z","end":"2026-03-29T01:51:18.875663Z","steps":["trace[1710676604] 'agreement among raft nodes before linearized reading' (duration: 2.393286169s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:18.875708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:16.482026Z","time spent":"2.393674453s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-29T01:51:19.380509Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478506894731657,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:51:19.881222Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478506894731657,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:51:20.001582Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.034604884s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-29T01:51:20.217576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.617862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:20.217683Z","caller":"traceutil/trace.go:171","msg":"trace[2141384773] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"3.250795838s","start":"2026-03-29T01:51:16.966869Z","end":"2026-03-29T01:51:20.217664Z","steps":["trace[2141384773] 'process raft request' (duration: 3.03501934s)","trace[2141384773] 'compare' (duration: 215.464176ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:20.217739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:16.966846Z","time spent":"3.250871751s","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:51:20.386511Z","caller":"traceutil/trace.go:171","msg":"trace[229637714] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"1.858104783s","start":"2026-03-29T01:51:18.52837Z","end":"2026-03-29T01:51:20.386475Z","steps":["trace[229637714] 'process raft request' (duration: 1.82378702s)","trace[229637714] 'compare' (duration: 34.050453ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:20.386685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:18.528336Z","time spent":"1.858265078s","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:51:20.386882Z","caller":"traceutil/trace.go:171","msg":"trace[2023319231] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"1.555663766s","start":"2026-03-29T01:51:18.831191Z","end":"2026-03-29T01:51:20.386855Z","steps":["trace[2023319231] 'process raft request' (duration: 1.555349944s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:20.387029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:18.831163Z","time spent":"1.555789671s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:51:20.38705Z","caller":"traceutil/trace.go:171","msg":"trace[888034757] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:286; }","duration":"1.511737571s","start":"2026-03-29T01:51:18.875298Z","end":"2026-03-29T01:51:20.387036Z","steps":["trace[888034757] 'read index received' (duration: 1.126634567s)","trace[888034757] 'applied index is now lower than readState.Index' (duration: 385.101654ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:20.387148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.592932931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:51:20.38718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.433548818s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-29T01:51:20.387211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.8436156s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3690"} {"level":"info","ts":"2026-03-29T01:51:20.387232Z","caller":"traceutil/trace.go:171","msg":"trace[1090393036] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:285; }","duration":"2.593040825s","start":"2026-03-29T01:51:17.794167Z","end":"2026-03-29T01:51:20.387208Z","steps":["trace[1090393036] 'agreement among raft nodes before linearized reading' (duration: 2.592926981s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:20.38726Z","caller":"traceutil/trace.go:171","msg":"trace[2038224528] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:285; }","duration":"3.843710223s","start":"2026-03-29T01:51:16.543537Z","end":"2026-03-29T01:51:20.387247Z","steps":["trace[2038224528] 'agreement among raft nodes before linearized reading' (duration: 3.843595809s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:20.3873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:16.543516Z","time spent":"3.843775726s","remote":"127.0.0.1:60470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3714,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-29T01:51:20.387299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.43359507s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-29T01:51:20.387358Z","caller":"traceutil/trace.go:171","msg":"trace[51431007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:285; }","duration":"3.433703034s","start":"2026-03-29T01:51:16.953639Z","end":"2026-03-29T01:51:20.387342Z","steps":["trace[51431007] 'agreement among raft nodes before linearized reading' (duration: 3.433631191s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:20.387423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.147487037s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:51:20.387441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:16.953608Z","time spent":"3.433821698s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-29T01:51:20.387468Z","caller":"traceutil/trace.go:171","msg":"trace[1664902692] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:285; }","duration":"2.147560851s","start":"2026-03-29T01:51:18.239895Z","end":"2026-03-29T01:51:20.387456Z","steps":["trace[1664902692] 'agreement among raft nodes before linearized reading' (duration: 2.147497708s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:20.387538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.41733787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"warn","ts":"2026-03-29T01:51:20.387541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:18.23988Z","time spent":"2.147651714s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-03-29T01:51:20.387581Z","caller":"traceutil/trace.go:171","msg":"trace[1469215951] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:285; }","duration":"1.417421083s","start":"2026-03-29T01:51:18.970148Z","end":"2026-03-29T01:51:20.387569Z","steps":["trace[1469215951] 'agreement among raft nodes before linearized reading' (duration: 1.417322489s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:20.387664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:18.970126Z","time spent":"1.417522487s","remote":"127.0.0.1:60504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"info","ts":"2026-03-29T01:51:20.387241Z","caller":"traceutil/trace.go:171","msg":"trace[1418009537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:285; }","duration":"3.433653292s","start":"2026-03-29T01:51:16.953574Z","end":"2026-03-29T01:51:20.387227Z","steps":["trace[1418009537] 'agreement among raft nodes before linearized reading' (duration: 3.433547778s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:20.38783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:16.953552Z","time spent":"3.434246914s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-29T01:51:20.387311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:17.794147Z","time spent":"2.5931482s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-29T01:51:20.387371Z","caller":"traceutil/trace.go:171","msg":"trace[1556837868] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"1.415826104s","start":"2026-03-29T01:51:18.971529Z","end":"2026-03-29T01:51:20.387356Z","steps":["trace[1556837868] 'process raft request' (duration: 1.415234832s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:20.388415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:18.971514Z","time spent":"1.416708457s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:51:20.388394Z","caller":"traceutil/trace.go:171","msg":"trace[1102206904] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"1.830008873s","start":"2026-03-29T01:51:18.55661Z","end":"2026-03-29T01:51:20.386619Z","steps":["trace[1102206904] 'process raft request' (duration: 1.829767654s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:20.388871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:18.556577Z","time spent":"1.832240419s","remote":"127.0.0.1:60470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3676,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:51:20.647419Z","caller":"traceutil/trace.go:171","msg":"trace[811097726] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"253.336485ms","start":"2026-03-29T01:51:20.394053Z","end":"2026-03-29T01:51:20.647389Z","steps":["trace[811097726] 'process raft request' (duration: 189.798513ms)","trace[811097726] 'compare' (duration: 63.294173ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:20.647478Z","caller":"traceutil/trace.go:171","msg":"trace[2081139634] linearizableReadLoop","detail":"{readStateIndex:296; appliedIndex:292; }","duration":"251.725683ms","start":"2026-03-29T01:51:20.395736Z","end":"2026-03-29T01:51:20.647461Z","steps":["trace[2081139634] 'read index received' (duration: 15.916574ms)","trace[2081139634] 'applied index is now lower than readState.Index' (duration: 235.808219ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:20.647581Z","caller":"traceutil/trace.go:171","msg":"trace[222457708] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"252.580476ms","start":"2026-03-29T01:51:20.394988Z","end":"2026-03-29T01:51:20.647568Z","steps":["trace[222457708] 'process raft request' (duration: 252.43036ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:20.647543Z","caller":"traceutil/trace.go:171","msg":"trace[1178322200] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"252.911108ms","start":"2026-03-29T01:51:20.394571Z","end":"2026-03-29T01:51:20.647483Z","steps":["trace[1178322200] 'process raft request' (duration: 252.723571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:20.647651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.88867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:51:20.647695Z","caller":"traceutil/trace.go:171","msg":"trace[1326988804] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:290; }","duration":"251.969983ms","start":"2026-03-29T01:51:20.395713Z","end":"2026-03-29T01:51:20.647682Z","steps":["trace[1326988804] 'agreement among raft nodes before linearized reading' (duration: 251.868639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:20.64771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.744814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-03-29T01:51:20.647805Z","caller":"traceutil/trace.go:171","msg":"trace[2092034649] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:290; }","duration":"251.881139ms","start":"2026-03-29T01:51:20.395912Z","end":"2026-03-29T01:51:20.647793Z","steps":["trace[2092034649] 'agreement among raft nodes before linearized reading' (duration: 251.694022ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:20.647966Z","caller":"traceutil/trace.go:171","msg":"trace[605874292] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"253.339934ms","start":"2026-03-29T01:51:20.394612Z","end":"2026-03-29T01:51:20.647952Z","steps":["trace[605874292] 'process raft request' (duration: 252.749922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:20.993382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.070545ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:20.99366Z","caller":"traceutil/trace.go:171","msg":"trace[103839785] linearizableReadLoop","detail":"{readStateIndex:301; appliedIndex:300; }","duration":"284.83656ms","start":"2026-03-29T01:51:20.708802Z","end":"2026-03-29T01:51:20.993638Z","steps":["trace[103839785] 'read index received' (duration: 178.37515ms)","trace[103839785] 'applied index is now lower than readState.Index' (duration: 106.45908ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:20.994009Z","caller":"traceutil/trace.go:171","msg":"trace[998709334] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"286.892248ms","start":"2026-03-29T01:51:20.706872Z","end":"2026-03-29T01:51:20.993764Z","steps":["trace[998709334] 'process raft request' (duration: 180.362905ms)","trace[998709334] 'compare' (duration: 105.891179ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:20.994504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.985434ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:51:20.9946Z","caller":"traceutil/trace.go:171","msg":"trace[1709657010] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:295; }","duration":"120.079007ms","start":"2026-03-29T01:51:20.874504Z","end":"2026-03-29T01:51:20.994583Z","steps":["trace[1709657010] 'agreement among raft nodes before linearized reading' (duration: 119.971623ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:20.994153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.331269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-29T01:51:20.995136Z","caller":"traceutil/trace.go:171","msg":"trace[148072502] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:295; }","duration":"286.288875ms","start":"2026-03-29T01:51:20.708784Z","end":"2026-03-29T01:51:20.995073Z","steps":["trace[148072502] 'agreement among raft nodes before linearized reading' (duration: 285.254275ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:21.325766Z","caller":"traceutil/trace.go:171","msg":"trace[198233510] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"176.695429ms","start":"2026-03-29T01:51:21.14902Z","end":"2026-03-29T01:51:21.325716Z","steps":["trace[198233510] 'process raft request' (duration: 95.688546ms)","trace[198233510] 'compare' (duration: 80.632428ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:21.326085Z","caller":"traceutil/trace.go:171","msg":"trace[667562896] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:307; }","duration":"109.010398ms","start":"2026-03-29T01:51:21.217053Z","end":"2026-03-29T01:51:21.326063Z","steps":["trace[667562896] 'read index received' (duration: 27.664473ms)","trace[667562896] 'applied index is now lower than readState.Index' (duration: 81.343585ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:21.326285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.194546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-29T01:51:21.326349Z","caller":"traceutil/trace.go:171","msg":"trace[1712118581] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:303; }","duration":"109.297019ms","start":"2026-03-29T01:51:21.217038Z","end":"2026-03-29T01:51:21.326335Z","steps":["trace[1712118581] 'agreement among raft nodes before linearized reading' (duration: 109.106872ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:21.327442Z","caller":"traceutil/trace.go:171","msg":"trace[842871978] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"108.985178ms","start":"2026-03-29T01:51:21.217848Z","end":"2026-03-29T01:51:21.326833Z","steps":["trace[842871978] 'process raft request' (duration: 108.169266ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:21.495953Z","caller":"traceutil/trace.go:171","msg":"trace[974121837] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"163.354526ms","start":"2026-03-29T01:51:21.33257Z","end":"2026-03-29T01:51:21.495924Z","steps":["trace[974121837] 'process raft request' (duration: 156.882017ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:21.495996Z","caller":"traceutil/trace.go:171","msg":"trace[112366734] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"144.103816ms","start":"2026-03-29T01:51:21.351872Z","end":"2026-03-29T01:51:21.495976Z","steps":["trace[112366734] 'process raft request' (duration: 143.851786ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:21.709206Z","caller":"traceutil/trace.go:171","msg":"trace[1969048558] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"118.083897ms","start":"2026-03-29T01:51:21.591064Z","end":"2026-03-29T01:51:21.709147Z","steps":["trace[1969048558] 'process raft request' (duration: 84.83845ms)","trace[1969048558] 'compare' (duration: 33.079421ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:22.153158Z","caller":"traceutil/trace.go:171","msg":"trace[133467313] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:323; }","duration":"215.938365ms","start":"2026-03-29T01:51:21.93716Z","end":"2026-03-29T01:51:22.153098Z","steps":["trace[133467313] 'read index received' (duration: 168.583194ms)","trace[133467313] 'applied index is now lower than readState.Index' (duration: 47.354341ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:22.153167Z","caller":"traceutil/trace.go:171","msg":"trace[1516780665] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"217.502386ms","start":"2026-03-29T01:51:21.935625Z","end":"2026-03-29T01:51:22.153127Z","steps":["trace[1516780665] 'process raft request' (duration: 170.090282ms)","trace[1516780665] 'compare' (duration: 47.271888ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:22.153306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.125463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-29T01:51:22.15336Z","caller":"traceutil/trace.go:171","msg":"trace[895240935] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:318; }","duration":"216.204826ms","start":"2026-03-29T01:51:21.93714Z","end":"2026-03-29T01:51:22.153345Z","steps":["trace[895240935] 'agreement among raft nodes before linearized reading' (duration: 216.06097ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:22.236137Z","caller":"traceutil/trace.go:171","msg":"trace[101890860] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"298.864269ms","start":"2026-03-29T01:51:21.937254Z","end":"2026-03-29T01:51:22.236118Z","steps":["trace[101890860] 'process raft request' (duration: 298.658771ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:22.236235Z","caller":"traceutil/trace.go:171","msg":"trace[1025386621] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"166.888429ms","start":"2026-03-29T01:51:22.069304Z","end":"2026-03-29T01:51:22.236192Z","steps":["trace[1025386621] 'process raft request' (duration: 166.737363ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:22.236335Z","caller":"traceutil/trace.go:171","msg":"trace[1868002674] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"166.795286ms","start":"2026-03-29T01:51:22.069512Z","end":"2026-03-29T01:51:22.236308Z","steps":["trace[1868002674] 'process raft request' (duration: 166.578247ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:22.236388Z","caller":"traceutil/trace.go:171","msg":"trace[1258469318] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"166.801266ms","start":"2026-03-29T01:51:22.069562Z","end":"2026-03-29T01:51:22.236363Z","steps":["trace[1258469318] 'process raft request' (duration: 166.552646ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:22.236571Z","caller":"traceutil/trace.go:171","msg":"trace[1376320310] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"166.745843ms","start":"2026-03-29T01:51:22.06981Z","end":"2026-03-29T01:51:22.236555Z","steps":["trace[1376320310] 'process raft request' (duration: 166.321837ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:22.236771Z","caller":"traceutil/trace.go:171","msg":"trace[1260699967] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"166.711582ms","start":"2026-03-29T01:51:22.070043Z","end":"2026-03-29T01:51:22.236755Z","steps":["trace[1260699967] 'process raft request' (duration: 166.559276ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:22.292278Z","caller":"traceutil/trace.go:171","msg":"trace[569493329] linearizableReadLoop","detail":"{readStateIndex:331; appliedIndex:324; }","duration":"139.023285ms","start":"2026-03-29T01:51:22.153236Z","end":"2026-03-29T01:51:22.29226Z","steps":["trace[569493329] 'read index received' (duration: 82.720336ms)","trace[569493329] 'applied index is now lower than readState.Index' (duration: 56.301699ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:22.292274Z","caller":"traceutil/trace.go:171","msg":"trace[997453550] transaction","detail":"{read_only:false; number_of_response:0; response_revision:319; }","duration":"170.77459ms","start":"2026-03-29T01:51:22.121475Z","end":"2026-03-29T01:51:22.292249Z","steps":["trace[997453550] 'process raft request' (duration: 170.555391ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:22.29245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.189609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-29T01:51:22.292464Z","caller":"traceutil/trace.go:171","msg":"trace[489376257] transaction","detail":"{read_only:false; response_revision:321; number_of_response:1; }","duration":"135.259649ms","start":"2026-03-29T01:51:22.157075Z","end":"2026-03-29T01:51:22.292335Z","steps":["trace[489376257] 'process raft request' (duration: 135.139274ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:22.292496Z","caller":"traceutil/trace.go:171","msg":"trace[329371118] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:321; }","duration":"355.258402ms","start":"2026-03-29T01:51:21.937225Z","end":"2026-03-29T01:51:22.292483Z","steps":["trace[329371118] 'agreement among raft nodes before linearized reading' (duration: 355.136017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:22.292514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.929673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"warn","ts":"2026-03-29T01:51:22.292559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:21.937222Z","time spent":"355.320185ms","remote":"127.0.0.1:60684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-03-29T01:51:22.292604Z","caller":"traceutil/trace.go:171","msg":"trace[738486266] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:321; }","duration":"347.065708ms","start":"2026-03-29T01:51:21.945523Z","end":"2026-03-29T01:51:22.292589Z","steps":["trace[738486266] 'agreement among raft nodes before linearized reading' (duration: 346.826919ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:22.292659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:21.945506Z","time spent":"347.14461ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":560,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-03-29T01:51:22.292983Z","caller":"traceutil/trace.go:171","msg":"trace[357428088] transaction","detail":"{read_only:false; response_revision:320; number_of_response:1; }","duration":"135.568501ms","start":"2026-03-29T01:51:22.156724Z","end":"2026-03-29T01:51:22.292292Z","steps":["trace[357428088] 'process raft request' (duration: 135.402205ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:22.345968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.450557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"warn","ts":"2026-03-29T01:51:22.346012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.49495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4134"} {"level":"info","ts":"2026-03-29T01:51:22.346039Z","caller":"traceutil/trace.go:171","msg":"trace[26482654] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:322; }","duration":"107.548892ms","start":"2026-03-29T01:51:22.238482Z","end":"2026-03-29T01:51:22.346031Z","steps":["trace[26482654] 'agreement among raft nodes before linearized reading' (duration: 107.486469ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:22.346034Z","caller":"traceutil/trace.go:171","msg":"trace[1718282544] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:322; }","duration":"107.52023ms","start":"2026-03-29T01:51:22.2385Z","end":"2026-03-29T01:51:22.34602Z","steps":["trace[1718282544] 'agreement among raft nodes before linearized reading' (duration: 107.381555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:22.346045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.472328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6755"} {"level":"warn","ts":"2026-03-29T01:51:22.346066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.836142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"warn","ts":"2026-03-29T01:51:22.346067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.376934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4663"} {"level":"info","ts":"2026-03-29T01:51:22.346139Z","caller":"traceutil/trace.go:171","msg":"trace[888677342] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:322; }","duration":"107.943877ms","start":"2026-03-29T01:51:22.238173Z","end":"2026-03-29T01:51:22.346117Z","steps":["trace[888677342] 'agreement among raft nodes before linearized reading' (duration: 107.824642ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:22.346181Z","caller":"traceutil/trace.go:171","msg":"trace[741490559] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:322; }","duration":"107.568262ms","start":"2026-03-29T01:51:22.238536Z","end":"2026-03-29T01:51:22.346105Z","steps":["trace[741490559] 'agreement among raft nodes before linearized reading' (duration: 107.319083ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:22.346238Z","caller":"traceutil/trace.go:171","msg":"trace[1901445639] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:322; }","duration":"107.485469ms","start":"2026-03-29T01:51:22.238651Z","end":"2026-03-29T01:51:22.346136Z","steps":["trace[1901445639] 'agreement among raft nodes before linearized reading' (duration: 107.363914ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:22.346872Z","caller":"traceutil/trace.go:171","msg":"trace[321308699] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"105.245991ms","start":"2026-03-29T01:51:22.240702Z","end":"2026-03-29T01:51:22.345948Z","steps":["trace[321308699] 'process raft request' (duration: 104.994472ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:23.242986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.997572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:23.243195Z","caller":"traceutil/trace.go:171","msg":"trace[1776066609] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:367; }","duration":"142.651461ms","start":"2026-03-29T01:51:23.100528Z","end":"2026-03-29T01:51:23.24318Z","steps":["trace[1776066609] 'read index received' (duration: 25.359797ms)","trace[1776066609] 'applied index is now lower than readState.Index' (duration: 117.290814ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:23.243315Z","caller":"traceutil/trace.go:171","msg":"trace[1227342464] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"157.229835ms","start":"2026-03-29T01:51:23.086074Z","end":"2026-03-29T01:51:23.243304Z","steps":["trace[1227342464] 'process raft request' (duration: 39.846247ms)","trace[1227342464] 'compare' (duration: 116.748503ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:23.243314Z","caller":"traceutil/trace.go:171","msg":"trace[1206365375] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"157.230085ms","start":"2026-03-29T01:51:23.086074Z","end":"2026-03-29T01:51:23.243304Z","steps":["trace[1206365375] 'process raft request' (duration: 157.037267ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:23.243599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.073758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-03-29T01:51:23.243632Z","caller":"traceutil/trace.go:171","msg":"trace[626902055] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:354; }","duration":"143.150581ms","start":"2026-03-29T01:51:23.100471Z","end":"2026-03-29T01:51:23.243622Z","steps":["trace[626902055] 'agreement among raft nodes before linearized reading' (duration: 143.088239ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:23.502839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.399651ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:23.503041Z","caller":"traceutil/trace.go:171","msg":"trace[49494364] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:370; }","duration":"203.078898ms","start":"2026-03-29T01:51:23.299944Z","end":"2026-03-29T01:51:23.503023Z","steps":["trace[49494364] 'read index received' (duration: 90.337833ms)","trace[49494364] 'applied index is now lower than readState.Index' (duration: 112.739385ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:23.503187Z","caller":"traceutil/trace.go:171","msg":"trace[133619012] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"249.575497ms","start":"2026-03-29T01:51:23.25359Z","end":"2026-03-29T01:51:23.503166Z","steps":["trace[133619012] 'process raft request' (duration: 136.775431ms)","trace[133619012] 'compare' (duration: 112.244935ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:23.503306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.354099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"warn","ts":"2026-03-29T01:51:23.503409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.090092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-29T01:51:23.503466Z","caller":"traceutil/trace.go:171","msg":"trace[2124648018] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:356; }","duration":"153.192496ms","start":"2026-03-29T01:51:23.35026Z","end":"2026-03-29T01:51:23.503452Z","steps":["trace[2124648018] 'agreement among raft nodes before linearized reading' (duration: 152.995048ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:23.503561Z","caller":"traceutil/trace.go:171","msg":"trace[1258654006] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:356; }","duration":"203.524836ms","start":"2026-03-29T01:51:23.299905Z","end":"2026-03-29T01:51:23.50343Z","steps":["trace[1258654006] 'agreement among raft nodes before linearized reading' (duration: 203.270626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:23.503668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.313244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-29T01:51:23.50371Z","caller":"traceutil/trace.go:171","msg":"trace[1849870517] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:356; }","duration":"103.384536ms","start":"2026-03-29T01:51:23.400315Z","end":"2026-03-29T01:51:23.5037Z","steps":["trace[1849870517] 'agreement among raft nodes before linearized reading' (duration: 103.320244ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:23.719059Z","caller":"traceutil/trace.go:171","msg":"trace[1257184056] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:374; }","duration":"118.837545ms","start":"2026-03-29T01:51:23.600196Z","end":"2026-03-29T01:51:23.719033Z","steps":["trace[1257184056] 'read index received' (duration: 77.322392ms)","trace[1257184056] 'applied index is now lower than readState.Index' (duration: 41.513893ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:23.719201Z","caller":"traceutil/trace.go:171","msg":"trace[1017548807] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"124.898573ms","start":"2026-03-29T01:51:23.594262Z","end":"2026-03-29T01:51:23.719161Z","steps":["trace[1017548807] 'process raft request' (duration: 124.637203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:23.7193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.069654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-29T01:51:23.719481Z","caller":"traceutil/trace.go:171","msg":"trace[914845768] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:361; }","duration":"119.302223ms","start":"2026-03-29T01:51:23.600158Z","end":"2026-03-29T01:51:23.71946Z","steps":["trace[914845768] 'agreement among raft nodes before linearized reading' (duration: 119.026692ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:23.936052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.876348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:23.93654Z","caller":"traceutil/trace.go:171","msg":"trace[1249744062] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"203.947773ms","start":"2026-03-29T01:51:23.732562Z","end":"2026-03-29T01:51:23.93651Z","steps":["trace[1249744062] 'process raft request' (duration: 101.481482ms)","trace[1249744062] 'compare' (duration: 101.766913ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:23.936763Z","caller":"traceutil/trace.go:171","msg":"trace[105887393] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"203.88086ms","start":"2026-03-29T01:51:23.732836Z","end":"2026-03-29T01:51:23.936717Z","steps":["trace[105887393] 'process raft request' (duration: 203.62714ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:23.93784Z","caller":"traceutil/trace.go:171","msg":"trace[1321803452] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"197.916136ms","start":"2026-03-29T01:51:23.739897Z","end":"2026-03-29T01:51:23.937813Z","steps":["trace[1321803452] 'process raft request' (duration: 197.667756ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:23.938024Z","caller":"traceutil/trace.go:171","msg":"trace[386045562] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"204.929941ms","start":"2026-03-29T01:51:23.732955Z","end":"2026-03-29T01:51:23.937885Z","steps":["trace[386045562] 'process raft request' (duration: 203.607189ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:23.940557Z","caller":"traceutil/trace.go:171","msg":"trace[145548683] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:376; }","duration":"189.554037ms","start":"2026-03-29T01:51:23.750984Z","end":"2026-03-29T01:51:23.940538Z","steps":["trace[145548683] 'read index received' (duration: 83.072608ms)","trace[145548683] 'applied index is now lower than readState.Index' (duration: 106.479889ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:23.940786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.927984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-29T01:51:23.941062Z","caller":"traceutil/trace.go:171","msg":"trace[1393839925] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:365; }","duration":"140.212546ms","start":"2026-03-29T01:51:23.800802Z","end":"2026-03-29T01:51:23.941014Z","steps":["trace[1393839925] 'agreement among raft nodes before linearized reading' (duration: 139.908834ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:23.940847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.811747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-29T01:51:23.94131Z","caller":"traceutil/trace.go:171","msg":"trace[1829459215] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:365; }","duration":"190.337207ms","start":"2026-03-29T01:51:23.750923Z","end":"2026-03-29T01:51:23.941261Z","steps":["trace[1829459215] 'agreement among raft nodes before linearized reading' (duration: 189.687022ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:24.338547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.921866ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:24.338715Z","caller":"traceutil/trace.go:171","msg":"trace[145062487] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"318.144186ms","start":"2026-03-29T01:51:24.020536Z","end":"2026-03-29T01:51:24.33868Z","steps":["trace[145062487] 'process raft request' (duration: 176.006171ms)","trace[145062487] 'compare' (duration: 141.727799ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:24.338858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.020522Z","time spent":"318.274052ms","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:51:24.339313Z","caller":"traceutil/trace.go:171","msg":"trace[1093291443] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:389; }","duration":"314.116896ms","start":"2026-03-29T01:51:24.025171Z","end":"2026-03-29T01:51:24.339287Z","steps":["trace[1093291443] 'read index received' (duration: 171.383478ms)","trace[1093291443] 'applied index is now lower than readState.Index' (duration: 142.730538ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:24.339572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.70805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-29T01:51:24.339603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.435628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-29T01:51:24.339642Z","caller":"traceutil/trace.go:171","msg":"trace[423202820] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:375; }","duration":"317.805763ms","start":"2026-03-29T01:51:24.021822Z","end":"2026-03-29T01:51:24.339628Z","steps":["trace[423202820] 'agreement among raft nodes before linearized reading' (duration: 317.526782ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:24.339645Z","caller":"traceutil/trace.go:171","msg":"trace[86792103] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:375; }","duration":"319.49555ms","start":"2026-03-29T01:51:24.020138Z","end":"2026-03-29T01:51:24.339634Z","steps":["trace[86792103] 'agreement among raft nodes before linearized reading' (duration: 319.23377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:24.339686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.021813Z","time spent":"317.862366ms","remote":"127.0.0.1:60504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-03-29T01:51:24.339717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.020129Z","time spent":"319.577474ms","remote":"127.0.0.1:60320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.122\" "} {"level":"info","ts":"2026-03-29T01:51:24.41329Z","caller":"traceutil/trace.go:171","msg":"trace[970774192] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"381.280129ms","start":"2026-03-29T01:51:24.03199Z","end":"2026-03-29T01:51:24.41327Z","steps":["trace[970774192] 'process raft request' (duration: 381.237817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:24.413453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.03198Z","time spent":"381.398523ms","remote":"127.0.0.1:60632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2264,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:51:24.41387Z","caller":"traceutil/trace.go:171","msg":"trace[1788341950] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"387.644202ms","start":"2026-03-29T01:51:24.026213Z","end":"2026-03-29T01:51:24.413858Z","steps":["trace[1788341950] 'process raft request' (duration: 386.639152ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:24.41395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.026194Z","time spent":"387.719694ms","remote":"127.0.0.1:60632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:51:24.414326Z","caller":"traceutil/trace.go:171","msg":"trace[192083060] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"385.268417ms","start":"2026-03-29T01:51:24.029048Z","end":"2026-03-29T01:51:24.414317Z","steps":["trace[192083060] 'process raft request' (duration: 383.949485ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:24.414383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.029033Z","time spent":"385.32501ms","remote":"127.0.0.1:60504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":164,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:51:24.414624Z","caller":"traceutil/trace.go:171","msg":"trace[1810919105] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"384.448335ms","start":"2026-03-29T01:51:24.030163Z","end":"2026-03-29T01:51:24.414612Z","steps":["trace[1810919105] 'process raft request' (duration: 382.882143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:24.414704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.030152Z","time spent":"384.506817ms","remote":"127.0.0.1:60722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:51:24.41491Z","caller":"traceutil/trace.go:171","msg":"trace[1518481510] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"384.487306ms","start":"2026-03-29T01:51:24.03041Z","end":"2026-03-29T01:51:24.414897Z","steps":["trace[1518481510] 'process raft request' (duration: 382.722636ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:24.414981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.030394Z","time spent":"384.558809ms","remote":"127.0.0.1:60366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:51:24.415216Z","caller":"traceutil/trace.go:171","msg":"trace[1141250543] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"384.796529ms","start":"2026-03-29T01:51:24.03041Z","end":"2026-03-29T01:51:24.415207Z","steps":["trace[1141250543] 'process raft request' (duration: 382.765968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:24.415281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.030396Z","time spent":"384.85363ms","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:51:26.625574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243177994s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-03-29T01:51:26.625816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.341344Z","time spent":"2.284463967s","remote":"127.0.0.1:60320","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-29T01:51:26.626112Z","caller":"traceutil/trace.go:171","msg":"trace[1869084685] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"2.283443336s","start":"2026-03-29T01:51:24.342621Z","end":"2026-03-29T01:51:26.626064Z","steps":["trace[1869084685] 'process raft request' (duration: 2.283067261s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:26.626256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.342602Z","time spent":"2.283578552s","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3424,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:51:26.626333Z","caller":"traceutil/trace.go:171","msg":"trace[1024657783] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"2.283351772s","start":"2026-03-29T01:51:24.342959Z","end":"2026-03-29T01:51:26.62631Z","steps":["trace[1024657783] 'process raft request' (duration: 2.282875693s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:26.626341Z","caller":"traceutil/trace.go:171","msg":"trace[1447723426] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:396; }","duration":"2.283392764s","start":"2026-03-29T01:51:24.342933Z","end":"2026-03-29T01:51:26.626326Z","steps":["trace[1447723426] 'read index received' (duration: 73.084947ms)","trace[1447723426] 'applied index is now lower than readState.Index' (duration: 2.210306897s)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:26.626454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.342821Z","time spent":"2.283554241s","remote":"127.0.0.1:60366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":669,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:51:26.626538Z","caller":"traceutil/trace.go:171","msg":"trace[385681236] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"2.282765709s","start":"2026-03-29T01:51:24.343761Z","end":"2026-03-29T01:51:26.626527Z","steps":["trace[385681236] 'process raft request' (duration: 2.282435286s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:26.62662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.343751Z","time spent":"2.282830072s","remote":"127.0.0.1:60742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2824,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:51:26.626646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.283701417s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-29T01:51:26.626703Z","caller":"traceutil/trace.go:171","msg":"trace[1714759441] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:384; }","duration":"2.2837784s","start":"2026-03-29T01:51:24.342909Z","end":"2026-03-29T01:51:26.626688Z","steps":["trace[1714759441] 'agreement among raft nodes before linearized reading' (duration: 2.283673405s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:26.626747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.342901Z","time spent":"2.283837273s","remote":"127.0.0.1:60504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-03-29T01:51:27.12725Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478506894731870,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:51:27.160879Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.26821283s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-29T01:51:27.465421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.162606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:27.465562Z","caller":"traceutil/trace.go:171","msg":"trace[2089523277] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"3.023109093s","start":"2026-03-29T01:51:24.442432Z","end":"2026-03-29T01:51:27.465541Z","steps":["trace[2089523277] 'process raft request' (duration: 2.718727297s)","trace[2089523277] 'compare' (duration: 303.944808ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:27.465625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.44241Z","time spent":"3.023186376s","remote":"127.0.0.1:60632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:51:27.467409Z","caller":"traceutil/trace.go:171","msg":"trace[199024747] linearizableReadLoop","detail":"{readStateIndex:404; appliedIndex:400; }","duration":"841.007341ms","start":"2026-03-29T01:51:26.62638Z","end":"2026-03-29T01:51:27.467387Z","steps":["trace[199024747] 'read index received' (duration: 534.788ms)","trace[199024747] 'applied index is now lower than readState.Index' (duration: 306.21829ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:27.467456Z","caller":"traceutil/trace.go:171","msg":"trace[454630016] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"832.342959ms","start":"2026-03-29T01:51:26.635098Z","end":"2026-03-29T01:51:27.467441Z","steps":["trace[454630016] 'process raft request' (duration: 832.238114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:27.467534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:26.635086Z","time spent":"832.426312ms","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:51:27.467635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.029903256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-03-29T01:51:27.467707Z","caller":"traceutil/trace.go:171","msg":"trace[765398769] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"840.212898ms","start":"2026-03-29T01:51:26.627483Z","end":"2026-03-29T01:51:27.467696Z","steps":["trace[765398769] 'process raft request' (duration: 839.696517ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:27.46778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:26.627452Z","time spent":"840.290761ms","remote":"127.0.0.1:60320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:51:27.467827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.345309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2839"} {"level":"info","ts":"2026-03-29T01:51:27.467869Z","caller":"traceutil/trace.go:171","msg":"trace[1829772088] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:388; }","duration":"833.419182ms","start":"2026-03-29T01:51:26.634439Z","end":"2026-03-29T01:51:27.467858Z","steps":["trace[1829772088] 'agreement among raft nodes before linearized reading' (duration: 833.344599ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:27.467852Z","caller":"traceutil/trace.go:171","msg":"trace[712901024] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"836.236416ms","start":"2026-03-29T01:51:26.631582Z","end":"2026-03-29T01:51:27.467819Z","steps":["trace[712901024] 'process raft request' (duration: 835.695114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:27.467909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:26.634422Z","time spent":"833.478615ms","remote":"127.0.0.1:60742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2863,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-03-29T01:51:27.468001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:26.631567Z","time spent":"836.354161ms","remote":"127.0.0.1:60366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:51:27.468048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.442128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-03-29T01:51:27.468076Z","caller":"traceutil/trace.go:171","msg":"trace[1753989695] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:388; }","duration":"689.503281ms","start":"2026-03-29T01:51:26.778563Z","end":"2026-03-29T01:51:27.468067Z","steps":["trace[1753989695] 'agreement among raft nodes before linearized reading' (duration: 689.440729ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:27.46811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:26.778543Z","time spent":"689.554993ms","remote":"127.0.0.1:60504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":209,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-03-29T01:51:27.467693Z","caller":"traceutil/trace.go:171","msg":"trace[1259647115] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:388; }","duration":"3.03001113s","start":"2026-03-29T01:51:24.437664Z","end":"2026-03-29T01:51:27.467675Z","steps":["trace[1259647115] 'agreement among raft nodes before linearized reading' (duration: 3.029855674s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:27.468197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.437637Z","time spent":"3.030548202s","remote":"127.0.0.1:60722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3730,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-03-29T01:51:27.468304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.729573709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-29T01:51:27.468336Z","caller":"traceutil/trace.go:171","msg":"trace[688262758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:388; }","duration":"2.729644271s","start":"2026-03-29T01:51:24.738683Z","end":"2026-03-29T01:51:27.468327Z","steps":["trace[688262758] 'agreement among raft nodes before linearized reading' (duration: 2.729585139s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:27.468331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.99377698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-29T01:51:27.468357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.738663Z","time spent":"2.729688443s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-29T01:51:27.46834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.697823935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-29T01:51:27.468389Z","caller":"traceutil/trace.go:171","msg":"trace[1000439098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:388; }","duration":"2.993876573s","start":"2026-03-29T01:51:24.474497Z","end":"2026-03-29T01:51:27.468373Z","steps":["trace[1000439098] 'agreement among raft nodes before linearized reading' (duration: 2.993788289s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:27.468414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.993884783s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-29T01:51:27.468421Z","caller":"traceutil/trace.go:171","msg":"trace[1447803293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:388; }","duration":"2.697932459s","start":"2026-03-29T01:51:24.770477Z","end":"2026-03-29T01:51:27.46841Z","steps":["trace[1447803293] 'agreement among raft nodes before linearized reading' (duration: 2.697801384s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:27.468425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.47448Z","time spent":"2.993935236s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-29T01:51:27.468461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.770465Z","time spent":"2.697986242s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-29T01:51:27.468458Z","caller":"traceutil/trace.go:171","msg":"trace[2133973858] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:388; }","duration":"2.993956017s","start":"2026-03-29T01:51:24.474489Z","end":"2026-03-29T01:51:27.468445Z","steps":["trace[2133973858] 'agreement among raft nodes before linearized reading' (duration: 2.993898015s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:27.468501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:24.474469Z","time spent":"2.99402543s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-29T01:51:27.46853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.508683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"info","ts":"2026-03-29T01:51:27.468641Z","caller":"traceutil/trace.go:171","msg":"trace[1937489401] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:388; }","duration":"980.677989ms","start":"2026-03-29T01:51:26.487943Z","end":"2026-03-29T01:51:27.468621Z","steps":["trace[1937489401] 'agreement among raft nodes before linearized reading' (duration: 980.435409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:27.468698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:26.487922Z","time spent":"980.763603ms","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4350,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-03-29T01:51:27.627393Z","caller":"traceutil/trace.go:171","msg":"trace[1799010854] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"150.119528ms","start":"2026-03-29T01:51:27.477218Z","end":"2026-03-29T01:51:27.627338Z","steps":["trace[1799010854] 'process raft request' (duration: 125.805959ms)","trace[1799010854] 'compare' (duration: 24.09254ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:27.627713Z","caller":"traceutil/trace.go:171","msg":"trace[1680499030] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"146.392154ms","start":"2026-03-29T01:51:27.4813Z","end":"2026-03-29T01:51:27.627692Z","steps":["trace[1680499030] 'process raft request' (duration: 145.968297ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:27.627787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.253237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-29T01:51:27.627894Z","caller":"traceutil/trace.go:171","msg":"trace[383371689] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:398; }","duration":"153.422463ms","start":"2026-03-29T01:51:27.474454Z","end":"2026-03-29T01:51:27.627876Z","steps":["trace[383371689] 'agreement among raft nodes before linearized reading' (duration: 153.228805ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:27.627896Z","caller":"traceutil/trace.go:171","msg":"trace[406796410] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"143.081629ms","start":"2026-03-29T01:51:27.484762Z","end":"2026-03-29T01:51:27.627844Z","steps":["trace[406796410] 'process raft request' (duration: 142.721654ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:27.772761Z","caller":"traceutil/trace.go:171","msg":"trace[523310728] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"103.564985ms","start":"2026-03-29T01:51:27.669171Z","end":"2026-03-29T01:51:27.772736Z","steps":["trace[523310728] 'process raft request' (duration: 94.358087ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:27.772727Z","caller":"traceutil/trace.go:171","msg":"trace[1238024209] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"102.162057ms","start":"2026-03-29T01:51:27.670542Z","end":"2026-03-29T01:51:27.772704Z","steps":["trace[1238024209] 'process raft request' (duration: 102.103035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:28.013509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.749886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:28.013707Z","caller":"traceutil/trace.go:171","msg":"trace[834344210] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"114.974168ms","start":"2026-03-29T01:51:27.898715Z","end":"2026-03-29T01:51:28.013689Z","steps":["trace[834344210] 'process raft request' (duration: 114.909136ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:28.013753Z","caller":"traceutil/trace.go:171","msg":"trace[1493827260] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"177.397502ms","start":"2026-03-29T01:51:27.836313Z","end":"2026-03-29T01:51:28.013711Z","steps":["trace[1493827260] 'process raft request' (duration: 54.996539ms)","trace[1493827260] 'compare' (duration: 121.59844ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:28.239366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.416289ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:28.239487Z","caller":"traceutil/trace.go:171","msg":"trace[578778806] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"215.240134ms","start":"2026-03-29T01:51:28.024227Z","end":"2026-03-29T01:51:28.239467Z","steps":["trace[578778806] 'process raft request' (duration: 93.639097ms)","trace[578778806] 'compare' (duration: 121.278084ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:29.163596Z","caller":"traceutil/trace.go:171","msg":"trace[749682544] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"143.984746ms","start":"2026-03-29T01:51:29.01958Z","end":"2026-03-29T01:51:29.163565Z","steps":["trace[749682544] 'process raft request' (duration: 143.816529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:29.71353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.639234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:29.713825Z","caller":"traceutil/trace.go:171","msg":"trace[2113637153] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"146.72151ms","start":"2026-03-29T01:51:29.567045Z","end":"2026-03-29T01:51:29.713767Z","steps":["trace[2113637153] 'process raft request' (duration: 37.757182ms)","trace[2113637153] 'compare' (duration: 108.505429ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:29.713912Z","caller":"traceutil/trace.go:171","msg":"trace[1792746094] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"144.232016ms","start":"2026-03-29T01:51:29.569659Z","end":"2026-03-29T01:51:29.713891Z","steps":["trace[1792746094] 'process raft request' (duration: 143.985586ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:29.714003Z","caller":"traceutil/trace.go:171","msg":"trace[1578404819] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"143.279256ms","start":"2026-03-29T01:51:29.570696Z","end":"2026-03-29T01:51:29.713976Z","steps":["trace[1578404819] 'process raft request' (duration: 143.013975ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:29.800132Z","caller":"traceutil/trace.go:171","msg":"trace[781675473] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:433; }","duration":"143.420132ms","start":"2026-03-29T01:51:29.656681Z","end":"2026-03-29T01:51:29.800102Z","steps":["trace[781675473] 'read index received' (duration: 50.760168ms)","trace[781675473] 'applied index is now lower than readState.Index' (duration: 92.658424ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:29.800276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.575249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:51:29.800311Z","caller":"traceutil/trace.go:171","msg":"trace[1825772268] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:419; }","duration":"143.677763ms","start":"2026-03-29T01:51:29.656621Z","end":"2026-03-29T01:51:29.800298Z","steps":["trace[1825772268] 'agreement among raft nodes before linearized reading' (duration: 143.586309ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:30.160798Z","caller":"traceutil/trace.go:171","msg":"trace[1628106377] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"139.258774ms","start":"2026-03-29T01:51:30.021521Z","end":"2026-03-29T01:51:30.16078Z","steps":["trace[1628106377] 'process raft request' (duration: 139.137739ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:30.934317Z","caller":"traceutil/trace.go:171","msg":"trace[802881818] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"136.544889ms","start":"2026-03-29T01:51:30.79774Z","end":"2026-03-29T01:51:30.934285Z","steps":["trace[802881818] 'process raft request' (duration: 49.989085ms)","trace[802881818] 'compare' (duration: 86.44285ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:31.267785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.192496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:31.267908Z","caller":"traceutil/trace.go:171","msg":"trace[1201203436] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"246.987053ms","start":"2026-03-29T01:51:31.020902Z","end":"2026-03-29T01:51:31.267889Z","steps":["trace[1201203436] 'process raft request' (duration: 89.597028ms)","trace[1201203436] 'compare' (duration: 156.915954ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:31.736536Z","caller":"traceutil/trace.go:171","msg":"trace[673378805] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"137.681313ms","start":"2026-03-29T01:51:31.598818Z","end":"2026-03-29T01:51:31.736499Z","steps":["trace[673378805] 'process raft request' (duration: 137.483734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:32.444973Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478506894731945,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:51:32.945957Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478506894731945,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:51:32.992984Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.161064728s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-29T01:51:33.805582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.005908834s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:33.805962Z","caller":"traceutil/trace.go:171","msg":"trace[242771401] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"2.065334844s","start":"2026-03-29T01:51:31.740587Z","end":"2026-03-29T01:51:33.805922Z","steps":["trace[242771401] 'process raft request' (duration: 59.005661ms)","trace[242771401] 'compare' (duration: 2.005813841s)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:33.806201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:31.740574Z","time spent":"2.065518761s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:51:33.806794Z","caller":"traceutil/trace.go:171","msg":"trace[581046261] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"2.065755632s","start":"2026-03-29T01:51:31.741018Z","end":"2026-03-29T01:51:33.806774Z","steps":["trace[581046261] 'process raft request' (duration: 2.064676855s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:33.806893Z","caller":"traceutil/trace.go:171","msg":"trace[1844497651] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"2.065364325s","start":"2026-03-29T01:51:31.741511Z","end":"2026-03-29T01:51:33.806876Z","steps":["trace[1844497651] 'process raft request' (duration: 2.065224639s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:33.80707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:31.741Z","time spent":"2.06593232s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:51:33.807248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:31.741503Z","time spent":"2.065514363s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:51:33.828788Z","caller":"traceutil/trace.go:171","msg":"trace[551292802] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:443; }","duration":"1.884522777s","start":"2026-03-29T01:51:31.944239Z","end":"2026-03-29T01:51:33.828762Z","steps":["trace[551292802] 'read index received' (duration: 1.048915906s)","trace[551292802] 'applied index is now lower than readState.Index' (duration: 835.606031ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:33.828883Z","caller":"traceutil/trace.go:171","msg":"trace[1060942331] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"1.338767047s","start":"2026-03-29T01:51:32.490109Z","end":"2026-03-29T01:51:33.828876Z","steps":["trace[1060942331] 'process raft request' (duration: 1.338533416s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:33.829089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.88482441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:51:33.829172Z","caller":"traceutil/trace.go:171","msg":"trace[1978523395] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"1.884947455s","start":"2026-03-29T01:51:31.944209Z","end":"2026-03-29T01:51:33.829157Z","steps":["trace[1978523395] 'agreement among raft nodes before linearized reading' (duration: 1.884805009s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:33.829228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:31.944193Z","time spent":"1.885015238s","remote":"127.0.0.1:60274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:51:33.829776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:32.490092Z","time spent":"1.339639225s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:51:35.972381Z","caller":"traceutil/trace.go:171","msg":"trace[1082797255] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"157.482201ms","start":"2026-03-29T01:51:35.814873Z","end":"2026-03-29T01:51:35.972355Z","steps":["trace[1082797255] 'process raft request' (duration: 157.347905ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:36.001641Z","caller":"traceutil/trace.go:171","msg":"trace[1343660551] linearizableReadLoop","detail":"{readStateIndex:452; appliedIndex:451; }","duration":"177.424254ms","start":"2026-03-29T01:51:35.824197Z","end":"2026-03-29T01:51:36.001622Z","steps":["trace[1343660551] 'read index received' (duration: 148.779496ms)","trace[1343660551] 'applied index is now lower than readState.Index' (duration: 28.643938ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:36.001742Z","caller":"traceutil/trace.go:171","msg":"trace[909132892] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"185.559874ms","start":"2026-03-29T01:51:35.816172Z","end":"2026-03-29T01:51:36.001732Z","steps":["trace[909132892] 'process raft request' (duration: 185.346994ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:36.001879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.668084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-29T01:51:36.001899Z","caller":"traceutil/trace.go:171","msg":"trace[1582022380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:433; }","duration":"177.735588ms","start":"2026-03-29T01:51:35.824157Z","end":"2026-03-29T01:51:36.001893Z","steps":["trace[1582022380] 'agreement among raft nodes before linearized reading' (duration: 177.684566ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:36.0019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.777285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-29T01:51:36.002Z","caller":"traceutil/trace.go:171","msg":"trace[1465077080] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:433; }","duration":"148.878251ms","start":"2026-03-29T01:51:35.853104Z","end":"2026-03-29T01:51:36.001983Z","steps":["trace[1465077080] 'agreement among raft nodes before linearized reading' (duration: 148.735805ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:36.171893Z","caller":"traceutil/trace.go:171","msg":"trace[939723043] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"164.111455ms","start":"2026-03-29T01:51:36.007757Z","end":"2026-03-29T01:51:36.171869Z","steps":["trace[939723043] 'process raft request' (duration: 163.972909ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:51:38.406011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.320668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:51:38.406096Z","caller":"traceutil/trace.go:171","msg":"trace[1944470671] linearizableReadLoop","detail":"{readStateIndex:457; appliedIndex:456; }","duration":"229.638766ms","start":"2026-03-29T01:51:38.176444Z","end":"2026-03-29T01:51:38.406083Z","steps":["trace[1944470671] 'read index received' (duration: 50.012µs)","trace[1944470671] 'applied index is now lower than readState.Index' (duration: 229.587974ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:38.406157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.7219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-29T01:51:38.406179Z","caller":"traceutil/trace.go:171","msg":"trace[2002599169] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:438; }","duration":"229.781842ms","start":"2026-03-29T01:51:38.176391Z","end":"2026-03-29T01:51:38.406173Z","steps":["trace[2002599169] 'agreement among raft nodes before linearized reading' (duration: 229.72526ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:38.406292Z","caller":"traceutil/trace.go:171","msg":"trace[1126893454] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"301.761918ms","start":"2026-03-29T01:51:38.104508Z","end":"2026-03-29T01:51:38.40627Z","steps":["trace[1126893454] 'process raft request' (duration: 41.104964ms)","trace[1126893454] 'compare' (duration: 260.224044ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:38.406338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:51:38.104492Z","time spent":"301.822521ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:51:40.10385Z","caller":"traceutil/trace.go:171","msg":"trace[203272340] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"101.479124ms","start":"2026-03-29T01:51:40.002344Z","end":"2026-03-29T01:51:40.103823Z","steps":["trace[203272340] 'process raft request' (duration: 101.297735ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:40.235439Z","caller":"traceutil/trace.go:171","msg":"trace[1181278510] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"128.523399ms","start":"2026-03-29T01:51:40.106884Z","end":"2026-03-29T01:51:40.235407Z","steps":["trace[1181278510] 'process raft request' (duration: 109.898682ms)","trace[1181278510] 'compare' (duration: 18.508662ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:51:42.603785Z","caller":"traceutil/trace.go:171","msg":"trace[343598707] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"131.81042ms","start":"2026-03-29T01:51:42.471949Z","end":"2026-03-29T01:51:42.60376Z","steps":["trace[343598707] 'process raft request' (duration: 131.672574ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:44.749101Z","caller":"traceutil/trace.go:171","msg":"trace[981389560] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:473; }","duration":"141.912026ms","start":"2026-03-29T01:51:44.60717Z","end":"2026-03-29T01:51:44.749082Z","steps":["trace[981389560] 'read index received' (duration: 141.747419ms)","trace[981389560] 'applied index is now lower than readState.Index' (duration: 163.917µs)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:51:44.749213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.040722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-29T01:51:44.749236Z","caller":"traceutil/trace.go:171","msg":"trace[1099572026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:453; }","duration":"142.117856ms","start":"2026-03-29T01:51:44.60711Z","end":"2026-03-29T01:51:44.749228Z","steps":["trace[1099572026] 'agreement among raft nodes before linearized reading' (duration: 142.057243ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:44.749223Z","caller":"traceutil/trace.go:171","msg":"trace[86267709] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"173.881122ms","start":"2026-03-29T01:51:44.575292Z","end":"2026-03-29T01:51:44.749173Z","steps":["trace[86267709] 'process raft request' (duration: 173.682802ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:51:46.978962Z","caller":"traceutil/trace.go:171","msg":"trace[489133154] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"101.717466ms","start":"2026-03-29T01:51:46.877175Z","end":"2026-03-29T01:51:46.978892Z","steps":["trace[489133154] 'process raft request' (duration: 101.469934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:01.682493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.746656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:52:01.682607Z","caller":"traceutil/trace.go:171","msg":"trace[1141309940] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"808.470504ms","start":"2026-03-29T01:52:00.874119Z","end":"2026-03-29T01:52:01.68259Z","steps":["trace[1141309940] 'process raft request' (duration: 91.550349ms)","trace[1141309940] 'compare' (duration: 716.592347ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:52:01.68287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:00.874101Z","time spent":"808.537817ms","remote":"127.0.0.1:60750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4177,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:52:01.685072Z","caller":"traceutil/trace.go:171","msg":"trace[240764509] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"809.384431ms","start":"2026-03-29T01:52:00.875635Z","end":"2026-03-29T01:52:01.68502Z","steps":["trace[240764509] 'process raft request' (duration: 809.17426ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:01.685254Z","caller":"traceutil/trace.go:171","msg":"trace[1315329510] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"809.401543ms","start":"2026-03-29T01:52:00.875822Z","end":"2026-03-29T01:52:01.685224Z","steps":["trace[1315329510] 'process raft request' (duration: 809.110778ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:01.685344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:00.875615Z","time spent":"809.583813ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:52:01.685393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:00.875803Z","time spent":"809.52851ms","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15015,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:52:01.68653Z","caller":"traceutil/trace.go:171","msg":"trace[427142927] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:555; }","duration":"756.04132ms","start":"2026-03-29T01:52:00.930455Z","end":"2026-03-29T01:52:01.686497Z","steps":["trace[427142927] 'read index received' (duration: 35.309266ms)","trace[427142927] 'applied index is now lower than readState.Index' (duration: 720.727373ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:52:01.687152Z","caller":"traceutil/trace.go:171","msg":"trace[2000278982] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"743.730849ms","start":"2026-03-29T01:52:00.943393Z","end":"2026-03-29T01:52:01.687123Z","steps":["trace[2000278982] 'process raft request' (duration: 741.847251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:01.68777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.310676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-29T01:52:01.687767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:00.943375Z","time spent":"743.849395ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317275,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:52:01.687812Z","caller":"traceutil/trace.go:171","msg":"trace[749261067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"757.38091ms","start":"2026-03-29T01:52:00.930419Z","end":"2026-03-29T01:52:01.6878Z","steps":["trace[749261067] 'agreement among raft nodes before linearized reading' (duration: 757.292295ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:01.687831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:00.930401Z","time spent":"757.426212ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-29T01:52:01.702641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.092909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:01.702843Z","caller":"traceutil/trace.go:171","msg":"trace[1740194010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"763.358734ms","start":"2026-03-29T01:52:00.939459Z","end":"2026-03-29T01:52:01.702818Z","steps":["trace[1740194010] 'agreement among raft nodes before linearized reading' (duration: 763.048437ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:01.702922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:00.939443Z","time spent":"763.46837ms","remote":"127.0.0.1:60274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-29T01:52:01.702746Z","caller":"traceutil/trace.go:171","msg":"trace[1043446399] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"156.564757ms","start":"2026-03-29T01:52:01.546159Z","end":"2026-03-29T01:52:01.702724Z","steps":["trace[1043446399] 'process raft request' (duration: 156.178547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:02.08265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.652603ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:52:02.082968Z","caller":"traceutil/trace.go:171","msg":"trace[599425180] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"329.373052ms","start":"2026-03-29T01:52:01.753513Z","end":"2026-03-29T01:52:02.082886Z","steps":["trace[599425180] 'process raft request' (duration: 54.338358ms)","trace[599425180] 'compare' (duration: 274.496876ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:52:02.082998Z","caller":"traceutil/trace.go:171","msg":"trace[621910529] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:567; }","duration":"328.347848ms","start":"2026-03-29T01:52:01.754623Z","end":"2026-03-29T01:52:02.082971Z","steps":["trace[621910529] 'read index received' (duration: 19.581492ms)","trace[621910529] 'applied index is now lower than readState.Index' (duration: 308.764366ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:52:02.083138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:01.753478Z","time spent":"329.559732ms","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3622,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:52:02.083171Z","caller":"traceutil/trace.go:171","msg":"trace[549493903] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"328.052653ms","start":"2026-03-29T01:52:01.755072Z","end":"2026-03-29T01:52:02.083125Z","steps":["trace[549493903] 'process raft request' (duration: 327.80581ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:02.083327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.692536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/cilium-operator\" ","response":"range_response_count:1 size:4335"} {"level":"info","ts":"2026-03-29T01:52:02.083382Z","caller":"traceutil/trace.go:171","msg":"trace[2134112547] range","detail":"{range_begin:/registry/deployments/kube-system/cilium-operator; range_end:; response_count:1; response_revision:546; }","duration":"328.74615ms","start":"2026-03-29T01:52:01.754617Z","end":"2026-03-29T01:52:02.083363Z","steps":["trace[2134112547] 'agreement among raft nodes before linearized reading' (duration: 328.545069ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:02.083392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:01.755061Z","time spent":"328.216731ms","remote":"127.0.0.1:60750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4294,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:52:02.083435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:01.754565Z","time spent":"328.854995ms","remote":"127.0.0.1:60722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":4359,"request content":"key:\"/registry/deployments/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-03-29T01:52:02.083632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.135261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-03-29T01:52:02.083684Z","caller":"traceutil/trace.go:171","msg":"trace[995467802] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:546; }","duration":"325.206545ms","start":"2026-03-29T01:52:01.758462Z","end":"2026-03-29T01:52:02.083669Z","steps":["trace[995467802] 'agreement among raft nodes before linearized reading' (duration: 325.064387ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:02.083322Z","caller":"traceutil/trace.go:171","msg":"trace[220420598] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"329.762613ms","start":"2026-03-29T01:52:01.753538Z","end":"2026-03-29T01:52:02.083301Z","steps":["trace[220420598] 'process raft request' (duration: 329.251896ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:02.083724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:01.758454Z","time spent":"325.258377ms","remote":"127.0.0.1:60742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":14487,"request content":"key:\"/registry/daemonsets/kube-system/cilium\" "} {"level":"warn","ts":"2026-03-29T01:52:02.083797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:01.753527Z","time spent":"330.221147ms","remote":"127.0.0.1:60366","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":736,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:52:02.08391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.102746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-03-29T01:52:02.083969Z","caller":"traceutil/trace.go:171","msg":"trace[1013428826] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:546; }","duration":"171.197831ms","start":"2026-03-29T01:52:01.912754Z","end":"2026-03-29T01:52:02.083951Z","steps":["trace[1013428826] 'agreement among raft nodes before linearized reading' (duration: 171.067664ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:02.083963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.722774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:52:02.084337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.734272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7bqt6\" ","response":"range_response_count:1 size:15076"} {"level":"info","ts":"2026-03-29T01:52:02.084426Z","caller":"traceutil/trace.go:171","msg":"trace[1037573931] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7bqt6; range_end:; response_count:1; response_revision:546; }","duration":"325.846238ms","start":"2026-03-29T01:52:01.758558Z","end":"2026-03-29T01:52:02.084404Z","steps":["trace[1037573931] 'agreement among raft nodes before linearized reading' (duration: 325.546633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:02.084485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:01.758555Z","time spent":"325.921992ms","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":15100,"request content":"key:\"/registry/pods/kube-system/cilium-7bqt6\" "} {"level":"warn","ts":"2026-03-29T01:52:02.084502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.238235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-03-29T01:52:02.084559Z","caller":"traceutil/trace.go:171","msg":"trace[2107023547] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:546; }","duration":"315.311758ms","start":"2026-03-29T01:52:01.769231Z","end":"2026-03-29T01:52:02.084542Z","steps":["trace[2107023547] 'agreement among raft nodes before linearized reading' (duration: 314.673174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:02.084603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:01.769222Z","time spent":"315.368531ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329980,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-03-29T01:52:02.085022Z","caller":"traceutil/trace.go:171","msg":"trace[120309871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"142.812738ms","start":"2026-03-29T01:52:01.941188Z","end":"2026-03-29T01:52:02.084001Z","steps":["trace[120309871] 'agreement among raft nodes before linearized reading' (duration: 142.720523ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:02.30314Z","caller":"traceutil/trace.go:171","msg":"trace[373986190] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"205.880434ms","start":"2026-03-29T01:52:02.09724Z","end":"2026-03-29T01:52:02.30312Z","steps":["trace[373986190] 'process raft request' (duration: 205.788669ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:02.303662Z","caller":"traceutil/trace.go:171","msg":"trace[1809167721] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"208.112332ms","start":"2026-03-29T01:52:02.09554Z","end":"2026-03-29T01:52:02.303652Z","steps":["trace[1809167721] 'process raft request' (duration: 180.738195ms)","trace[1809167721] 'compare' (duration: 26.608406ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:52:02.303725Z","caller":"traceutil/trace.go:171","msg":"trace[2021448278] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:571; }","duration":"198.689816ms","start":"2026-03-29T01:52:02.105018Z","end":"2026-03-29T01:52:02.303708Z","steps":["trace[2021448278] 'read index received' (duration: 12.987221ms)","trace[2021448278] 'applied index is now lower than readState.Index' (duration: 185.701615ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:52:02.303801Z","caller":"traceutil/trace.go:171","msg":"trace[686924412] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"197.326055ms","start":"2026-03-29T01:52:02.106432Z","end":"2026-03-29T01:52:02.303759Z","steps":["trace[686924412] 'process raft request' (duration: 196.64821ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:02.303914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.878077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/cilium\" ","response":"range_response_count:1 size:14463"} {"level":"info","ts":"2026-03-29T01:52:02.303939Z","caller":"traceutil/trace.go:171","msg":"trace[356375357] range","detail":"{range_begin:/registry/daemonsets/kube-system/cilium; range_end:; response_count:1; response_revision:550; }","duration":"198.920009ms","start":"2026-03-29T01:52:02.105011Z","end":"2026-03-29T01:52:02.303931Z","steps":["trace[356375357] 'agreement among raft nodes before linearized reading' (duration: 198.782322ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:02.438025Z","caller":"traceutil/trace.go:171","msg":"trace[1285448170] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"109.999272ms","start":"2026-03-29T01:52:02.327976Z","end":"2026-03-29T01:52:02.437976Z","steps":["trace[1285448170] 'process raft request' (duration: 100.603369ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:04.034454Z","caller":"traceutil/trace.go:171","msg":"trace[381325801] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:581; }","duration":"282.763864ms","start":"2026-03-29T01:52:03.751667Z","end":"2026-03-29T01:52:04.034431Z","steps":["trace[381325801] 'read index received' (duration: 282.68338ms)","trace[381325801] 'applied index is now lower than readState.Index' (duration: 79.484µs)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:52:04.034825Z","caller":"traceutil/trace.go:171","msg":"trace[1016437243] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"338.966627ms","start":"2026-03-29T01:52:03.695847Z","end":"2026-03-29T01:52:04.034813Z","steps":["trace[1016437243] 'process raft request' (duration: 338.4566ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:04.034951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:03.695832Z","time spent":"339.02904ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:52:04.035193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.524744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-29T01:52:04.035262Z","caller":"traceutil/trace.go:171","msg":"trace[1309815881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:557; }","duration":"283.620889ms","start":"2026-03-29T01:52:03.751622Z","end":"2026-03-29T01:52:04.035243Z","steps":["trace[1309815881] 'agreement among raft nodes before linearized reading' (duration: 283.473942ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:04.309545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.609447ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-03-29T01:52:04.310124Z","caller":"traceutil/trace.go:171","msg":"trace[1366892996] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:582; }","duration":"266.992299ms","start":"2026-03-29T01:52:04.043105Z","end":"2026-03-29T01:52:04.310097Z","steps":["trace[1366892996] 'read index received' (duration: 64.817252ms)","trace[1366892996] 'applied index is now lower than readState.Index' (duration: 202.173577ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:52:04.310151Z","caller":"traceutil/trace.go:171","msg":"trace[748812801] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"271.625414ms","start":"2026-03-29T01:52:04.038512Z","end":"2026-03-29T01:52:04.310138Z","steps":["trace[748812801] 'process raft request' (duration: 271.257665ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:04.310442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.265963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:04.311214Z","caller":"traceutil/trace.go:171","msg":"trace[2145554108] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:558; }","duration":"268.111718ms","start":"2026-03-29T01:52:04.043079Z","end":"2026-03-29T01:52:04.31119Z","steps":["trace[2145554108] 'agreement among raft nodes before linearized reading' (duration: 267.102434ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:04.985291Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478506894732234,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-29T01:52:05.162816Z","caller":"traceutil/trace.go:171","msg":"trace[747635442] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"851.688633ms","start":"2026-03-29T01:52:04.311096Z","end":"2026-03-29T01:52:05.162784Z","steps":["trace[747635442] 'process raft request' (duration: 849.349058ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:05.162989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:04.311079Z","time spent":"851.82725ms","remote":"127.0.0.1:60320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:52:05.170217Z","caller":"traceutil/trace.go:171","msg":"trace[1855939136] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"687.104509ms","start":"2026-03-29T01:52:04.483077Z","end":"2026-03-29T01:52:05.170182Z","steps":["trace[1855939136] 'process raft request' (duration: 686.907529ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:05.170279Z","caller":"traceutil/trace.go:171","msg":"trace[1885942415] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"678.837811ms","start":"2026-03-29T01:52:04.491434Z","end":"2026-03-29T01:52:05.170272Z","steps":["trace[1885942415] 'process raft request' (duration: 678.644481ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:05.170352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:04.483052Z","time spent":"687.240796ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:52:05.170356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:04.49142Z","time spent":"678.886764ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:52:05.170367Z","caller":"traceutil/trace.go:171","msg":"trace[384206985] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"670.575514ms","start":"2026-03-29T01:52:04.499754Z","end":"2026-03-29T01:52:05.170329Z","steps":["trace[384206985] 'process raft request' (duration: 670.412966ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:05.17023Z","caller":"traceutil/trace.go:171","msg":"trace[1109144800] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"677.978276ms","start":"2026-03-29T01:52:04.492231Z","end":"2026-03-29T01:52:05.170209Z","steps":["trace[1109144800] 'process raft request' (duration: 677.882201ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:05.170422Z","caller":"traceutil/trace.go:171","msg":"trace[136579457] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"665.893756ms","start":"2026-03-29T01:52:04.504499Z","end":"2026-03-29T01:52:05.170393Z","steps":["trace[136579457] 'process raft request' (duration: 665.727747ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:05.170483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:04.492213Z","time spent":"678.24897ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:52:05.170532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:04.499744Z","time spent":"670.710121ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:52:05.170541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:04.504492Z","time spent":"665.989291ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:52:05.170561Z","caller":"traceutil/trace.go:171","msg":"trace[1838574439] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"664.305322ms","start":"2026-03-29T01:52:04.506239Z","end":"2026-03-29T01:52:05.170544Z","steps":["trace[1838574439] 'process raft request' (duration: 664.023467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:05.170638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:04.506231Z","time spent":"664.371085ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:52:05.170664Z","caller":"traceutil/trace.go:171","msg":"trace[749042498] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"652.580821ms","start":"2026-03-29T01:52:04.518074Z","end":"2026-03-29T01:52:05.170655Z","steps":["trace[749042498] 'process raft request' (duration: 652.246513ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:05.170501Z","caller":"traceutil/trace.go:171","msg":"trace[104191641] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"666.319388ms","start":"2026-03-29T01:52:04.504089Z","end":"2026-03-29T01:52:05.170409Z","steps":["trace[104191641] 'process raft request' (duration: 666.106817ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:05.170794Z","caller":"traceutil/trace.go:171","msg":"trace[1634860438] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"652.135247ms","start":"2026-03-29T01:52:04.518649Z","end":"2026-03-29T01:52:05.170784Z","steps":["trace[1634860438] 'process raft request' (duration: 651.917256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:05.17086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:04.518634Z","time spent":"652.200211ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:52:05.170266Z","caller":"traceutil/trace.go:171","msg":"trace[99938532] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"674.481921ms","start":"2026-03-29T01:52:04.49576Z","end":"2026-03-29T01:52:05.170242Z","steps":["trace[99938532] 'process raft request' (duration: 674.388476ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:05.170944Z","caller":"traceutil/trace.go:171","msg":"trace[1930377226] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"642.023842ms","start":"2026-03-29T01:52:04.528904Z","end":"2026-03-29T01:52:05.170928Z","steps":["trace[1930377226] 'process raft request' (duration: 641.768278ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:05.170949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:04.495749Z","time spent":"675.165997ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:52:05.171003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:04.528876Z","time spent":"642.099086ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:52:05.17077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:04.518048Z","time spent":"652.634634ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:52:05.171019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:04.50406Z","time spent":"666.810405ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:52:05.177931Z","caller":"traceutil/trace.go:171","msg":"trace[1645577564] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:584; }","duration":"692.977241ms","start":"2026-03-29T01:52:04.484936Z","end":"2026-03-29T01:52:05.177913Z","steps":["trace[1645577564] 'read index received' (duration: 675.531533ms)","trace[1645577564] 'applied index is now lower than readState.Index' (duration: 17.444808ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:52:05.178014Z","caller":"traceutil/trace.go:171","msg":"trace[1380289966] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"639.454437ms","start":"2026-03-29T01:52:04.538553Z","end":"2026-03-29T01:52:05.178008Z","steps":["trace[1380289966] 'process raft request' (duration: 632.327859ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:05.178103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:04.538531Z","time spent":"639.537812ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:52:05.178237Z","caller":"traceutil/trace.go:171","msg":"trace[2106992630] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"508.854121ms","start":"2026-03-29T01:52:04.669375Z","end":"2026-03-29T01:52:05.178229Z","steps":["trace[2106992630] 'process raft request' (duration: 508.46225ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:05.178271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:04.669344Z","time spent":"508.913515ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:52:05.178473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.51538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-29T01:52:05.178503Z","caller":"traceutil/trace.go:171","msg":"trace[1038990529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"693.589394ms","start":"2026-03-29T01:52:04.484907Z","end":"2026-03-29T01:52:05.178496Z","steps":["trace[1038990529] 'agreement among raft nodes before linearized reading' (duration: 693.434506ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:05.178523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:04.484893Z","time spent":"693.625737ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-29T01:52:05.17863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.409432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-29T01:52:05.178653Z","caller":"traceutil/trace.go:171","msg":"trace[2035757283] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:572; }","duration":"574.466885ms","start":"2026-03-29T01:52:04.604181Z","end":"2026-03-29T01:52:05.178648Z","steps":["trace[2035757283] 'agreement among raft nodes before linearized reading' (duration: 574.425353ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:05.178669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:04.604161Z","time spent":"574.504207ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-29T01:52:05.178928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.910001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:05.178953Z","caller":"traceutil/trace.go:171","msg":"trace[47204350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:572; }","duration":"237.936132ms","start":"2026-03-29T01:52:04.94101Z","end":"2026-03-29T01:52:05.178947Z","steps":["trace[47204350] 'agreement among raft nodes before linearized reading' (duration: 237.90036ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:06.361832Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.057902614s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-29T01:52:06.502623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.382041ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:06.502726Z","caller":"traceutil/trace.go:171","msg":"trace[205232072] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:573; }","duration":"748.506147ms","start":"2026-03-29T01:52:05.754206Z","end":"2026-03-29T01:52:06.502712Z","steps":["trace[205232072] 'range keys from in-memory index tree' (duration: 748.36412ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:06.503361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.220776129s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:52:06.503561Z","caller":"traceutil/trace.go:171","msg":"trace[1573844637] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"1.317692585s","start":"2026-03-29T01:52:05.185828Z","end":"2026-03-29T01:52:06.503521Z","steps":["trace[1573844637] 'process raft request' (duration: 96.695074ms)","trace[1573844637] 'compare' (duration: 1.220317694s)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:52:06.5037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:05.185805Z","time spent":"1.317817251s","remote":"127.0.0.1:60792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:52:06.505198Z","caller":"traceutil/trace.go:171","msg":"trace[677253466] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"1.312884729s","start":"2026-03-29T01:52:05.192161Z","end":"2026-03-29T01:52:06.505045Z","steps":["trace[677253466] 'process raft request' (duration: 1.311684305s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:06.505337Z","caller":"traceutil/trace.go:171","msg":"trace[754956546] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"1.31346739s","start":"2026-03-29T01:52:05.191834Z","end":"2026-03-29T01:52:06.505301Z","steps":["trace[754956546] 'process raft request' (duration: 1.31197831s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:06.505417Z","caller":"traceutil/trace.go:171","msg":"trace[902270197] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.313178704s","start":"2026-03-29T01:52:05.192213Z","end":"2026-03-29T01:52:06.505392Z","steps":["trace[902270197] 'process raft request' (duration: 1.311674344s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:06.505489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:05.192208Z","time spent":"1.313246398s","remote":"127.0.0.1:60792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:52:06.505538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:05.191817Z","time spent":"1.313624898s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:52:06.506379Z","caller":"traceutil/trace.go:171","msg":"trace[1204548771] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"1.31873293s","start":"2026-03-29T01:52:05.187624Z","end":"2026-03-29T01:52:06.506357Z","steps":["trace[1204548771] 'process raft request' (duration: 1.316147282s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:06.506452Z","caller":"traceutil/trace.go:171","msg":"trace[1563197044] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"1.320451141s","start":"2026-03-29T01:52:05.185982Z","end":"2026-03-29T01:52:06.506434Z","steps":["trace[1563197044] 'process raft request' (duration: 1.317474392s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:06.506463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:05.187604Z","time spent":"1.318818755s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:52:06.506517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:05.185975Z","time spent":"1.320501564s","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:52:06.5054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:05.192153Z","time spent":"1.31310056s","remote":"127.0.0.1:60792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:52:06.507143Z","caller":"traceutil/trace.go:171","msg":"trace[1338259671] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.314877915s","start":"2026-03-29T01:52:05.192248Z","end":"2026-03-29T01:52:06.507126Z","steps":["trace[1338259671] 'process raft request' (duration: 1.311663413s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:06.507194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:05.192244Z","time spent":"1.314920137s","remote":"127.0.0.1:60792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:52:06.507419Z","caller":"traceutil/trace.go:171","msg":"trace[1119655976] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"1.314704016s","start":"2026-03-29T01:52:05.192682Z","end":"2026-03-29T01:52:06.507386Z","steps":["trace[1119655976] 'process raft request' (duration: 1.312109447s)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:06.509022Z","caller":"traceutil/trace.go:171","msg":"trace[1566045503] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.316274799s","start":"2026-03-29T01:52:05.19273Z","end":"2026-03-29T01:52:06.509005Z","steps":["trace[1566045503] 'process raft request' (duration: 1.313186434s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:06.509174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:05.192726Z","time spent":"1.316390646s","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:52:06.509715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:05.192675Z","time spent":"1.31479322s","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:52:06.510486Z","caller":"traceutil/trace.go:171","msg":"trace[1720889541] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.312913752s","start":"2026-03-29T01:52:05.197554Z","end":"2026-03-29T01:52:06.510468Z","steps":["trace[1720889541] 'process raft request' (duration: 1.311353448s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:06.510565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:05.197545Z","time spent":"1.312982795s","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:52:06.510733Z","caller":"traceutil/trace.go:171","msg":"trace[1074587756] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.313054809s","start":"2026-03-29T01:52:05.197649Z","end":"2026-03-29T01:52:06.510704Z","steps":["trace[1074587756] 'process raft request' (duration: 1.311550649s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:06.510904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:05.197643Z","time spent":"1.313151934s","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:52:06.511137Z","caller":"traceutil/trace.go:171","msg":"trace[388420188] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.312300269s","start":"2026-03-29T01:52:05.198822Z","end":"2026-03-29T01:52:06.511123Z","steps":["trace[388420188] 'process raft request' (duration: 1.310473841s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:06.511214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:05.198814Z","time spent":"1.312357462s","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:52:06.511424Z","caller":"traceutil/trace.go:171","msg":"trace[702431806] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:598; }","duration":"1.319007276s","start":"2026-03-29T01:52:05.192387Z","end":"2026-03-29T01:52:06.511394Z","steps":["trace[702431806] 'read index received' (duration: 13.133809ms)","trace[702431806] 'applied index is now lower than readState.Index' (duration: 1.304338935s)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:52:06.511551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.319161114s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-29T01:52:06.511591Z","caller":"traceutil/trace.go:171","msg":"trace[1838232223] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:586; }","duration":"1.319216457s","start":"2026-03-29T01:52:05.192364Z","end":"2026-03-29T01:52:06.511581Z","steps":["trace[1838232223] 'agreement among raft nodes before linearized reading' (duration: 1.317501435s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:06.511618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:05.192357Z","time spent":"1.319253799s","remote":"127.0.0.1:60576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-03-29T01:52:06.511616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.837975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:06.511653Z","caller":"traceutil/trace.go:171","msg":"trace[1118621174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"569.906329ms","start":"2026-03-29T01:52:05.941736Z","end":"2026-03-29T01:52:06.511643Z","steps":["trace[1118621174] 'agreement among raft nodes before linearized reading' (duration: 569.848086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:06.511688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:05.941717Z","time spent":"569.964092ms","remote":"127.0.0.1:60274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-29T01:52:06.511733Z","caller":"traceutil/trace.go:171","msg":"trace[1325887026] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.275018696s","start":"2026-03-29T01:52:05.234925Z","end":"2026-03-29T01:52:06.509944Z","steps":["trace[1325887026] 'process raft request' (duration: 1.2749057s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:06.511778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:05.234886Z","time spent":"1.276873465s","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:52:06.511861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.218676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-29T01:52:06.511898Z","caller":"traceutil/trace.go:171","msg":"trace[16057778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"197.289769ms","start":"2026-03-29T01:52:06.314599Z","end":"2026-03-29T01:52:06.511889Z","steps":["trace[16057778] 'agreement among raft nodes before linearized reading' (duration: 197.228476ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:06.511929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.314015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-29T01:52:06.511949Z","caller":"traceutil/trace.go:171","msg":"trace[1081994123] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"473.377548ms","start":"2026-03-29T01:52:06.038566Z","end":"2026-03-29T01:52:06.511943Z","steps":["trace[1081994123] 'agreement among raft nodes before linearized reading' (duration: 473.340146ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:06.511967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:06.038542Z","time spent":"473.42113ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-29T01:52:07.082568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.590446ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-03-29T01:52:07.082787Z","caller":"traceutil/trace.go:171","msg":"trace[629895589] transaction","detail":"{read_only:false; number_of_response:0; response_revision:586; }","duration":"571.313218ms","start":"2026-03-29T01:52:06.511449Z","end":"2026-03-29T01:52:07.082762Z","steps":["trace[629895589] 'process raft request' (duration: 448.464078ms)","trace[629895589] 'compare' (duration: 122.537374ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:52:07.082934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:06.511433Z","time spent":"571.464967ms","remote":"127.0.0.1:60792","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:52:07.1821Z","caller":"traceutil/trace.go:171","msg":"trace[1811482047] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"664.183602ms","start":"2026-03-29T01:52:06.517902Z","end":"2026-03-29T01:52:07.182085Z","steps":["trace[1811482047] 'process raft request' (duration: 664.129999ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:07.182158Z","caller":"traceutil/trace.go:171","msg":"trace[392887618] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"667.173302ms","start":"2026-03-29T01:52:06.514954Z","end":"2026-03-29T01:52:07.182127Z","steps":["trace[392887618] 'process raft request' (duration: 666.973311ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:07.18221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:06.517886Z","time spent":"664.266916ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:52:07.182393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:06.514934Z","time spent":"667.337641ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:52:07.182434Z","caller":"traceutil/trace.go:171","msg":"trace[1123435325] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"661.617535ms","start":"2026-03-29T01:52:06.520793Z","end":"2026-03-29T01:52:07.182411Z","steps":["trace[1123435325] 'process raft request' (duration: 661.267146ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:07.182553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:06.520781Z","time spent":"661.710001ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:52:07.182767Z","caller":"traceutil/trace.go:171","msg":"trace[776765993] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"660.703656ms","start":"2026-03-29T01:52:06.522054Z","end":"2026-03-29T01:52:07.182758Z","steps":["trace[776765993] 'process raft request' (duration: 660.357027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:07.182809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:06.522025Z","time spent":"660.7652ms","remote":"127.0.0.1:60792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":986,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-29T01:52:07.18284Z","caller":"traceutil/trace.go:171","msg":"trace[1513669399] transaction","detail":"{read_only:false; number_of_response:1; response_revision:589; }","duration":"661.658068ms","start":"2026-03-29T01:52:06.521154Z","end":"2026-03-29T01:52:07.182812Z","steps":["trace[1513669399] 'process raft request' (duration: 661.13951ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:07.182994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:06.521143Z","time spent":"661.771213ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":96,"response count":0,"response size":30445,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:52:07.183017Z","caller":"traceutil/trace.go:171","msg":"trace[1984704728] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"658.632635ms","start":"2026-03-29T01:52:06.524376Z","end":"2026-03-29T01:52:07.183009Z","steps":["trace[1984704728] 'process raft request' (duration: 658.088496ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:07.183064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:06.524364Z","time spent":"658.676758ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:52:07.183119Z","caller":"traceutil/trace.go:171","msg":"trace[1316737359] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"653.078919ms","start":"2026-03-29T01:52:06.530022Z","end":"2026-03-29T01:52:07.183101Z","steps":["trace[1316737359] 'process raft request' (duration: 652.514479ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:07.183255Z","caller":"traceutil/trace.go:171","msg":"trace[1489304257] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"635.949534ms","start":"2026-03-29T01:52:06.547295Z","end":"2026-03-29T01:52:07.183245Z","steps":["trace[1489304257] 'process raft request' (duration: 635.331731ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:07.183296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:06.547275Z","time spent":"636.000137ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:52:07.183241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:06.530011Z","time spent":"653.175874ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:52:07.184713Z","caller":"traceutil/trace.go:171","msg":"trace[790601242] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"606.906343ms","start":"2026-03-29T01:52:06.577792Z","end":"2026-03-29T01:52:07.184698Z","steps":["trace[790601242] 'process raft request' (duration: 604.957638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:07.185113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:06.577763Z","time spent":"607.23886ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:52:07.184855Z","caller":"traceutil/trace.go:171","msg":"trace[655015765] linearizableReadLoop","detail":"{readStateIndex:614; appliedIndex:612; }","duration":"669.613403ms","start":"2026-03-29T01:52:06.515227Z","end":"2026-03-29T01:52:07.18484Z","steps":["trace[655015765] 'read index received' (duration: 444.704837ms)","trace[655015765] 'applied index is now lower than readState.Index' (duration: 224.907656ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:52:07.184934Z","caller":"traceutil/trace.go:171","msg":"trace[647598181] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"585.51676ms","start":"2026-03-29T01:52:06.599403Z","end":"2026-03-29T01:52:07.18492Z","steps":["trace[647598181] 'process raft request' (duration: 585.237915ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:07.185681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:06.599378Z","time spent":"586.278391ms","remote":"127.0.0.1:60332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:52:07.333757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.477265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1010"} {"level":"info","ts":"2026-03-29T01:52:07.333849Z","caller":"traceutil/trace.go:171","msg":"trace[940327391] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1beta1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:595; }","duration":"249.629563ms","start":"2026-03-29T01:52:07.084197Z","end":"2026-03-29T01:52:07.333826Z","steps":["trace[940327391] 'agreement among raft nodes before linearized reading' (duration: 101.652464ms)","trace[940327391] 'range keys from in-memory index tree' (duration: 147.823231ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:52:07.33384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.581346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:07.333916Z","caller":"traceutil/trace.go:171","msg":"trace[2088059856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"818.694812ms","start":"2026-03-29T01:52:06.515199Z","end":"2026-03-29T01:52:07.333894Z","steps":["trace[2088059856] 'agreement among raft nodes before linearized reading' (duration: 669.694687ms)","trace[2088059856] 'range keys from in-memory index tree' (duration: 148.867278ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:52:07.333963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:06.515185Z","time spent":"818.767026ms","remote":"127.0.0.1:60280","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-29T01:52:07.335647Z","caller":"traceutil/trace.go:171","msg":"trace[448363093] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"133.322123ms","start":"2026-03-29T01:52:07.202306Z","end":"2026-03-29T01:52:07.335628Z","steps":["trace[448363093] 'process raft request' (duration: 133.100451ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:07.429418Z","caller":"traceutil/trace.go:171","msg":"trace[140268129] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"198.852849ms","start":"2026-03-29T01:52:07.230539Z","end":"2026-03-29T01:52:07.429392Z","steps":["trace[140268129] 'process raft request' (duration: 198.586995ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:08.031326Z","caller":"traceutil/trace.go:171","msg":"trace[225185028] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"168.754659ms","start":"2026-03-29T01:52:07.86254Z","end":"2026-03-29T01:52:08.031295Z","steps":["trace[225185028] 'process raft request' (duration: 121.270636ms)","trace[225185028] 'compare' (duration: 47.308683ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:52:08.034837Z","caller":"traceutil/trace.go:171","msg":"trace[757199641] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"168.630243ms","start":"2026-03-29T01:52:07.866188Z","end":"2026-03-29T01:52:08.034819Z","steps":["trace[757199641] 'process raft request' (duration: 167.560595ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:08.035506Z","caller":"traceutil/trace.go:171","msg":"trace[1929477752] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"145.619968ms","start":"2026-03-29T01:52:07.889854Z","end":"2026-03-29T01:52:08.035474Z","steps":["trace[1929477752] 'process raft request' (duration: 144.905859ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:12.825182Z","caller":"traceutil/trace.go:171","msg":"trace[202267821] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"289.330576ms","start":"2026-03-29T01:52:12.535824Z","end":"2026-03-29T01:52:12.825155Z","steps":["trace[202267821] 'process raft request' (duration: 289.150486ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:12.825976Z","caller":"traceutil/trace.go:171","msg":"trace[1127353746] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:677; }","duration":"187.680533ms","start":"2026-03-29T01:52:12.638277Z","end":"2026-03-29T01:52:12.825958Z","steps":["trace[1127353746] 'read index received' (duration: 186.703709ms)","trace[1127353746] 'applied index is now lower than readState.Index' (duration: 975.234µs)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:52:12.826298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.981559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:12.826393Z","caller":"traceutil/trace.go:171","msg":"trace[685336364] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:0; response_revision:650; }","duration":"188.147338ms","start":"2026-03-29T01:52:12.638221Z","end":"2026-03-29T01:52:12.826368Z","steps":["trace[685336364] 'agreement among raft nodes before linearized reading' (duration: 188.00033ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:12.826104Z","caller":"traceutil/trace.go:171","msg":"trace[311632233] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"288.008934ms","start":"2026-03-29T01:52:12.538084Z","end":"2026-03-29T01:52:12.826093Z","steps":["trace[311632233] 'process raft request' (duration: 287.651365ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:14.313319Z","caller":"traceutil/trace.go:171","msg":"trace[1819029132] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"371.155505ms","start":"2026-03-29T01:52:13.942141Z","end":"2026-03-29T01:52:14.313297Z","steps":["trace[1819029132] 'read index received' (duration: 369.011507ms)","trace[1819029132] 'applied index is now lower than readState.Index' (duration: 2.143028ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:52:14.313434Z","caller":"traceutil/trace.go:171","msg":"trace[956982253] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"500.322161ms","start":"2026-03-29T01:52:13.81308Z","end":"2026-03-29T01:52:14.313402Z","steps":["trace[956982253] 'process raft request' (duration: 498.769026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:14.313571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.616926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-03-29T01:52:14.313546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.341325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:14.313614Z","caller":"traceutil/trace.go:171","msg":"trace[983101251] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:660; }","duration":"290.713831ms","start":"2026-03-29T01:52:14.02289Z","end":"2026-03-29T01:52:14.313603Z","steps":["trace[983101251] 'agreement among raft nodes before linearized reading' (duration: 290.549282ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:14.313645Z","caller":"traceutil/trace.go:171","msg":"trace[1924626163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"371.519365ms","start":"2026-03-29T01:52:13.942102Z","end":"2026-03-29T01:52:14.313622Z","steps":["trace[1924626163] 'agreement among raft nodes before linearized reading' (duration: 371.309314ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:14.313691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:13.942082Z","time spent":"371.5999ms","remote":"127.0.0.1:60274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:52:14.313901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:13.813044Z","time spent":"500.738374ms","remote":"127.0.0.1:60392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:52:14.313912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.736824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7bqt6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-29T01:52:14.314016Z","caller":"traceutil/trace.go:171","msg":"trace[919363359] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7bqt6; range_end:; response_count:1; response_revision:660; }","duration":"164.871873ms","start":"2026-03-29T01:52:14.149116Z","end":"2026-03-29T01:52:14.313987Z","steps":["trace[919363359] 'agreement among raft nodes before linearized reading' (duration: 164.594617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:15.356484Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.017928835s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T01:52:15.35691Z","caller":"traceutil/trace.go:171","msg":"trace[1066983944] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.02889691s","start":"2026-03-29T01:52:14.327994Z","end":"2026-03-29T01:52:15.356891Z","steps":["trace[1066983944] 'process raft request' (duration: 1.028732961s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:15.357007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:14.327976Z","time spent":"1.028968744s","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:52:15.386937Z","caller":"traceutil/trace.go:171","msg":"trace[354484839] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:691; }","duration":"327.614122ms","start":"2026-03-29T01:52:15.059303Z","end":"2026-03-29T01:52:15.386917Z","steps":["trace[354484839] 'read index received' (duration: 297.41299ms)","trace[354484839] 'applied index is now lower than readState.Index' (duration: 30.200312ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:52:15.387034Z","caller":"traceutil/trace.go:171","msg":"trace[1616164980] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"994.830538ms","start":"2026-03-29T01:52:14.392194Z","end":"2026-03-29T01:52:15.387024Z","steps":["trace[1616164980] 'process raft request' (duration: 994.677069ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:15.387075Z","caller":"traceutil/trace.go:171","msg":"trace[681772156] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.05888973s","start":"2026-03-29T01:52:14.328152Z","end":"2026-03-29T01:52:15.387042Z","steps":["trace[681772156] 'process raft request' (duration: 1.058618705s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:15.387129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:14.392171Z","time spent":"994.892841ms","remote":"127.0.0.1:60504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-29T01:52:15.387245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:14.328132Z","time spent":"1.059023807s","remote":"127.0.0.1:60320","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:52:15.387314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.289583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:15.387357Z","caller":"traceutil/trace.go:171","msg":"trace[1606721333] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"325.343936ms","start":"2026-03-29T01:52:15.062002Z","end":"2026-03-29T01:52:15.387346Z","steps":["trace[1606721333] 'agreement among raft nodes before linearized reading' (duration: 325.24262ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:15.387391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:15.061998Z","time spent":"325.385749ms","remote":"127.0.0.1:60274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:52:15.387688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.737983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-29T01:52:15.387785Z","caller":"traceutil/trace.go:171","msg":"trace[488374073] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:663; }","duration":"326.851379ms","start":"2026-03-29T01:52:15.060919Z","end":"2026-03-29T01:52:15.38777Z","steps":["trace[488374073] 'agreement among raft nodes before linearized reading' (duration: 326.452857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:15.388301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:15.060914Z","time spent":"327.370188ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-29T01:52:15.387982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.66032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-29T01:52:15.388519Z","caller":"traceutil/trace.go:171","msg":"trace[1471414663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:663; }","duration":"329.228811ms","start":"2026-03-29T01:52:15.059273Z","end":"2026-03-29T01:52:15.388502Z","steps":["trace[1471414663] 'agreement among raft nodes before linearized reading' (duration: 327.938159ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:15.388573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:15.059259Z","time spent":"329.297365ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-29T01:52:15.388071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.499215ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-29T01:52:15.388847Z","caller":"traceutil/trace.go:171","msg":"trace[1497629902] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:663; }","duration":"328.282188ms","start":"2026-03-29T01:52:15.060552Z","end":"2026-03-29T01:52:15.388834Z","steps":["trace[1497629902] 'agreement among raft nodes before linearized reading' (duration: 326.554213ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:15.388894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:15.060547Z","time spent":"328.331572ms","remote":"127.0.0.1:60656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-03-29T01:52:15.567199Z","caller":"traceutil/trace.go:171","msg":"trace[108469112] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"142.158487ms","start":"2026-03-29T01:52:15.425003Z","end":"2026-03-29T01:52:15.567161Z","steps":["trace[108469112] 'read index received' (duration: 47.428975ms)","trace[108469112] 'applied index is now lower than readState.Index' (duration: 94.727942ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:52:15.567234Z","caller":"traceutil/trace.go:171","msg":"trace[1994759307] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"142.493336ms","start":"2026-03-29T01:52:15.424712Z","end":"2026-03-29T01:52:15.567205Z","steps":["trace[1994759307] 'process raft request' (duration: 47.70618ms)","trace[1994759307] 'compare' (duration: 94.470728ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:52:15.567409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.376509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-03-29T01:52:15.567446Z","caller":"traceutil/trace.go:171","msg":"trace[1185950437] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:664; }","duration":"142.453533ms","start":"2026-03-29T01:52:15.42498Z","end":"2026-03-29T01:52:15.567434Z","steps":["trace[1185950437] 'agreement among raft nodes before linearized reading' (duration: 142.338387ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:15.602853Z","caller":"traceutil/trace.go:171","msg":"trace[1598091348] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"173.202485ms","start":"2026-03-29T01:52:15.429621Z","end":"2026-03-29T01:52:15.602823Z","steps":["trace[1598091348] 'process raft request' (duration: 173.159242ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:15.602933Z","caller":"traceutil/trace.go:171","msg":"trace[1852948021] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"177.157264ms","start":"2026-03-29T01:52:15.42577Z","end":"2026-03-29T01:52:15.602927Z","steps":["trace[1852948021] 'process raft request' (duration: 176.972163ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:15.60287Z","caller":"traceutil/trace.go:171","msg":"trace[599984338] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"177.438919ms","start":"2026-03-29T01:52:15.425404Z","end":"2026-03-29T01:52:15.602843Z","steps":["trace[599984338] 'process raft request' (duration: 177.205576ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:17.342751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.588708765s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:17.342909Z","caller":"traceutil/trace.go:171","msg":"trace[2010406842] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:667; }","duration":"1.588885555s","start":"2026-03-29T01:52:15.753988Z","end":"2026-03-29T01:52:17.342874Z","steps":["trace[2010406842] 'range keys from in-memory index tree' (duration: 1.588685224s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:17.34327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580459129s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-29T01:52:17.343331Z","caller":"traceutil/trace.go:171","msg":"trace[640587929] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"1.776033514s","start":"2026-03-29T01:52:15.567286Z","end":"2026-03-29T01:52:17.343319Z","steps":["trace[640587929] 'read index received' (duration: 35.77755ms)","trace[640587929] 'applied index is now lower than readState.Index' (duration: 1.740254844s)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:52:17.343411Z","caller":"traceutil/trace.go:171","msg":"trace[1088588099] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"1.840859101s","start":"2026-03-29T01:52:15.50254Z","end":"2026-03-29T01:52:17.343399Z","steps":["trace[1088588099] 'process raft request' (duration: 260.206592ms)","trace[1088588099] 'compare' (duration: 1.579865675s)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:52:17.343445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.904338764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:52:17.343487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:15.502517Z","time spent":"1.840934135s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:52:17.343545Z","caller":"traceutil/trace.go:171","msg":"trace[1706191018] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:668; }","duration":"1.904459582s","start":"2026-03-29T01:52:15.439069Z","end":"2026-03-29T01:52:17.343528Z","steps":["trace[1706191018] 'agreement among raft nodes before linearized reading' (duration: 1.904341855s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:17.343595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:15.439055Z","time spent":"1.904527425s","remote":"127.0.0.1:60618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":0,"response size":29,"request content":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-03-29T01:52:17.343739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.039575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:17.343768Z","caller":"traceutil/trace.go:171","msg":"trace[1765474042] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:668; }","duration":"759.103459ms","start":"2026-03-29T01:52:16.584657Z","end":"2026-03-29T01:52:17.34376Z","steps":["trace[1765474042] 'agreement among raft nodes before linearized reading' (duration: 759.062606ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:17.343788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:16.584638Z","time spent":"759.144941ms","remote":"127.0.0.1:60420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":29,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-03-29T01:52:17.343806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.592803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:17.343845Z","caller":"traceutil/trace.go:171","msg":"trace[988831195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"403.666197ms","start":"2026-03-29T01:52:16.940169Z","end":"2026-03-29T01:52:17.343835Z","steps":["trace[988831195] 'agreement among raft nodes before linearized reading' (duration: 403.613364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:17.343878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:16.94015Z","time spent":"403.72156ms","remote":"127.0.0.1:60274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-29T01:52:18.538432Z","caller":"traceutil/trace.go:171","msg":"trace[1102129525] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"188.574246ms","start":"2026-03-29T01:52:18.349827Z","end":"2026-03-29T01:52:18.538401Z","steps":["trace[1102129525] 'process raft request' (duration: 187.984223ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:20.745145Z","caller":"traceutil/trace.go:171","msg":"trace[989690182] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"117.374598ms","start":"2026-03-29T01:52:20.627747Z","end":"2026-03-29T01:52:20.745121Z","steps":["trace[989690182] 'process raft request' (duration: 116.596774ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:22.506928Z","caller":"traceutil/trace.go:171","msg":"trace[1502036014] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"111.852838ms","start":"2026-03-29T01:52:22.395045Z","end":"2026-03-29T01:52:22.506898Z","steps":["trace[1502036014] 'process raft request' (duration: 111.632226ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:23.393232Z","caller":"traceutil/trace.go:171","msg":"trace[355624981] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"128.566557ms","start":"2026-03-29T01:52:23.264627Z","end":"2026-03-29T01:52:23.393194Z","steps":["trace[355624981] 'read index received' (duration: 125.641781ms)","trace[355624981] 'applied index is now lower than readState.Index' (duration: 2.923326ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:52:23.393351Z","caller":"traceutil/trace.go:171","msg":"trace[1012396910] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"135.111379ms","start":"2026-03-29T01:52:23.258186Z","end":"2026-03-29T01:52:23.393297Z","steps":["trace[1012396910] 'process raft request' (duration: 132.186853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:23.393545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.871285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:23.393608Z","caller":"traceutil/trace.go:171","msg":"trace[895033886] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:766; }","duration":"128.978921ms","start":"2026-03-29T01:52:23.264604Z","end":"2026-03-29T01:52:23.393583Z","steps":["trace[895033886] 'agreement among raft nodes before linearized reading' (duration: 128.853143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:23.393738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.016584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:23.393826Z","caller":"traceutil/trace.go:171","msg":"trace[1132298112] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:766; }","duration":"129.152271ms","start":"2026-03-29T01:52:23.264648Z","end":"2026-03-29T01:52:23.3938Z","steps":["trace[1132298112] 'agreement among raft nodes before linearized reading' (duration: 128.96038ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:23.394113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.863277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:23.394237Z","caller":"traceutil/trace.go:171","msg":"trace[874831232] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:766; }","duration":"106.007185ms","start":"2026-03-29T01:52:23.288217Z","end":"2026-03-29T01:52:23.394224Z","steps":["trace[874831232] 'agreement among raft nodes before linearized reading' (duration: 105.864857ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:23.394241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.284029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:23.394377Z","caller":"traceutil/trace.go:171","msg":"trace[1054037389] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:766; }","duration":"129.421386ms","start":"2026-03-29T01:52:23.264938Z","end":"2026-03-29T01:52:23.394359Z","steps":["trace[1054037389] 'agreement among raft nodes before linearized reading' (duration: 129.243676ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:23.394206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.563477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:23.394447Z","caller":"traceutil/trace.go:171","msg":"trace[138251771] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:766; }","duration":"106.813871ms","start":"2026-03-29T01:52:23.287616Z","end":"2026-03-29T01:52:23.394429Z","steps":["trace[138251771] 'agreement among raft nodes before linearized reading' (duration: 106.554286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:23.394113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.218097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:23.394525Z","caller":"traceutil/trace.go:171","msg":"trace[1052920347] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:766; }","duration":"106.701245ms","start":"2026-03-29T01:52:23.287816Z","end":"2026-03-29T01:52:23.394517Z","steps":["trace[1052920347] 'agreement among raft nodes before linearized reading' (duration: 106.256329ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:23.833557Z","caller":"traceutil/trace.go:171","msg":"trace[117728230] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"100.503923ms","start":"2026-03-29T01:52:23.733013Z","end":"2026-03-29T01:52:23.833517Z","steps":["trace[117728230] 'process raft request' (duration: 32.482507ms)","trace[117728230] 'compare' (duration: 67.662746ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:52:23.896373Z","caller":"traceutil/trace.go:171","msg":"trace[836318041] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"163.25746ms","start":"2026-03-29T01:52:23.733015Z","end":"2026-03-29T01:52:23.896272Z","steps":["trace[836318041] 'process raft request' (duration: 162.694948ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:23.896985Z","caller":"traceutil/trace.go:171","msg":"trace[583980877] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"163.329774ms","start":"2026-03-29T01:52:23.733644Z","end":"2026-03-29T01:52:23.896974Z","steps":["trace[583980877] 'process raft request' (duration: 162.230221ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:23.897139Z","caller":"traceutil/trace.go:171","msg":"trace[1854036428] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"160.160753ms","start":"2026-03-29T01:52:23.736942Z","end":"2026-03-29T01:52:23.897103Z","steps":["trace[1854036428] 'process raft request' (duration: 159.296394ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:23.897765Z","caller":"traceutil/trace.go:171","msg":"trace[1749266657] linearizableReadLoop","detail":"{readStateIndex:802; appliedIndex:800; }","duration":"164.289708ms","start":"2026-03-29T01:52:23.733451Z","end":"2026-03-29T01:52:23.897741Z","steps":["trace[1749266657] 'read index received' (duration: 32.058953ms)","trace[1749266657] 'applied index is now lower than readState.Index' (duration: 132.228745ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:52:23.897968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.480689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:23.89803Z","caller":"traceutil/trace.go:171","msg":"trace[1568952040] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:772; }","duration":"164.633428ms","start":"2026-03-29T01:52:23.733382Z","end":"2026-03-29T01:52:23.898015Z","steps":["trace[1568952040] 'agreement among raft nodes before linearized reading' (duration: 164.467808ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:23.898107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.463228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:23.898202Z","caller":"traceutil/trace.go:171","msg":"trace[998979309] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:772; }","duration":"164.716543ms","start":"2026-03-29T01:52:23.733468Z","end":"2026-03-29T01:52:23.898185Z","steps":["trace[998979309] 'agreement among raft nodes before linearized reading' (duration: 164.452608ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:24.17434Z","caller":"traceutil/trace.go:171","msg":"trace[152174274] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"230.971488ms","start":"2026-03-29T01:52:23.94335Z","end":"2026-03-29T01:52:24.174322Z","steps":["trace[152174274] 'process raft request' (duration: 230.870982ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:24.174359Z","caller":"traceutil/trace.go:171","msg":"trace[1358408473] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:805; }","duration":"204.191455ms","start":"2026-03-29T01:52:23.970142Z","end":"2026-03-29T01:52:24.174334Z","steps":["trace[1358408473] 'read index received' (duration: 204.178445ms)","trace[1358408473] 'applied index is now lower than readState.Index' (duration: 10.69µs)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:52:24.174672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.478592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:24.174793Z","caller":"traceutil/trace.go:171","msg":"trace[797181918] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:773; }","duration":"204.691824ms","start":"2026-03-29T01:52:23.970091Z","end":"2026-03-29T01:52:24.174783Z","steps":["trace[797181918] 'agreement among raft nodes before linearized reading' (duration: 204.44419ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:24.20223Z","caller":"traceutil/trace.go:171","msg":"trace[127536845] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"107.756183ms","start":"2026-03-29T01:52:24.094449Z","end":"2026-03-29T01:52:24.202205Z","steps":["trace[127536845] 'process raft request' (duration: 106.026035ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:24.202331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.874885ms","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-03-29T01:52:24.20238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.058273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:24.202473Z","caller":"traceutil/trace.go:171","msg":"trace[683145900] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:774; }","duration":"223.185011ms","start":"2026-03-29T01:52:23.979269Z","end":"2026-03-29T01:52:24.202454Z","steps":["trace[683145900] 'agreement among raft nodes before linearized reading' (duration: 222.958708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:24.202496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.518235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-29T01:52:24.202522Z","caller":"traceutil/trace.go:171","msg":"trace[1780829026] range","detail":"{range_begin:/registry/masterleases/199.204.45.122; range_end:; response_count:1; response_revision:774; }","duration":"179.562847ms","start":"2026-03-29T01:52:24.022952Z","end":"2026-03-29T01:52:24.202515Z","steps":["trace[1780829026] 'agreement among raft nodes before linearized reading' (duration: 179.490463ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:24.202705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.592211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:52:24.202714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.427286ms","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":"info","ts":"2026-03-29T01:52:24.202731Z","caller":"traceutil/trace.go:171","msg":"trace[772452511] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:774; }","duration":"185.635523ms","start":"2026-03-29T01:52:24.017091Z","end":"2026-03-29T01:52:24.202726Z","steps":["trace[772452511] 'agreement among raft nodes before linearized reading' (duration: 185.595181ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:24.202748Z","caller":"traceutil/trace.go:171","msg":"trace[3417368] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:774; }","duration":"213.476559ms","start":"2026-03-29T01:52:23.989262Z","end":"2026-03-29T01:52:24.202738Z","steps":["trace[3417368] 'agreement among raft nodes before linearized reading' (duration: 213.422996ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:24.202858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.870477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:24.202886Z","caller":"traceutil/trace.go:171","msg":"trace[557096763] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:774; }","duration":"185.953502ms","start":"2026-03-29T01:52:24.016924Z","end":"2026-03-29T01:52:24.202877Z","steps":["trace[557096763] 'agreement among raft nodes before linearized reading' (duration: 185.912359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:24.202934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.602964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:52:24.202976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.267019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:24.203002Z","caller":"traceutil/trace.go:171","msg":"trace[1464548091] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:774; }","duration":"186.327923ms","start":"2026-03-29T01:52:24.016669Z","end":"2026-03-29T01:52:24.202997Z","steps":["trace[1464548091] 'agreement among raft nodes before linearized reading' (duration: 186.29063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:24.203093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.197913ms","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-03-29T01:52:24.203117Z","caller":"traceutil/trace.go:171","msg":"trace[2083755207] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:774; }","duration":"213.231475ms","start":"2026-03-29T01:52:23.98988Z","end":"2026-03-29T01:52:24.203111Z","steps":["trace[2083755207] 'agreement among raft nodes before linearized reading' (duration: 213.197713ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:24.202972Z","caller":"traceutil/trace.go:171","msg":"trace[2012209978] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:774; }","duration":"223.650917ms","start":"2026-03-29T01:52:23.979309Z","end":"2026-03-29T01:52:24.20296Z","steps":["trace[2012209978] 'agreement among raft nodes before linearized reading' (duration: 223.602385ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:24.202417Z","caller":"traceutil/trace.go:171","msg":"trace[793979940] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:774; }","duration":"212.97921ms","start":"2026-03-29T01:52:23.989413Z","end":"2026-03-29T01:52:24.202392Z","steps":["trace[793979940] 'agreement among raft nodes before linearized reading' (duration: 212.80174ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:52:31.944161Z","caller":"traceutil/trace.go:171","msg":"trace[97899477] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"172.093618ms","start":"2026-03-29T01:52:31.772043Z","end":"2026-03-29T01:52:31.944136Z","steps":["trace[97899477] 'process raft request' (duration: 171.661303ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:35.05738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.616851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:52:35.058878Z","caller":"traceutil/trace.go:171","msg":"trace[920991293] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:848; }","duration":"105.15002ms","start":"2026-03-29T01:52:34.95368Z","end":"2026-03-29T01:52:35.05883Z","steps":["trace[920991293] 'range keys from in-memory index tree' (duration: 103.518885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:35.057728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.098366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5x2m5\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-03-29T01:52:35.059131Z","caller":"traceutil/trace.go:171","msg":"trace[934287880] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5x2m5; range_end:; response_count:1; response_revision:848; }","duration":"769.541571ms","start":"2026-03-29T01:52:34.289549Z","end":"2026-03-29T01:52:35.059091Z","steps":["trace[934287880] 'range keys from in-memory index tree' (duration: 767.786588ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:52:35.059252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:52:34.289521Z","time spent":"769.686519ms","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3989,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-5x2m5\" "} {"level":"info","ts":"2026-03-29T01:53:14.577645Z","caller":"traceutil/trace.go:171","msg":"trace[2086027875] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"230.237781ms","start":"2026-03-29T01:53:14.347385Z","end":"2026-03-29T01:53:14.577623Z","steps":["trace[2086027875] 'process raft request' (duration: 230.111043ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:53:31.284958Z","caller":"traceutil/trace.go:171","msg":"trace[688151451] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"577.794019ms","start":"2026-03-29T01:53:30.707143Z","end":"2026-03-29T01:53:31.284937Z","steps":["trace[688151451] 'process raft request' (duration: 577.674632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:31.285766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:30.707129Z","time spent":"578.550958ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:53:34.505541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478506894733547,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-29T01:53:34.684325Z","caller":"traceutil/trace.go:171","msg":"trace[1763991246] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"980.599965ms","start":"2026-03-29T01:53:33.703639Z","end":"2026-03-29T01:53:34.684239Z","steps":["trace[1763991246] 'process raft request' (duration: 980.310656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:34.684514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:33.703611Z","time spent":"980.823949ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:53:34.687113Z","caller":"traceutil/trace.go:171","msg":"trace[898298392] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1215; }","duration":"682.663575ms","start":"2026-03-29T01:53:34.004415Z","end":"2026-03-29T01:53:34.687079Z","steps":["trace[898298392] 'read index received' (duration: 678.69809ms)","trace[898298392] 'applied index is now lower than readState.Index' (duration: 3.963295ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:53:34.687706Z","caller":"traceutil/trace.go:171","msg":"trace[1864201499] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"983.967261ms","start":"2026-03-29T01:53:33.703711Z","end":"2026-03-29T01:53:34.687678Z","steps":["trace[1864201499] 'process raft request' (duration: 983.048362ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:34.688587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.144041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:53:34.688668Z","caller":"traceutil/trace.go:171","msg":"trace[1615816188] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1171; }","duration":"684.277689ms","start":"2026-03-29T01:53:34.004372Z","end":"2026-03-29T01:53:34.688649Z","steps":["trace[1615816188] 'agreement among raft nodes before linearized reading' (duration: 684.123139ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:34.688703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:34.004355Z","time spent":"684.341863ms","remote":"127.0.0.1:60274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-29T01:53:34.688905Z","caller":"traceutil/trace.go:171","msg":"trace[1304209846] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"797.777894ms","start":"2026-03-29T01:53:33.891117Z","end":"2026-03-29T01:53:34.688895Z","steps":["trace[1304209846] 'process raft request' (duration: 795.894563ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:34.689161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.913455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-29T01:53:34.689169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.039165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-29T01:53:34.689203Z","caller":"traceutil/trace.go:171","msg":"trace[1819279532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1171; }","duration":"193.965638ms","start":"2026-03-29T01:53:34.495228Z","end":"2026-03-29T01:53:34.689194Z","steps":["trace[1819279532] 'agreement among raft nodes before linearized reading' (duration: 193.805368ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:53:34.689225Z","caller":"traceutil/trace.go:171","msg":"trace[1748642258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1171; }","duration":"146.124699ms","start":"2026-03-29T01:53:34.543086Z","end":"2026-03-29T01:53:34.689211Z","steps":["trace[1748642258] 'agreement among raft nodes before linearized reading' (duration: 145.928067ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:53:34.688572Z","caller":"traceutil/trace.go:171","msg":"trace[1988458394] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"982.411451ms","start":"2026-03-29T01:53:33.706125Z","end":"2026-03-29T01:53:34.688536Z","steps":["trace[1988458394] 'process raft request' (duration: 980.83724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:34.689344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:33.706103Z","time spent":"983.213963ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:53:34.689345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.476244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-29T01:53:34.688574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:33.70368Z","time spent":"984.073947ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-29T01:53:34.689422Z","caller":"traceutil/trace.go:171","msg":"trace[913212374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1171; }","duration":"198.120845ms","start":"2026-03-29T01:53:34.49129Z","end":"2026-03-29T01:53:34.68941Z","steps":["trace[913212374] 'agreement among raft nodes before linearized reading' (duration: 197.381678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:34.688997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:33.891089Z","time spent":"797.840708ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:53:37.21003Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478506894733569,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:53:37.711166Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12737478506894733569,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-29T01:53:37.859974Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.151076737s","expected-duration":"1s"} {"level":"info","ts":"2026-03-29T01:53:37.86053Z","caller":"traceutil/trace.go:171","msg":"trace[1422154088] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"1.151655843s","start":"2026-03-29T01:53:36.708843Z","end":"2026-03-29T01:53:37.860499Z","steps":["trace[1422154088] 'read index received' (duration: 1.151306371s)","trace[1422154088] 'applied index is now lower than readState.Index' (duration: 348.622µs)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:53:37.861287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150785688s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-29T01:53:37.861334Z","caller":"traceutil/trace.go:171","msg":"trace[245810522] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1176; }","duration":"1.150901925s","start":"2026-03-29T01:53:36.71042Z","end":"2026-03-29T01:53:37.861322Z","steps":["trace[245810522] 'agreement among raft nodes before linearized reading' (duration: 1.150708103s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:37.861368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:36.710399Z","time spent":"1.150961509s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-29T01:53:37.86158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.210066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-29T01:53:37.861606Z","caller":"traceutil/trace.go:171","msg":"trace[1901903659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1176; }","duration":"545.28662ms","start":"2026-03-29T01:53:37.316312Z","end":"2026-03-29T01:53:37.861599Z","steps":["trace[1901903659] 'agreement among raft nodes before linearized reading' (duration: 545.222086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:37.861622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:37.316282Z","time spent":"545.335444ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-29T01:53:37.861725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.055062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:53:37.861755Z","caller":"traceutil/trace.go:171","msg":"trace[606252975] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1176; }","duration":"722.141447ms","start":"2026-03-29T01:53:37.139603Z","end":"2026-03-29T01:53:37.861744Z","steps":["trace[606252975] 'agreement among raft nodes before linearized reading' (duration: 722.092244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:37.861782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:37.139571Z","time spent":"722.205112ms","remote":"127.0.0.1:60688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-03-29T01:53:37.861762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.827761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-29T01:53:37.862054Z","caller":"traceutil/trace.go:171","msg":"trace[1849998632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1176; }","duration":"545.179754ms","start":"2026-03-29T01:53:37.316848Z","end":"2026-03-29T01:53:37.862027Z","steps":["trace[1849998632] 'agreement among raft nodes before linearized reading' (duration: 544.592396ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:37.86211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.325697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:53:37.862139Z","caller":"traceutil/trace.go:171","msg":"trace[435202488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1176; }","duration":"920.414003ms","start":"2026-03-29T01:53:36.941718Z","end":"2026-03-29T01:53:37.862132Z","steps":["trace[435202488] 'agreement among raft nodes before linearized reading' (duration: 920.36752ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:37.862164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:36.941677Z","time spent":"920.482116ms","remote":"127.0.0.1:60274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-29T01:53:37.862161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:37.316831Z","time spent":"545.316663ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-29T01:53:37.862649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.616282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-29T01:53:37.862682Z","caller":"traceutil/trace.go:171","msg":"trace[650647316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1176; }","duration":"545.666355ms","start":"2026-03-29T01:53:37.317009Z","end":"2026-03-29T01:53:37.862675Z","steps":["trace[650647316] 'agreement among raft nodes before linearized reading' (duration: 545.58756ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:37.862703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:37.317001Z","time spent":"545.696647ms","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-29T01:53:37.862775Z","caller":"traceutil/trace.go:171","msg":"trace[1697450152] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"1.153948532s","start":"2026-03-29T01:53:36.708819Z","end":"2026-03-29T01:53:37.862767Z","steps":["trace[1697450152] 'process raft request' (duration: 1.151422049s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:37.862867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:36.708804Z","time spent":"1.153995785s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-29T01:53:37.863075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154203378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-29T01:53:37.863205Z","caller":"traceutil/trace.go:171","msg":"trace[1228236541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1176; }","duration":"1.154401561s","start":"2026-03-29T01:53:36.708791Z","end":"2026-03-29T01:53:37.863192Z","steps":["trace[1228236541] 'agreement among raft nodes before linearized reading' (duration: 1.154139564s)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:53:37.863242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-29T01:53:36.708768Z","time spent":"1.154465185s","remote":"127.0.0.1:60566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-29T01:54:30.304621Z","caller":"traceutil/trace.go:171","msg":"trace[1013948693] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"138.771841ms","start":"2026-03-29T01:54:30.165829Z","end":"2026-03-29T01:54:30.304601Z","steps":["trace[1013948693] 'process raft request' (duration: 138.505697ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:30.517651Z","caller":"traceutil/trace.go:171","msg":"trace[1055961154] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"175.460011ms","start":"2026-03-29T01:54:30.342169Z","end":"2026-03-29T01:54:30.517629Z","steps":["trace[1055961154] 'process raft request' (duration: 172.78488ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:54:38.550609Z","caller":"traceutil/trace.go:171","msg":"trace[1507462407] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"144.31679ms","start":"2026-03-29T01:54:38.406266Z","end":"2026-03-29T01:54:38.550583Z","steps":["trace[1507462407] 'process raft request' (duration: 110.14614ms)","trace[1507462407] 'compare' (duration: 33.857676ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:54:38.550831Z","caller":"traceutil/trace.go:171","msg":"trace[995378860] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"144.401299ms","start":"2026-03-29T01:54:38.40642Z","end":"2026-03-29T01:54:38.550821Z","steps":["trace[995378860] 'process raft request' (duration: 143.950987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:02.779671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.071699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1084"} {"level":"info","ts":"2026-03-29T01:55:02.779752Z","caller":"traceutil/trace.go:171","msg":"trace[1934140391] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1498; }","duration":"165.212559ms","start":"2026-03-29T01:55:02.614523Z","end":"2026-03-29T01:55:02.779736Z","steps":["trace[1934140391] 'range keys from in-memory index tree' (duration: 164.926591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:02.779876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.236917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/\" range_end:\"/registry/services/specs/kube-system0\" limit:500 ","response":"range_response_count:1 size:1211"} {"level":"warn","ts":"2026-03-29T01:55:02.779616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.516939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubelet-config\" ","response":"range_response_count:1 size:1357"} {"level":"info","ts":"2026-03-29T01:55:02.779934Z","caller":"traceutil/trace.go:171","msg":"trace[1662947588] range","detail":"{range_begin:/registry/services/specs/kube-system/; range_end:/registry/services/specs/kube-system0; response_count:1; response_revision:1498; }","duration":"106.340557ms","start":"2026-03-29T01:55:02.673576Z","end":"2026-03-29T01:55:02.779917Z","steps":["trace[1662947588] 'range keys from in-memory index tree' (duration: 106.080098ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:02.779933Z","caller":"traceutil/trace.go:171","msg":"trace[229794602] range","detail":"{range_begin:/registry/configmaps/kube-system/kubelet-config; range_end:; response_count:1; response_revision:1498; }","duration":"156.871087ms","start":"2026-03-29T01:55:02.623051Z","end":"2026-03-29T01:55:02.779922Z","steps":["trace[229794602] 'range keys from in-memory index tree' (duration: 156.445389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:02.780323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.769779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:55:02.780427Z","caller":"traceutil/trace.go:171","msg":"trace[1815996451] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1498; }","duration":"147.914528ms","start":"2026-03-29T01:55:02.632489Z","end":"2026-03-29T01:55:02.780403Z","steps":["trace[1815996451] 'count revisions from in-memory index tree' (duration: 147.712949ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:03.056346Z","caller":"traceutil/trace.go:171","msg":"trace[669920230] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"272.174478ms","start":"2026-03-29T01:55:02.784147Z","end":"2026-03-29T01:55:03.056322Z","steps":["trace[669920230] 'process raft request' (duration: 173.68862ms)","trace[669920230] 'compare' (duration: 98.104009ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:55:03.056656Z","caller":"traceutil/trace.go:171","msg":"trace[1594727620] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"272.454817ms","start":"2026-03-29T01:55:02.784174Z","end":"2026-03-29T01:55:03.056628Z","steps":["trace[1594727620] 'process raft request' (duration: 272.070798ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:03.056915Z","caller":"traceutil/trace.go:171","msg":"trace[1500946052] linearizableReadLoop","detail":"{readStateIndex:1570; appliedIndex:1567; }","duration":"226.874585ms","start":"2026-03-29T01:55:02.830015Z","end":"2026-03-29T01:55:03.056889Z","steps":["trace[1500946052] 'read index received' (duration: 127.796699ms)","trace[1500946052] 'applied index is now lower than readState.Index' (duration: 99.076216ms)"],"step_count":2} {"level":"warn","ts":"2026-03-29T01:55:03.057117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.524831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-29T01:55:03.057127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.379452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubelet-config\" ","response":"range_response_count:1 size:1357"} {"level":"info","ts":"2026-03-29T01:55:03.057187Z","caller":"traceutil/trace.go:171","msg":"trace[1601440472] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1502; }","duration":"115.611881ms","start":"2026-03-29T01:55:02.941541Z","end":"2026-03-29T01:55:03.057153Z","steps":["trace[1601440472] 'agreement among raft nodes before linearized reading' (duration: 115.530561ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:03.057181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.240339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/local-path-storage/\" range_end:\"/registry/rolebindings/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:55:03.057252Z","caller":"traceutil/trace.go:171","msg":"trace[586581988] range","detail":"{range_begin:/registry/rolebindings/local-path-storage/; range_end:/registry/rolebindings/local-path-storage0; response_count:0; response_revision:1502; }","duration":"184.354987ms","start":"2026-03-29T01:55:02.872876Z","end":"2026-03-29T01:55:03.05723Z","steps":["trace[586581988] 'agreement among raft nodes before linearized reading' (duration: 184.241378ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:03.057276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.713304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/kube-system/\" range_end:\"/registry/statefulsets/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-29T01:55:03.057327Z","caller":"traceutil/trace.go:171","msg":"trace[2087087829] range","detail":"{range_begin:/registry/statefulsets/kube-system/; range_end:/registry/statefulsets/kube-system0; response_count:0; response_revision:1502; }","duration":"191.772844ms","start":"2026-03-29T01:55:02.865536Z","end":"2026-03-29T01:55:03.057308Z","steps":["trace[2087087829] 'agreement among raft nodes before linearized reading' (duration: 191.675184ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:03.057184Z","caller":"traceutil/trace.go:171","msg":"trace[1295113072] range","detail":"{range_begin:/registry/configmaps/kube-system/kubelet-config; range_end:; response_count:1; response_revision:1502; }","duration":"194.467841ms","start":"2026-03-29T01:55:02.8627Z","end":"2026-03-29T01:55:03.057168Z","steps":["trace[1295113072] 'agreement among raft nodes before linearized reading' (duration: 194.363792ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:03.057503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.467292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:85 size:63223"} {"level":"info","ts":"2026-03-29T01:55:03.057575Z","caller":"traceutil/trace.go:171","msg":"trace[414416707] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:85; response_revision:1502; }","duration":"227.583622ms","start":"2026-03-29T01:55:02.829976Z","end":"2026-03-29T01:55:03.05756Z","steps":["trace[414416707] 'agreement among raft nodes before linearized reading' (duration: 227.012304ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:03.056959Z","caller":"traceutil/trace.go:171","msg":"trace[1655617763] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"271.248643ms","start":"2026-03-29T01:55:02.785649Z","end":"2026-03-29T01:55:03.056898Z","steps":["trace[1655617763] 'process raft request' (duration: 270.882604ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:07.24333Z","caller":"traceutil/trace.go:171","msg":"trace[1349236351] linearizableReadLoop","detail":"{readStateIndex:1585; appliedIndex:1582; }","duration":"104.520202ms","start":"2026-03-29T01:55:07.13879Z","end":"2026-03-29T01:55:07.24331Z","steps":["trace[1349236351] 'read index received' (duration: 37.642028ms)","trace[1349236351] 'applied index is now lower than readState.Index' (duration: 66.877404ms)"],"step_count":2} {"level":"info","ts":"2026-03-29T01:55:07.243337Z","caller":"traceutil/trace.go:171","msg":"trace[42169356] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"107.887314ms","start":"2026-03-29T01:55:07.135414Z","end":"2026-03-29T01:55:07.243302Z","steps":["trace[42169356] 'process raft request' (duration: 103.652973ms)"],"step_count":1} {"level":"info","ts":"2026-03-29T01:55:07.243388Z","caller":"traceutil/trace.go:171","msg":"trace[1875206710] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"107.892444ms","start":"2026-03-29T01:55:07.135473Z","end":"2026-03-29T01:55:07.243366Z","steps":["trace[1875206710] 'process raft request' (duration: 107.725534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-29T01:55:07.243598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.780821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/local-path-storage/\" range_end:\"/registry/events/local-path-storage0\" limit:500 ","response":"range_response_count:11 size:9384"} {"level":"info","ts":"2026-03-29T01:55:07.24363Z","caller":"traceutil/trace.go:171","msg":"trace[1750570288] range","detail":"{range_begin:/registry/events/local-path-storage/; range_end:/registry/events/local-path-storage0; response_count:11; response_revision:1516; }","duration":"104.847551ms","start":"2026-03-29T01:55:07.138773Z","end":"2026-03-29T01:55:07.243621Z","steps":["trace[1750570288] 'agreement among raft nodes before linearized reading' (duration: 104.656892ms)"],"step_count":1}