{"level":"warn","ts":"2026-03-03T00:47:03.987807Z","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-03T00:47:03.987883Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.36: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://162.253.55.36:2380","--initial-cluster=instance=https://162.253.55.36:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.36:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.36: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-03T00:47:03.987948Z","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-03T00:47:03.987956Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-03-03T00:47:03.987979Z","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-03T00:47:03.988524Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"]} {"level":"info","ts":"2026-03-03T00:47:03.988616Z","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://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.36: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-03T00:47:04.168216Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"179.402516ms"} {"level":"info","ts":"2026-03-03T00:47:04.298706Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb89ec34a9687f0b","cluster-id":"d8e0d5e222e856c9"} {"level":"info","ts":"2026-03-03T00:47:04.298809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=()"} {"level":"info","ts":"2026-03-03T00:47:04.298839Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 0"} {"level":"info","ts":"2026-03-03T00:47:04.298853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb89ec34a9687f0b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-03T00:47:04.298936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became follower at term 1"} {"level":"info","ts":"2026-03-03T00:47:04.299137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"warn","ts":"2026-03-03T00:47:04.389753Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-03T00:47:04.391706Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-03T00:47:04.393308Z","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-03T00:47:04.414055Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb89ec34a9687f0b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-03T00:47:04.41519Z","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-03T00:47:04.415334Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb89ec34a9687f0b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-03T00:47:04.415502Z","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-03T00:47:04.415564Z","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-03T00:47:04.42001Z","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-03T00:47:04.420153Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-03-03T00:47:04.420197Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.36:2380"} {"level":"info","ts":"2026-03-03T00:47:04.420445Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb89ec34a9687f0b","initial-advertise-peer-urls":["https://162.253.55.36:2380"],"listen-peer-urls":["https://162.253.55.36:2380"],"advertise-client-urls":["https://162.253.55.36:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.36:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-03T00:47:04.420553Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-03T00:47:04.50253Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b switched to configuration voters=(14666513372433383179)"} {"level":"info","ts":"2026-03-03T00:47:04.502786Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","added-peer-id":"cb89ec34a9687f0b","added-peer-peer-urls":["https://162.253.55.36:2380"]} {"level":"info","ts":"2026-03-03T00:47:04.825558Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b is starting a new election at term 1"} {"level":"info","ts":"2026-03-03T00:47:04.825614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became pre-candidate at term 1"} {"level":"info","ts":"2026-03-03T00:47:04.825629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgPreVoteResp from cb89ec34a9687f0b at term 1"} {"level":"info","ts":"2026-03-03T00:47:04.825641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became candidate at term 2"} {"level":"info","ts":"2026-03-03T00:47:04.825646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b received MsgVoteResp from cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-03-03T00:47:04.825677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb89ec34a9687f0b became leader at term 2"} {"level":"info","ts":"2026-03-03T00:47:04.825686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb89ec34a9687f0b elected leader cb89ec34a9687f0b at term 2"} {"level":"info","ts":"2026-03-03T00:47:05.016588Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T00:47:05.379274Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb89ec34a9687f0b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.36:2379]}","request-path":"/0/members/cb89ec34a9687f0b/attributes","cluster-id":"d8e0d5e222e856c9","publish-timeout":"7s"} {"level":"info","ts":"2026-03-03T00:47:05.379306Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T00:47:05.379541Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-03T00:47:05.379587Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-03T00:47:05.379464Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-03T00:47:05.384411Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.36:2379"} {"level":"info","ts":"2026-03-03T00:47:05.387242Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-03T00:47:05.415342Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d8e0d5e222e856c9","local-member-id":"cb89ec34a9687f0b","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T00:47:05.415483Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T00:47:05.41558Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-03T00:47:06.994913Z","caller":"traceutil/trace.go:171","msg":"trace[110211096] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"126.965546ms","start":"2026-03-03T00:47:06.867918Z","end":"2026-03-03T00:47:06.994884Z","steps":["trace[110211096] 'process raft request' (duration: 126.888384ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:06.994875Z","caller":"traceutil/trace.go:171","msg":"trace[1230368183] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"127.725745ms","start":"2026-03-03T00:47:06.867123Z","end":"2026-03-03T00:47:06.994848Z","steps":["trace[1230368183] 'process raft request' (duration: 125.436257ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:06.99497Z","caller":"traceutil/trace.go:171","msg":"trace[964819360] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"126.675508ms","start":"2026-03-03T00:47:06.868259Z","end":"2026-03-03T00:47:06.994935Z","steps":["trace[964819360] 'process raft request' (duration: 126.596756ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:06.995095Z","caller":"traceutil/trace.go:171","msg":"trace[1633515997] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"126.887514ms","start":"2026-03-03T00:47:06.868195Z","end":"2026-03-03T00:47:06.995082Z","steps":["trace[1633515997] 'process raft request' (duration: 126.634398ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:06.995173Z","caller":"traceutil/trace.go:171","msg":"trace[595509998] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"124.273588ms","start":"2026-03-03T00:47:06.870829Z","end":"2026-03-03T00:47:06.995102Z","steps":["trace[595509998] 'process raft request' (duration: 124.074223ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:06.995364Z","caller":"traceutil/trace.go:171","msg":"trace[2040381853] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"123.57209ms","start":"2026-03-03T00:47:06.871776Z","end":"2026-03-03T00:47:06.995348Z","steps":["trace[2040381853] 'process raft request' (duration: 123.143889ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:06.995382Z","caller":"traceutil/trace.go:171","msg":"trace[1436386543] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"124.631857ms","start":"2026-03-03T00:47:06.870681Z","end":"2026-03-03T00:47:06.995313Z","steps":["trace[1436386543] 'process raft request' (duration: 124.192906ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:06.995672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.915045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T00:47:06.995823Z","caller":"traceutil/trace.go:171","msg":"trace[1306693473] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:27; }","duration":"127.091779ms","start":"2026-03-03T00:47:06.868676Z","end":"2026-03-03T00:47:06.995768Z","steps":["trace[1306693473] 'agreement among raft nodes before linearized reading' (duration: 126.779102ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:06.995288Z","caller":"traceutil/trace.go:171","msg":"trace[937434967] linearizableReadLoop","detail":"{readStateIndex:27; appliedIndex:22; }","duration":"126.560736ms","start":"2026-03-03T00:47:06.868703Z","end":"2026-03-03T00:47:06.995264Z","steps":["trace[937434967] 'read index received' (duration: 46.800472ms)","trace[937434967] 'applied index is now lower than readState.Index' (duration: 79.758604ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:06.997268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.543515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-03T00:47:06.997334Z","caller":"traceutil/trace.go:171","msg":"trace[755699153] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:27; }","duration":"126.631248ms","start":"2026-03-03T00:47:06.870686Z","end":"2026-03-03T00:47:06.997317Z","steps":["trace[755699153] 'agreement among raft nodes before linearized reading' (duration: 126.531805ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:08.126646Z","caller":"traceutil/trace.go:171","msg":"trace[787274235] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"103.612298ms","start":"2026-03-03T00:47:08.023003Z","end":"2026-03-03T00:47:08.126616Z","steps":["trace[787274235] 'process raft request' (duration: 103.525256ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:08.126693Z","caller":"traceutil/trace.go:171","msg":"trace[480299661] transaction","detail":"{read_only:false; response_revision:102; number_of_response:1; }","duration":"103.732561ms","start":"2026-03-03T00:47:08.02292Z","end":"2026-03-03T00:47:08.126653Z","steps":["trace[480299661] 'process raft request' (duration: 37.077586ms)","trace[480299661] 'compare' (duration: 66.339777ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:08.347614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.517672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-03T00:47:08.347817Z","caller":"traceutil/trace.go:171","msg":"trace[1567187565] transaction","detail":"{read_only:false; response_revision:109; number_of_response:1; }","duration":"147.61178ms","start":"2026-03-03T00:47:08.200168Z","end":"2026-03-03T00:47:08.34778Z","steps":["trace[1567187565] 'process raft request' (duration: 42.327659ms)","trace[1567187565] 'compare' (duration: 104.387739ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:08.348196Z","caller":"traceutil/trace.go:171","msg":"trace[1250660105] transaction","detail":"{read_only:false; response_revision:110; number_of_response:1; }","duration":"147.311503ms","start":"2026-03-03T00:47:08.200864Z","end":"2026-03-03T00:47:08.348176Z","steps":["trace[1250660105] 'process raft request' (duration: 147.17352ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:08.655932Z","caller":"traceutil/trace.go:171","msg":"trace[2071419213] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"105.812905ms","start":"2026-03-03T00:47:08.550089Z","end":"2026-03-03T00:47:08.655901Z","steps":["trace[2071419213] 'process raft request' (duration: 105.739403ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:08.655985Z","caller":"traceutil/trace.go:171","msg":"trace[2094330509] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"106.01165ms","start":"2026-03-03T00:47:08.549939Z","end":"2026-03-03T00:47:08.65595Z","steps":["trace[2094330509] 'process raft request' (duration: 60.791517ms)","trace[2094330509] 'compare' (duration: 44.874584ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:08.980465Z","caller":"traceutil/trace.go:171","msg":"trace[1901878279] transaction","detail":"{read_only:false; response_revision:129; number_of_response:1; }","duration":"124.057175ms","start":"2026-03-03T00:47:08.856358Z","end":"2026-03-03T00:47:08.980415Z","steps":["trace[1901878279] 'process raft request' (duration: 43.762165ms)","trace[1901878279] 'compare' (duration: 80.062914ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:09.164216Z","caller":"traceutil/trace.go:171","msg":"trace[1732957932] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"107.2051ms","start":"2026-03-03T00:47:09.05699Z","end":"2026-03-03T00:47:09.164195Z","steps":["trace[1732957932] 'process raft request' (duration: 54.318323ms)","trace[1732957932] 'compare' (duration: 52.634721ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:09.16422Z","caller":"traceutil/trace.go:171","msg":"trace[1439593647] transaction","detail":"{read_only:false; response_revision:133; number_of_response:1; }","duration":"105.943388ms","start":"2026-03-03T00:47:09.058252Z","end":"2026-03-03T00:47:09.164195Z","steps":["trace[1439593647] 'process raft request' (duration: 105.879817ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:09.837676Z","caller":"traceutil/trace.go:171","msg":"trace[1936398746] transaction","detail":"{read_only:false; response_revision:145; number_of_response:1; }","duration":"245.519387ms","start":"2026-03-03T00:47:09.592116Z","end":"2026-03-03T00:47:09.837636Z","steps":["trace[1936398746] 'process raft request' (duration: 242.212263ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:11.248715Z","caller":"traceutil/trace.go:171","msg":"trace[1530541661] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"121.900034ms","start":"2026-03-03T00:47:11.126778Z","end":"2026-03-03T00:47:11.248678Z","steps":["trace[1530541661] 'process raft request' (duration: 121.260788ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:11.434151Z","caller":"traceutil/trace.go:171","msg":"trace[1112301943] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"103.380945ms","start":"2026-03-03T00:47:11.330748Z","end":"2026-03-03T00:47:11.434129Z","steps":["trace[1112301943] 'process raft request' (duration: 33.756244ms)","trace[1112301943] 'compare' (duration: 69.546059ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:12.074311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.789579ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-03T00:47:12.07441Z","caller":"traceutil/trace.go:171","msg":"trace[1206508131] linearizableReadLoop","detail":"{readStateIndex:231; appliedIndex:230; }","duration":"233.169981ms","start":"2026-03-03T00:47:11.841224Z","end":"2026-03-03T00:47:12.074394Z","steps":["trace[1206508131] 'read index received' (duration: 21.145246ms)","trace[1206508131] 'applied index is now lower than readState.Index' (duration: 212.023645ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:12.074495Z","caller":"traceutil/trace.go:171","msg":"trace[1674175594] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"448.447607ms","start":"2026-03-03T00:47:11.626037Z","end":"2026-03-03T00:47:12.074485Z","steps":["trace[1674175594] 'process raft request' (duration: 236.412022ms)","trace[1674175594] 'compare' (duration: 211.628185ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:12.074549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:11.626017Z","time spent":"448.510188ms","remote":"127.0.0.1:42760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":694,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T00:47:12.074611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.081761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:47:12.074682Z","caller":"traceutil/trace.go:171","msg":"trace[1971369411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:226; }","duration":"160.214395ms","start":"2026-03-03T00:47:11.914449Z","end":"2026-03-03T00:47:12.074664Z","steps":["trace[1971369411] 'agreement among raft nodes before linearized reading' (duration: 160.063221ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:12.074912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.677294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:47:12.075186Z","caller":"traceutil/trace.go:171","msg":"trace[787253847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:226; }","duration":"233.825597ms","start":"2026-03-03T00:47:11.841175Z","end":"2026-03-03T00:47:12.075Z","steps":["trace[787253847] 'agreement among raft nodes before linearized reading' (duration: 233.625162ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:12.456884Z","caller":"traceutil/trace.go:171","msg":"trace[669673784] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"149.824252ms","start":"2026-03-03T00:47:12.307033Z","end":"2026-03-03T00:47:12.456857Z","steps":["trace[669673784] 'process raft request' (duration: 62.338857ms)","trace[669673784] 'compare' (duration: 87.345701ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:12.501972Z","caller":"traceutil/trace.go:171","msg":"trace[2097643743] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"126.579154ms","start":"2026-03-03T00:47:12.375366Z","end":"2026-03-03T00:47:12.501945Z","steps":["trace[2097643743] 'process raft request' (duration: 126.42652ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:12.76815Z","caller":"traceutil/trace.go:171","msg":"trace[1213768979] linearizableReadLoop","detail":"{readStateIndex:240; appliedIndex:239; }","duration":"103.90101ms","start":"2026-03-03T00:47:12.664218Z","end":"2026-03-03T00:47:12.768119Z","steps":["trace[1213768979] 'read index received' (duration: 54.172µs)","trace[1213768979] 'applied index is now lower than readState.Index' (duration: 103.845728ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:12.768262Z","caller":"traceutil/trace.go:171","msg":"trace[1485552344] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"104.039963ms","start":"2026-03-03T00:47:12.664188Z","end":"2026-03-03T00:47:12.768228Z","steps":["trace[1485552344] 'process raft request' (duration: 45.953313ms)","trace[1485552344] 'compare' (duration: 57.821083ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:12.768448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.060424ms","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-03T00:47:12.768514Z","caller":"traceutil/trace.go:171","msg":"trace[655742979] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:235; }","duration":"104.28766ms","start":"2026-03-03T00:47:12.664203Z","end":"2026-03-03T00:47:12.768491Z","steps":["trace[655742979] 'agreement among raft nodes before linearized reading' (duration: 104.024843ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:13.672208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.824617ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-03T00:47:13.672406Z","caller":"traceutil/trace.go:171","msg":"trace[1321828714] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"490.956911ms","start":"2026-03-03T00:47:13.181424Z","end":"2026-03-03T00:47:13.672381Z","steps":["trace[1321828714] 'process raft request' (duration: 123.888127ms)","trace[1321828714] 'compare' (duration: 366.628852ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:13.672484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:13.181409Z","time spent":"491.038642ms","remote":"127.0.0.1:42698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":465,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T00:47:14.061299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.54687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-03T00:47:14.061662Z","caller":"traceutil/trace.go:171","msg":"trace[775604537] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"857.79949ms","start":"2026-03-03T00:47:13.203824Z","end":"2026-03-03T00:47:14.061623Z","steps":["trace[775604537] 'process raft request' (duration: 575.867661ms)","trace[775604537] 'compare' (duration: 281.415686ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:14.061695Z","caller":"traceutil/trace.go:171","msg":"trace[2058271735] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"717.063716ms","start":"2026-03-03T00:47:13.344614Z","end":"2026-03-03T00:47:14.061677Z","steps":["trace[2058271735] 'process raft request' (duration: 717.005355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:14.061882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:13.344587Z","time spent":"717.232871ms","remote":"127.0.0.1:42528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1068,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T00:47:14.06175Z","caller":"traceutil/trace.go:171","msg":"trace[1135058628] linearizableReadLoop","detail":"{readStateIndex:254; appliedIndex:252; }","duration":"803.647629ms","start":"2026-03-03T00:47:13.258053Z","end":"2026-03-03T00:47:14.0617Z","steps":["trace[1135058628] 'read index received' (duration: 47.345429ms)","trace[1135058628] 'applied index is now lower than readState.Index' (duration: 756.291679ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:14.062109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.057919ms","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-03T00:47:14.062146Z","caller":"traceutil/trace.go:171","msg":"trace[1563698757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:249; }","duration":"804.132111ms","start":"2026-03-03T00:47:13.258003Z","end":"2026-03-03T00:47:14.062135Z","steps":["trace[1563698757] 'agreement among raft nodes before linearized reading' (duration: 804.024698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:14.062251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:13.257989Z","time spent":"804.233713ms","remote":"127.0.0.1:42690","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-03T00:47:14.062494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:13.203799Z","time spent":"857.933482ms","remote":"127.0.0.1:42624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T00:47:14.062835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.679063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:47:14.062899Z","caller":"traceutil/trace.go:171","msg":"trace[1801543960] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:0; response_revision:249; }","duration":"266.821886ms","start":"2026-03-03T00:47:13.79606Z","end":"2026-03-03T00:47:14.062882Z","steps":["trace[1801543960] 'agreement among raft nodes before linearized reading' (duration: 266.465507ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:14.477653Z","caller":"traceutil/trace.go:171","msg":"trace[1783597783] linearizableReadLoop","detail":"{readStateIndex:268; appliedIndex:267; }","duration":"180.466251ms","start":"2026-03-03T00:47:14.297163Z","end":"2026-03-03T00:47:14.477629Z","steps":["trace[1783597783] 'read index received' (duration: 142.327765ms)","trace[1783597783] 'applied index is now lower than readState.Index' (duration: 38.137876ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:14.47775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.561493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:47:14.47779Z","caller":"traceutil/trace.go:171","msg":"trace[237227118] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:0; response_revision:262; }","duration":"180.634865ms","start":"2026-03-03T00:47:14.297144Z","end":"2026-03-03T00:47:14.477779Z","steps":["trace[237227118] 'agreement among raft nodes before linearized reading' (duration: 180.559213ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:14.478091Z","caller":"traceutil/trace.go:171","msg":"trace[35113832] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"212.39891ms","start":"2026-03-03T00:47:14.265569Z","end":"2026-03-03T00:47:14.477968Z","steps":["trace[35113832] 'process raft request' (duration: 173.835943ms)","trace[35113832] 'compare' (duration: 38.144366ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:14.915151Z","caller":"traceutil/trace.go:171","msg":"trace[968316560] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"206.129661ms","start":"2026-03-03T00:47:14.708991Z","end":"2026-03-03T00:47:14.91512Z","steps":["trace[968316560] 'process raft request' (duration: 142.685314ms)","trace[968316560] 'compare' (duration: 63.294253ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:14.915839Z","caller":"traceutil/trace.go:171","msg":"trace[1654697250] linearizableReadLoop","detail":"{readStateIndex:278; appliedIndex:276; }","duration":"143.425943ms","start":"2026-03-03T00:47:14.7724Z","end":"2026-03-03T00:47:14.915826Z","steps":["trace[1654697250] 'read index received' (duration: 79.34872ms)","trace[1654697250] 'applied index is now lower than readState.Index' (duration: 64.076283ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:14.916154Z","caller":"traceutil/trace.go:171","msg":"trace[517124957] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"206.310145ms","start":"2026-03-03T00:47:14.709808Z","end":"2026-03-03T00:47:14.916118Z","steps":["trace[517124957] 'process raft request' (duration: 205.934696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:14.916185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.744861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-03T00:47:14.916269Z","caller":"traceutil/trace.go:171","msg":"trace[1198449003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:272; }","duration":"143.907735ms","start":"2026-03-03T00:47:14.772342Z","end":"2026-03-03T00:47:14.91625Z","steps":["trace[1198449003] 'agreement among raft nodes before linearized reading' (duration: 143.72317ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:15.777393Z","caller":"traceutil/trace.go:171","msg":"trace[190409187] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:293; }","duration":"161.345768ms","start":"2026-03-03T00:47:15.616015Z","end":"2026-03-03T00:47:15.777361Z","steps":["trace[190409187] 'read index received' (duration: 142.224564ms)","trace[190409187] 'applied index is now lower than readState.Index' (duration: 19.119714ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:15.777432Z","caller":"traceutil/trace.go:171","msg":"trace[630311209] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"163.42893ms","start":"2026-03-03T00:47:15.613971Z","end":"2026-03-03T00:47:15.7774Z","steps":["trace[630311209] 'process raft request' (duration: 144.254104ms)","trace[630311209] 'compare' (duration: 19.002542ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:15.777695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.641416ms","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-03T00:47:15.777818Z","caller":"traceutil/trace.go:171","msg":"trace[1826055181] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:288; }","duration":"161.789459ms","start":"2026-03-03T00:47:15.616004Z","end":"2026-03-03T00:47:15.777793Z","steps":["trace[1826055181] 'agreement among raft nodes before linearized reading' (duration: 161.476541ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:17.510964Z","caller":"traceutil/trace.go:171","msg":"trace[363125343] transaction","detail":"{read_only:false; number_of_response:0; response_revision:333; }","duration":"141.137609ms","start":"2026-03-03T00:47:17.369807Z","end":"2026-03-03T00:47:17.510944Z","steps":["trace[363125343] 'process raft request' (duration: 141.023836ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:17.511053Z","caller":"traceutil/trace.go:171","msg":"trace[1149645081] transaction","detail":"{read_only:false; number_of_response:0; response_revision:333; }","duration":"141.168499ms","start":"2026-03-03T00:47:17.369864Z","end":"2026-03-03T00:47:17.511032Z","steps":["trace[1149645081] 'process raft request' (duration: 140.989284ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:17.51123Z","caller":"traceutil/trace.go:171","msg":"trace[415661816] transaction","detail":"{read_only:false; number_of_response:0; response_revision:333; }","duration":"141.262992ms","start":"2026-03-03T00:47:17.369722Z","end":"2026-03-03T00:47:17.510985Z","steps":["trace[415661816] 'process raft request' (duration: 141.010945ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:17.511283Z","caller":"traceutil/trace.go:171","msg":"trace[299197899] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"129.166815ms","start":"2026-03-03T00:47:17.382104Z","end":"2026-03-03T00:47:17.511271Z","steps":["trace[299197899] 'process raft request' (duration: 128.893198ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:17.511364Z","caller":"traceutil/trace.go:171","msg":"trace[1147252141] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"134.785467ms","start":"2026-03-03T00:47:17.376566Z","end":"2026-03-03T00:47:17.511352Z","steps":["trace[1147252141] 'process raft request' (duration: 134.321985ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:17.696701Z","caller":"traceutil/trace.go:171","msg":"trace[99288670] linearizableReadLoop","detail":"{readStateIndex:354; appliedIndex:353; }","duration":"130.813966ms","start":"2026-03-03T00:47:17.565861Z","end":"2026-03-03T00:47:17.696675Z","steps":["trace[99288670] 'read index received' (duration: 71.01204ms)","trace[99288670] 'applied index is now lower than readState.Index' (duration: 59.800796ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:17.696722Z","caller":"traceutil/trace.go:171","msg":"trace[128376340] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"134.778117ms","start":"2026-03-03T00:47:17.56191Z","end":"2026-03-03T00:47:17.696688Z","steps":["trace[128376340] 'process raft request' (duration: 75.097164ms)","trace[128376340] 'compare' (duration: 59.527109ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:17.696912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.003281ms","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-03T00:47:17.696952Z","caller":"traceutil/trace.go:171","msg":"trace[327662299] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:338; }","duration":"131.093243ms","start":"2026-03-03T00:47:17.565847Z","end":"2026-03-03T00:47:17.69694Z","steps":["trace[327662299] 'agreement among raft nodes before linearized reading' (duration: 130.923069ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:17.865332Z","caller":"traceutil/trace.go:171","msg":"trace[1390483600] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"104.225812ms","start":"2026-03-03T00:47:17.761079Z","end":"2026-03-03T00:47:17.865304Z","steps":["trace[1390483600] 'process raft request' (duration: 75.415602ms)","trace[1390483600] 'compare' (duration: 28.583855ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:18.039345Z","caller":"traceutil/trace.go:171","msg":"trace[574588009] linearizableReadLoop","detail":"{readStateIndex:358; appliedIndex:357; }","duration":"161.482775ms","start":"2026-03-03T00:47:17.877843Z","end":"2026-03-03T00:47:18.039325Z","steps":["trace[574588009] 'read index received' (duration: 151.099901ms)","trace[574588009] 'applied index is now lower than readState.Index' (duration: 10.382054ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:18.039415Z","caller":"traceutil/trace.go:171","msg":"trace[1222618605] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"162.743046ms","start":"2026-03-03T00:47:17.876644Z","end":"2026-03-03T00:47:18.039387Z","steps":["trace[1222618605] 'process raft request' (duration: 152.321372ms)","trace[1222618605] 'compare' (duration: 10.23824ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:18.039579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.603548ms","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-03T00:47:18.039657Z","caller":"traceutil/trace.go:171","msg":"trace[1335109068] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:342; }","duration":"161.817373ms","start":"2026-03-03T00:47:17.877824Z","end":"2026-03-03T00:47:18.039641Z","steps":["trace[1335109068] 'agreement among raft nodes before linearized reading' (duration: 161.570776ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:18.220846Z","caller":"traceutil/trace.go:171","msg":"trace[1849238672] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:359; }","duration":"172.133775ms","start":"2026-03-03T00:47:18.04869Z","end":"2026-03-03T00:47:18.220824Z","steps":["trace[1849238672] 'read index received' (duration: 122.437005ms)","trace[1849238672] 'applied index is now lower than readState.Index' (duration: 49.69608ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:18.220934Z","caller":"traceutil/trace.go:171","msg":"trace[1259298660] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"174.835394ms","start":"2026-03-03T00:47:18.046057Z","end":"2026-03-03T00:47:18.220892Z","steps":["trace[1259298660] 'process raft request' (duration: 125.080432ms)","trace[1259298660] 'compare' (duration: 49.584068ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:18.221144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.413622ms","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-03T00:47:18.221183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.593857ms","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-03T00:47:18.221258Z","caller":"traceutil/trace.go:171","msg":"trace[485654525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:344; }","duration":"116.70461ms","start":"2026-03-03T00:47:18.104535Z","end":"2026-03-03T00:47:18.221239Z","steps":["trace[485654525] 'agreement among raft nodes before linearized reading' (duration: 116.575787ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:18.221213Z","caller":"traceutil/trace.go:171","msg":"trace[1389451794] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:344; }","duration":"172.545396ms","start":"2026-03-03T00:47:18.048656Z","end":"2026-03-03T00:47:18.221176Z","steps":["trace[1389451794] 'agreement among raft nodes before linearized reading' (duration: 172.286579ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:18.445733Z","caller":"traceutil/trace.go:171","msg":"trace[1647451256] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:360; }","duration":"210.800306ms","start":"2026-03-03T00:47:18.234909Z","end":"2026-03-03T00:47:18.445709Z","steps":["trace[1647451256] 'read index received' (duration: 28.718929ms)","trace[1647451256] 'applied index is now lower than readState.Index' (duration: 182.080317ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:18.44598Z","caller":"traceutil/trace.go:171","msg":"trace[2114041870] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"213.516294ms","start":"2026-03-03T00:47:18.232313Z","end":"2026-03-03T00:47:18.445829Z","steps":["trace[2114041870] 'process raft request' (duration: 182.297773ms)","trace[2114041870] 'compare' (duration: 30.979085ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:18.445957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.550447ms","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-03T00:47:18.446088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.173286ms","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-03T00:47:18.446124Z","caller":"traceutil/trace.go:171","msg":"trace[1939577205] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:346; }","duration":"148.748592ms","start":"2026-03-03T00:47:18.29735Z","end":"2026-03-03T00:47:18.446098Z","steps":["trace[1939577205] 'agreement among raft nodes before linearized reading' (duration: 148.537627ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:18.4462Z","caller":"traceutil/trace.go:171","msg":"trace[589449526] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:346; }","duration":"211.277298ms","start":"2026-03-03T00:47:18.234884Z","end":"2026-03-03T00:47:18.446161Z","steps":["trace[589449526] 'agreement among raft nodes before linearized reading' (duration: 211.171355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:18.446457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.316354ms","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-03T00:47:18.446491Z","caller":"traceutil/trace.go:171","msg":"trace[410063103] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:346; }","duration":"115.374416ms","start":"2026-03-03T00:47:18.331106Z","end":"2026-03-03T00:47:18.44648Z","steps":["trace[410063103] 'agreement among raft nodes before linearized reading' (duration: 115.312524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:18.446617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.706299ms","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-03T00:47:18.446688Z","caller":"traceutil/trace.go:171","msg":"trace[1549765552] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:346; }","duration":"144.806222ms","start":"2026-03-03T00:47:18.301865Z","end":"2026-03-03T00:47:18.446671Z","steps":["trace[1549765552] 'agreement among raft nodes before linearized reading' (duration: 144.611967ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:18.446688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.956159ms","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-03T00:47:18.44673Z","caller":"traceutil/trace.go:171","msg":"trace[820812053] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:346; }","duration":"114.013981ms","start":"2026-03-03T00:47:18.332703Z","end":"2026-03-03T00:47:18.446716Z","steps":["trace[820812053] 'agreement among raft nodes before linearized reading' (duration: 113.931119ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:18.447082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.902447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-03T00:47:18.447204Z","caller":"traceutil/trace.go:171","msg":"trace[1027318759] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:346; }","duration":"136.060451ms","start":"2026-03-03T00:47:18.311118Z","end":"2026-03-03T00:47:18.447179Z","steps":["trace[1027318759] 'agreement among raft nodes before linearized reading' (duration: 135.289221ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:18.827925Z","caller":"traceutil/trace.go:171","msg":"trace[1400007630] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"152.010954ms","start":"2026-03-03T00:47:18.675872Z","end":"2026-03-03T00:47:18.827883Z","steps":["trace[1400007630] 'process raft request' (duration: 103.590937ms)","trace[1400007630] 'compare' (duration: 48.13165ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:18.828539Z","caller":"traceutil/trace.go:171","msg":"trace[646854311] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"151.558732ms","start":"2026-03-03T00:47:18.676966Z","end":"2026-03-03T00:47:18.828524Z","steps":["trace[646854311] 'process raft request' (duration: 150.782903ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:18.828811Z","caller":"traceutil/trace.go:171","msg":"trace[1090040024] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"150.713681ms","start":"2026-03-03T00:47:18.67807Z","end":"2026-03-03T00:47:18.828784Z","steps":["trace[1090040024] 'process raft request' (duration: 149.764097ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:18.82902Z","caller":"traceutil/trace.go:171","msg":"trace[1773648796] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:365; }","duration":"112.387139ms","start":"2026-03-03T00:47:18.71662Z","end":"2026-03-03T00:47:18.829007Z","steps":["trace[1773648796] 'read index received' (duration: 315.978µs)","trace[1773648796] 'applied index is now lower than readState.Index' (duration: 112.070021ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:18.829112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.481072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-03T00:47:18.829168Z","caller":"traceutil/trace.go:171","msg":"trace[585163400] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:352; }","duration":"112.591284ms","start":"2026-03-03T00:47:18.716563Z","end":"2026-03-03T00:47:18.829155Z","steps":["trace[585163400] 'agreement among raft nodes before linearized reading' (duration: 112.487602ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:18.829313Z","caller":"traceutil/trace.go:171","msg":"trace[1656832934] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"109.431074ms","start":"2026-03-03T00:47:18.719869Z","end":"2026-03-03T00:47:18.8293Z","steps":["trace[1656832934] 'process raft request' (duration: 109.092445ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:18.980869Z","caller":"traceutil/trace.go:171","msg":"trace[447789762] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"144.407412ms","start":"2026-03-03T00:47:18.836432Z","end":"2026-03-03T00:47:18.980839Z","steps":["trace[447789762] 'process raft request' (duration: 99.616286ms)","trace[447789762] 'compare' (duration: 44.648193ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:19.034614Z","caller":"traceutil/trace.go:171","msg":"trace[907333155] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"184.482819ms","start":"2026-03-03T00:47:18.850103Z","end":"2026-03-03T00:47:19.034586Z","steps":["trace[907333155] 'process raft request' (duration: 184.276563ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:19.034693Z","caller":"traceutil/trace.go:171","msg":"trace[397881630] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"184.535861ms","start":"2026-03-03T00:47:18.850132Z","end":"2026-03-03T00:47:19.034668Z","steps":["trace[397881630] 'process raft request' (duration: 184.387667ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:19.034745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.502579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-03-03T00:47:19.034773Z","caller":"traceutil/trace.go:171","msg":"trace[1134151200] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:361; }","duration":"184.574151ms","start":"2026-03-03T00:47:18.850193Z","end":"2026-03-03T00:47:19.034768Z","steps":["trace[1134151200] 'agreement among raft nodes before linearized reading' (duration: 184.478299ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:19.034635Z","caller":"traceutil/trace.go:171","msg":"trace[1983519830] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:369; }","duration":"184.398356ms","start":"2026-03-03T00:47:18.850223Z","end":"2026-03-03T00:47:19.034621Z","steps":["trace[1983519830] 'read index received' (duration: 85.838777ms)","trace[1983519830] 'applied index is now lower than readState.Index' (duration: 98.558619ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:19.035006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.660374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:1 size:2108"} {"level":"info","ts":"2026-03-03T00:47:19.03504Z","caller":"traceutil/trace.go:171","msg":"trace[1330956133] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"184.9042ms","start":"2026-03-03T00:47:18.850124Z","end":"2026-03-03T00:47:19.035028Z","steps":["trace[1330956133] 'process raft request' (duration: 184.341046ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:19.035053Z","caller":"traceutil/trace.go:171","msg":"trace[833572424] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:1; response_revision:361; }","duration":"184.729505ms","start":"2026-03-03T00:47:18.850312Z","end":"2026-03-03T00:47:19.035041Z","steps":["trace[833572424] 'agreement among raft nodes before linearized reading' (duration: 184.617362ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:19.035332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.246313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-03-03T00:47:19.035373Z","caller":"traceutil/trace.go:171","msg":"trace[1966093234] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:361; }","duration":"102.299434ms","start":"2026-03-03T00:47:18.933063Z","end":"2026-03-03T00:47:19.035363Z","steps":["trace[1966093234] 'agreement among raft nodes before linearized reading' (duration: 102.239413ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:19.03538Z","caller":"traceutil/trace.go:171","msg":"trace[1201817503] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"185.221808ms","start":"2026-03-03T00:47:18.850124Z","end":"2026-03-03T00:47:19.035346Z","steps":["trace[1201817503] 'process raft request' (duration: 184.369376ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:19.035637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.802561ms","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-03T00:47:19.035658Z","caller":"traceutil/trace.go:171","msg":"trace[2111882889] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"185.294079ms","start":"2026-03-03T00:47:18.850352Z","end":"2026-03-03T00:47:19.035646Z","steps":["trace[2111882889] 'process raft request' (duration: 184.224242ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:19.035676Z","caller":"traceutil/trace.go:171","msg":"trace[418127883] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:361; }","duration":"168.851232ms","start":"2026-03-03T00:47:18.866816Z","end":"2026-03-03T00:47:19.035667Z","steps":["trace[418127883] 'agreement among raft nodes before linearized reading' (duration: 168.77887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:19.035806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.057869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-03T00:47:19.03583Z","caller":"traceutil/trace.go:171","msg":"trace[378529515] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:361; }","duration":"119.123391ms","start":"2026-03-03T00:47:18.916701Z","end":"2026-03-03T00:47:19.035824Z","steps":["trace[378529515] 'agreement among raft nodes before linearized reading' (duration: 119.07923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:19.03725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.597882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-03-03T00:47:19.037398Z","caller":"traceutil/trace.go:171","msg":"trace[874054063] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"187.060764ms","start":"2026-03-03T00:47:18.850303Z","end":"2026-03-03T00:47:19.037364Z","steps":["trace[874054063] 'process raft request' (duration: 184.249863ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:19.038267Z","caller":"traceutil/trace.go:171","msg":"trace[1650583930] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"187.745142ms","start":"2026-03-03T00:47:18.850484Z","end":"2026-03-03T00:47:19.03823Z","steps":["trace[1650583930] 'process raft request' (duration: 184.11449ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:19.037336Z","caller":"traceutil/trace.go:171","msg":"trace[70886224] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:361; }","duration":"104.428698ms","start":"2026-03-03T00:47:18.932884Z","end":"2026-03-03T00:47:19.037313Z","steps":["trace[70886224] 'agreement among raft nodes before linearized reading' (duration: 102.608972ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:19.554636Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582952282981701,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T00:47:21.062101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.504746259s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T00:47:21.062503Z","caller":"traceutil/trace.go:171","msg":"trace[1867914047] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"2.014629713s","start":"2026-03-03T00:47:19.047842Z","end":"2026-03-03T00:47:21.062471Z","steps":["trace[1867914047] 'process raft request' (duration: 509.30417ms)","trace[1867914047] 'compare' (duration: 1.504570334s)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:21.062516Z","caller":"traceutil/trace.go:171","msg":"trace[2053424614] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"2.008651921s","start":"2026-03-03T00:47:19.053823Z","end":"2026-03-03T00:47:21.062475Z","steps":["trace[2053424614] 'process raft request' (duration: 2.008517538s)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:21.062572Z","caller":"traceutil/trace.go:171","msg":"trace[901265642] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"2.008691612s","start":"2026-03-03T00:47:19.053859Z","end":"2026-03-03T00:47:21.062551Z","steps":["trace[901265642] 'process raft request' (duration: 2.008573499s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:21.062657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:19.053833Z","time spent":"2.008781645s","remote":"127.0.0.1:42698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1050,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T00:47:21.062657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:19.053803Z","time spent":"2.008795515s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":654,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T00:47:21.062673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:19.047825Z","time spent":"2.014745666s","remote":"127.0.0.1:42690","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-03T00:47:21.062929Z","caller":"traceutil/trace.go:171","msg":"trace[2094539685] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"2.00900425s","start":"2026-03-03T00:47:19.05391Z","end":"2026-03-03T00:47:21.062914Z","steps":["trace[2094539685] 'process raft request' (duration: 2.008567799s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:21.063042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:19.053833Z","time spent":"2.009143974s","remote":"127.0.0.1:42598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T00:47:21.063048Z","caller":"traceutil/trace.go:171","msg":"trace[510108512] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"2.005128212s","start":"2026-03-03T00:47:19.057903Z","end":"2026-03-03T00:47:21.063031Z","steps":["trace[510108512] 'process raft request' (duration: 2.004744952s)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:21.062937Z","caller":"traceutil/trace.go:171","msg":"trace[183972863] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"2.008945778s","start":"2026-03-03T00:47:19.053978Z","end":"2026-03-03T00:47:21.062924Z","steps":["trace[183972863] 'process raft request' (duration: 2.008547298s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:21.063128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:19.057873Z","time spent":"2.005213755s","remote":"127.0.0.1:42612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4354,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:47:21.063185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:19.053967Z","time spent":"2.009164835s","remote":"127.0.0.1:42690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:47:21.063254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.009230907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-03-03T00:47:21.063297Z","caller":"traceutil/trace.go:171","msg":"trace[1568603952] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:370; }","duration":"2.009295998s","start":"2026-03-03T00:47:19.053991Z","end":"2026-03-03T00:47:21.063287Z","steps":["trace[1568603952] 'agreement among raft nodes before linearized reading' (duration: 2.009176065s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:21.063331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:19.053984Z","time spent":"2.009340119s","remote":"127.0.0.1:42612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4392,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-03T00:47:21.063117Z","caller":"traceutil/trace.go:171","msg":"trace[1855017290] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"2.004959748s","start":"2026-03-03T00:47:19.058098Z","end":"2026-03-03T00:47:21.063057Z","steps":["trace[1855017290] 'process raft request' (duration: 2.00466365s)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:21.063096Z","caller":"traceutil/trace.go:171","msg":"trace[1054296844] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:377; }","duration":"2.009067831s","start":"2026-03-03T00:47:19.054016Z","end":"2026-03-03T00:47:21.063084Z","steps":["trace[1054296844] 'read index received' (duration: 503.146793ms)","trace[1054296844] 'applied index is now lower than readState.Index' (duration: 1.505920158s)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:21.063479Z","caller":"traceutil/trace.go:171","msg":"trace[1505790850] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"2.004993489s","start":"2026-03-03T00:47:19.058448Z","end":"2026-03-03T00:47:21.063441Z","steps":["trace[1505790850] 'process raft request' (duration: 2.004581478s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:21.063539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:19.058442Z","time spent":"2.005068951s","remote":"127.0.0.1:42778","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-03T00:47:21.063556Z","caller":"traceutil/trace.go:171","msg":"trace[716504813] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"2.005329257s","start":"2026-03-03T00:47:19.058214Z","end":"2026-03-03T00:47:21.063544Z","steps":["trace[716504813] 'process raft request' (duration: 2.004743992s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:21.06361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.994150914s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-03T00:47:21.063659Z","caller":"traceutil/trace.go:171","msg":"trace[558574131] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:370; }","duration":"1.994297368s","start":"2026-03-03T00:47:19.069349Z","end":"2026-03-03T00:47:21.063647Z","steps":["trace[558574131] 'agreement among raft nodes before linearized reading' (duration: 1.994192135s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:21.063676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:19.058207Z","time spent":"2.005426669s","remote":"127.0.0.1:42682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2259,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:47:21.063698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:19.069328Z","time spent":"1.994362719s","remote":"127.0.0.1:42634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"warn","ts":"2026-03-03T00:47:21.063557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:19.058093Z","time spent":"2.005400749s","remote":"127.0.0.1:42624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3378,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T00:47:21.063829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.293627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6961"} {"level":"warn","ts":"2026-03-03T00:47:21.063848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.841505ms","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-03T00:47:21.06387Z","caller":"traceutil/trace.go:171","msg":"trace[1313875581] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:370; }","duration":"441.369149ms","start":"2026-03-03T00:47:20.622491Z","end":"2026-03-03T00:47:21.063861Z","steps":["trace[1313875581] 'agreement among raft nodes before linearized reading' (duration: 441.297987ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:21.063875Z","caller":"traceutil/trace.go:171","msg":"trace[1189441564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:370; }","duration":"795.909876ms","start":"2026-03-03T00:47:20.267957Z","end":"2026-03-03T00:47:21.063867Z","steps":["trace[1189441564] 'agreement among raft nodes before linearized reading' (duration: 795.862055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:21.0639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:20.62247Z","time spent":"441.422821ms","remote":"127.0.0.1:42624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6985,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-03-03T00:47:21.063943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.895993504s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-03-03T00:47:21.063417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.009396441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"warn","ts":"2026-03-03T00:47:21.064016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.947362887s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-03-03T00:47:21.063901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:20.267936Z","time spent":"795.959217ms","remote":"127.0.0.1:42690","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-03T00:47:21.064046Z","caller":"traceutil/trace.go:171","msg":"trace[1505326309] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:370; }","duration":"1.94746016s","start":"2026-03-03T00:47:19.116578Z","end":"2026-03-03T00:47:21.064039Z","steps":["trace[1505326309] 'agreement among raft nodes before linearized reading' (duration: 1.947416219s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:21.064076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:19.116548Z","time spent":"1.947522141s","remote":"127.0.0.1:42634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":221,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"info","ts":"2026-03-03T00:47:21.063978Z","caller":"traceutil/trace.go:171","msg":"trace[1358365917] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:370; }","duration":"1.896096056s","start":"2026-03-03T00:47:19.167872Z","end":"2026-03-03T00:47:21.063968Z","steps":["trace[1358365917] 'agreement among raft nodes before linearized reading' (duration: 1.896046695s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:21.064124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:19.167844Z","time spent":"1.896271111s","remote":"127.0.0.1:42634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-03-03T00:47:21.064014Z","caller":"traceutil/trace.go:171","msg":"trace[1021903312] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:370; }","duration":"2.010015066s","start":"2026-03-03T00:47:19.053986Z","end":"2026-03-03T00:47:21.064001Z","steps":["trace[1021903312] 'agreement among raft nodes before linearized reading' (duration: 2.00939728s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:21.064202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:19.053977Z","time spent":"2.010213121s","remote":"127.0.0.1:42612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4392,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-03T00:47:22.042315Z","caller":"traceutil/trace.go:171","msg":"trace[348388082] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"971.686222ms","start":"2026-03-03T00:47:21.070607Z","end":"2026-03-03T00:47:22.042293Z","steps":["trace[348388082] 'process raft request' (duration: 971.564639ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:22.042423Z","caller":"traceutil/trace.go:171","msg":"trace[232086108] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"971.771454ms","start":"2026-03-03T00:47:21.070612Z","end":"2026-03-03T00:47:22.042384Z","steps":["trace[232086108] 'process raft request' (duration: 971.451086ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:22.042482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:21.070588Z","time spent":"971.821716ms","remote":"127.0.0.1:42690","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-03T00:47:22.042638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:21.070602Z","time spent":"971.873748ms","remote":"127.0.0.1:42690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:47:22.149298Z","caller":"traceutil/trace.go:171","msg":"trace[100162462] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"1.078669568s","start":"2026-03-03T00:47:21.070608Z","end":"2026-03-03T00:47:22.149278Z","steps":["trace[100162462] 'process raft request' (duration: 1.076795511s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:22.149408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:21.070588Z","time spent":"1.078770371s","remote":"127.0.0.1:42504","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-03T00:47:22.150097Z","caller":"traceutil/trace.go:171","msg":"trace[42442728] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"1.079258284s","start":"2026-03-03T00:47:21.070817Z","end":"2026-03-03T00:47:22.150075Z","steps":["trace[42442728] 'process raft request' (duration: 1.078404032s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:22.150214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:21.070807Z","time spent":"1.079349406s","remote":"127.0.0.1:42624","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-03T00:47:22.150114Z","caller":"traceutil/trace.go:171","msg":"trace[590266753] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"1.078296059s","start":"2026-03-03T00:47:21.071806Z","end":"2026-03-03T00:47:22.150102Z","steps":["trace[590266753] 'process raft request' (duration: 1.078109605s)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:22.150358Z","caller":"traceutil/trace.go:171","msg":"trace[2558629] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"1.076617577s","start":"2026-03-03T00:47:21.073717Z","end":"2026-03-03T00:47:22.150334Z","steps":["trace[2558629] 'process raft request' (duration: 1.076311699s)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:22.150447Z","caller":"traceutil/trace.go:171","msg":"trace[550174504] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"1.072582415s","start":"2026-03-03T00:47:21.077851Z","end":"2026-03-03T00:47:22.150433Z","steps":["trace[550174504] 'process raft request' (duration: 1.072237736s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:22.150518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:21.077842Z","time spent":"1.072642616s","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T00:47:22.150524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:21.07371Z","time spent":"1.07673751s","remote":"127.0.0.1:42624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:47:22.150733Z","caller":"traceutil/trace.go:171","msg":"trace[726387416] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"1.072598045s","start":"2026-03-03T00:47:21.078124Z","end":"2026-03-03T00:47:22.150722Z","steps":["trace[726387416] 'process raft request' (duration: 1.07200207s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:22.150789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:21.078116Z","time spent":"1.072646276s","remote":"127.0.0.1:42528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-03T00:47:22.15038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:21.071795Z","time spent":"1.078542055s","remote":"127.0.0.1:42904","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:47:22.151116Z","caller":"traceutil/trace.go:171","msg":"trace[816422459] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"1.072270757s","start":"2026-03-03T00:47:21.078835Z","end":"2026-03-03T00:47:22.151106Z","steps":["trace[816422459] 'process raft request' (duration: 1.071740303s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:22.151179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:21.078828Z","time spent":"1.072315318s","remote":"127.0.0.1:42528","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:47:22.151286Z","caller":"traceutil/trace.go:171","msg":"trace[2085261056] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"1.072082682s","start":"2026-03-03T00:47:21.079184Z","end":"2026-03-03T00:47:22.151267Z","steps":["trace[2085261056] 'process raft request' (duration: 1.071754444s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:22.151392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:21.079177Z","time spent":"1.072164494s","remote":"127.0.0.1:42908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-03T00:47:30.254618Z","caller":"traceutil/trace.go:171","msg":"trace[1372455209] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"176.330858ms","start":"2026-03-03T00:47:30.078267Z","end":"2026-03-03T00:47:30.254598Z","steps":["trace[1372455209] 'read index received' (duration: 176.192235ms)","trace[1372455209] 'applied index is now lower than readState.Index' (duration: 137.853µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:30.254664Z","caller":"traceutil/trace.go:171","msg":"trace[2122160081] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"181.637124ms","start":"2026-03-03T00:47:30.073004Z","end":"2026-03-03T00:47:30.254641Z","steps":["trace[2122160081] 'process raft request' (duration: 181.460779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:30.254814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.538395ms","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-03T00:47:30.254848Z","caller":"traceutil/trace.go:171","msg":"trace[1302320416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:459; }","duration":"176.584956ms","start":"2026-03-03T00:47:30.078253Z","end":"2026-03-03T00:47:30.254838Z","steps":["trace[1302320416] 'agreement among raft nodes before linearized reading' (duration: 176.468823ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:42.964989Z","caller":"traceutil/trace.go:171","msg":"trace[221545765] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"633.488204ms","start":"2026-03-03T00:47:42.331476Z","end":"2026-03-03T00:47:42.964965Z","steps":["trace[221545765] 'process raft request' (duration: 633.33521ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:42.965147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:42.33146Z","time spent":"633.614377ms","remote":"127.0.0.1:42690","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-03T00:47:42.969232Z","caller":"traceutil/trace.go:171","msg":"trace[100412724] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:541; }","duration":"135.180402ms","start":"2026-03-03T00:47:42.834033Z","end":"2026-03-03T00:47:42.969214Z","steps":["trace[100412724] 'read index received' (duration: 130.770173ms)","trace[100412724] 'applied index is now lower than readState.Index' (duration: 4.409489ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:42.969298Z","caller":"traceutil/trace.go:171","msg":"trace[626263614] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"637.045581ms","start":"2026-03-03T00:47:42.332223Z","end":"2026-03-03T00:47:42.969269Z","steps":["trace[626263614] 'process raft request' (duration: 636.887707ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:42.969387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.294595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-03-03T00:47:42.969423Z","caller":"traceutil/trace.go:171","msg":"trace[904992126] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:521; }","duration":"135.412329ms","start":"2026-03-03T00:47:42.834Z","end":"2026-03-03T00:47:42.969412Z","steps":["trace[904992126] 'agreement among raft nodes before linearized reading' (duration: 135.295246ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:42.969452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:42.332206Z","time spent":"637.152043ms","remote":"127.0.0.1:42690","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-03T00:47:49.802543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.952895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-03T00:47:49.802622Z","caller":"traceutil/trace.go:171","msg":"trace[876068959] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"352.466667ms","start":"2026-03-03T00:47:49.450142Z","end":"2026-03-03T00:47:49.802609Z","steps":["trace[876068959] 'read index received' (duration: 126.359977ms)","trace[876068959] 'applied index is now lower than readState.Index' (duration: 226.10578ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:49.802768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.630841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vtdtx\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-03-03T00:47:49.802789Z","caller":"traceutil/trace.go:171","msg":"trace[2068947921] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vtdtx; range_end:; response_count:1; response_revision:564; }","duration":"352.685492ms","start":"2026-03-03T00:47:49.450097Z","end":"2026-03-03T00:47:49.802782Z","steps":["trace[2068947921] 'agreement among raft nodes before linearized reading' (duration: 352.562739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:49.802813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:49.450071Z","time spent":"352.735884ms","remote":"127.0.0.1:42624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-vtdtx\" "} {"level":"info","ts":"2026-03-03T00:47:49.802892Z","caller":"traceutil/trace.go:171","msg":"trace[1229732564] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"511.120532ms","start":"2026-03-03T00:47:49.291741Z","end":"2026-03-03T00:47:49.802862Z","steps":["trace[1229732564] 'process raft request' (duration: 284.773377ms)","trace[1229732564] 'compare' (duration: 225.875513ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:49.803043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:49.291722Z","time spent":"511.243026ms","remote":"127.0.0.1:42690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:47:50.725294Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582952282982083,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T00:47:51.225613Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9154582952282982083,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-03T00:47:51.358822Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.541851855s","expected-duration":"1s"} {"level":"info","ts":"2026-03-03T00:47:51.359241Z","caller":"traceutil/trace.go:171","msg":"trace[1369578440] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"1.13465346s","start":"2026-03-03T00:47:50.224565Z","end":"2026-03-03T00:47:51.359218Z","steps":["trace[1369578440] 'read index received' (duration: 1.134429394s)","trace[1369578440] 'applied index is now lower than readState.Index' (duration: 223.146µs)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:51.359342Z","caller":"traceutil/trace.go:171","msg":"trace[558664427] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.542501102s","start":"2026-03-03T00:47:49.81683Z","end":"2026-03-03T00:47:51.359331Z","steps":["trace[558664427] 'process raft request' (duration: 1.542210445s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:51.359448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:49.816807Z","time spent":"1.542568225s","remote":"127.0.0.1:42624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:47:51.359673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.826376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-03T00:47:51.359744Z","caller":"traceutil/trace.go:171","msg":"trace[1469223160] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:565; }","duration":"342.940499ms","start":"2026-03-03T00:47:51.016785Z","end":"2026-03-03T00:47:51.359726Z","steps":["trace[1469223160] 'agreement among raft nodes before linearized reading' (duration: 342.770734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:51.35978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:51.016757Z","time spent":"343.014561ms","remote":"127.0.0.1:42690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-03T00:47:51.359956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.670412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-03T00:47:51.359984Z","caller":"traceutil/trace.go:171","msg":"trace[90266396] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:565; }","duration":"310.732894ms","start":"2026-03-03T00:47:51.049244Z","end":"2026-03-03T00:47:51.359977Z","steps":["trace[90266396] 'agreement among raft nodes before linearized reading' (duration: 310.664082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:51.359992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135421921s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:47:51.360035Z","caller":"traceutil/trace.go:171","msg":"trace[1952034863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"1.135484943s","start":"2026-03-03T00:47:50.224535Z","end":"2026-03-03T00:47:51.36002Z","steps":["trace[1952034863] 'agreement among raft nodes before linearized reading' (duration: 1.135432172s)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:51.360078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:50.224522Z","time spent":"1.135547644s","remote":"127.0.0.1:42440","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-03T00:47:51.360004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:51.049229Z","time spent":"310.769425ms","remote":"127.0.0.1:42690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T00:47:52.572479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.420264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-03T00:47:52.572602Z","caller":"traceutil/trace.go:171","msg":"trace[794848909] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"765.585759ms","start":"2026-03-03T00:47:51.806996Z","end":"2026-03-03T00:47:52.572582Z","steps":["trace[794848909] 'range keys from in-memory index tree' (duration: 765.217369ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:52.572648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:51.806979Z","time spent":"765.658031ms","remote":"127.0.0.1:42690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-03T00:47:52.951928Z","caller":"traceutil/trace.go:171","msg":"trace[15549309] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"374.12001ms","start":"2026-03-03T00:47:52.577774Z","end":"2026-03-03T00:47:52.951894Z","steps":["trace[15549309] 'process raft request' (duration: 373.920935ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:52.952135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:52.577757Z","time spent":"374.269594ms","remote":"127.0.0.1:42690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-03T00:47:53.739209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.921979ms","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-03T00:47:53.739301Z","caller":"traceutil/trace.go:171","msg":"trace[720605984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:569; }","duration":"308.086823ms","start":"2026-03-03T00:47:53.431196Z","end":"2026-03-03T00:47:53.739283Z","steps":["trace[720605984] 'range keys from in-memory index tree' (duration: 307.717733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:53.739267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.008337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.36\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-03T00:47:53.739405Z","caller":"traceutil/trace.go:171","msg":"trace[1374097834] range","detail":"{range_begin:/registry/masterleases/162.253.55.36; range_end:; response_count:1; response_revision:569; }","duration":"721.210992ms","start":"2026-03-03T00:47:53.018164Z","end":"2026-03-03T00:47:53.739375Z","steps":["trace[1374097834] 'range keys from in-memory index tree' (duration: 720.854823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:53.739445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.001704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-03T00:47:53.739461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:53.01814Z","time spent":"721.311075ms","remote":"127.0.0.1:42466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.36\" "} {"level":"info","ts":"2026-03-03T00:47:53.739482Z","caller":"traceutil/trace.go:171","msg":"trace[1611547284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:569; }","duration":"307.096386ms","start":"2026-03-03T00:47:53.432376Z","end":"2026-03-03T00:47:53.739473Z","steps":["trace[1611547284] 'range keys from in-memory index tree' (duration: 306.900001ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:53.739543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:53.432357Z","time spent":"307.177049ms","remote":"127.0.0.1:42690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-03T00:47:53.739797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.041403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-03T00:47:53.739835Z","caller":"traceutil/trace.go:171","msg":"trace[1679313363] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"515.117265ms","start":"2026-03-03T00:47:53.224708Z","end":"2026-03-03T00:47:53.739826Z","steps":["trace[1679313363] 'range keys from in-memory index tree' (duration: 514.982271ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:53.73986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:53.22469Z","time spent":"515.163606ms","remote":"127.0.0.1:42440","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-03T00:47:53.739346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:53.43117Z","time spent":"308.166765ms","remote":"127.0.0.1:42690","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-03T00:47:53.740069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.281173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-03T00:47:53.740099Z","caller":"traceutil/trace.go:171","msg":"trace[5426179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:569; }","duration":"363.363376ms","start":"2026-03-03T00:47:53.376728Z","end":"2026-03-03T00:47:53.740092Z","steps":["trace[5426179] 'range keys from in-memory index tree' (duration: 363.15054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:53.740558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:53.3767Z","time spent":"363.414787ms","remote":"127.0.0.1:42690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-03T00:47:53.868463Z","caller":"traceutil/trace.go:171","msg":"trace[1484730907] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"116.333479ms","start":"2026-03-03T00:47:53.752096Z","end":"2026-03-03T00:47:53.86843Z","steps":["trace[1484730907] 'process raft request' (duration: 116.247937ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:53.868662Z","caller":"traceutil/trace.go:171","msg":"trace[429135866] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"116.71789ms","start":"2026-03-03T00:47:53.751931Z","end":"2026-03-03T00:47:53.868649Z","steps":["trace[429135866] 'process raft request' (duration: 94.365101ms)","trace[429135866] 'compare' (duration: 21.917707ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:53.868995Z","caller":"traceutil/trace.go:171","msg":"trace[712729743] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"116.532485ms","start":"2026-03-03T00:47:53.75233Z","end":"2026-03-03T00:47:53.868862Z","steps":["trace[712729743] 'process raft request' (duration: 116.054522ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:55.899184Z","caller":"traceutil/trace.go:171","msg":"trace[805163379] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"321.682577ms","start":"2026-03-03T00:47:55.577477Z","end":"2026-03-03T00:47:55.89916Z","steps":["trace[805163379] 'process raft request' (duration: 291.114248ms)","trace[805163379] 'compare' (duration: 30.474456ms)"],"step_count":2} {"level":"warn","ts":"2026-03-03T00:47:55.899561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-03T00:47:55.57745Z","time spent":"322.036106ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-03T00:47:55.902064Z","caller":"traceutil/trace.go:171","msg":"trace[511588192] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"105.765993ms","start":"2026-03-03T00:47:55.796282Z","end":"2026-03-03T00:47:55.902048Z","steps":["trace[511588192] 'read index received' (duration: 72.317757ms)","trace[511588192] 'applied index is now lower than readState.Index' (duration: 33.447326ms)"],"step_count":2} {"level":"info","ts":"2026-03-03T00:47:55.902167Z","caller":"traceutil/trace.go:171","msg":"trace[337756636] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"136.898457ms","start":"2026-03-03T00:47:55.765259Z","end":"2026-03-03T00:47:55.902158Z","steps":["trace[337756636] 'process raft request' (duration: 136.710572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-03T00:47:55.902367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.084392ms","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-03T00:47:55.902404Z","caller":"traceutil/trace.go:171","msg":"trace[1167788932] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:605; }","duration":"106.158394ms","start":"2026-03-03T00:47:55.796236Z","end":"2026-03-03T00:47:55.902394Z","steps":["trace[1167788932] 'agreement among raft nodes before linearized reading' (duration: 106.026251ms)"],"step_count":1} {"level":"info","ts":"2026-03-03T00:47:58.866919Z","caller":"traceutil/trace.go:171","msg":"trace[107024088] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"259.924291ms","start":"2026-03-03T00:47:58.606929Z","end":"2026-03-03T00:47:58.866853Z","steps":["trace[107024088] 'process raft request' (duration: 258.39077ms)"],"step_count":1}