{"level":"warn","ts":"2026-04-20T02:07:54.780838Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T02:07:54.780966Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-20T02:07:54.781045Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T02:07:54.781062Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-20T02:07:54.781083Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T02:07:54.781886Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-04-20T02:07:54.781992Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-20T02:07:54.787235Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.966121ms"} {"level":"info","ts":"2026-04-20T02:07:54.796668Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-04-20T02:07:54.79675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T02:07:54.796775Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-04-20T02:07:54.796786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T02:07:54.796799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-04-20T02:07:54.796858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-04-20T02:07:54.81232Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T02:07:54.818609Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T02:07:54.821228Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-20T02:07:54.823816Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T02:07:54.824038Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T02:07:54.824146Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T02:07:54.824322Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T02:07:54.824342Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T02:07:54.826294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-04-20T02:07:54.82655Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-20T02:07:54.827291Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T02:07:54.827378Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-20T02:07:54.827453Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-20T02:07:54.827739Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T02:07:54.827797Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T02:07:55.399096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T02:07:55.399166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T02:07:55.399201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-04-20T02:07:55.399216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-04-20T02:07:55.399223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-20T02:07:55.399233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-04-20T02:07:55.399243Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-20T02:07:55.416758Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T02:07:55.418742Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T02:07:55.418816Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T02:07:55.419888Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T02:07:55.420363Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T02:07:55.42054Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T02:07:55.420582Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T02:07:55.421433Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T02:07:55.42147Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T02:07:55.421953Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T02:07:55.427942Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-04-20T02:07:59.406437Z","caller":"traceutil/trace.go:171","msg":"trace[933969366] linearizableReadLoop","detail":"{readStateIndex:165; appliedIndex:164; }","duration":"104.874514ms","start":"2026-04-20T02:07:59.301533Z","end":"2026-04-20T02:07:59.406408Z","steps":["trace[933969366] 'read index received' (duration: 44.73807ms)","trace[933969366] 'applied index is now lower than readState.Index' (duration: 60.135644ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:07:59.406642Z","caller":"traceutil/trace.go:171","msg":"trace[763328537] transaction","detail":"{read_only:false; response_revision:161; number_of_response:1; }","duration":"122.260573ms","start":"2026-04-20T02:07:59.284321Z","end":"2026-04-20T02:07:59.406582Z","steps":["trace[763328537] 'process raft request' (duration: 62.027907ms)","trace[763328537] 'compare' (duration: 59.903089ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:07:59.406598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.042708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:07:59.406867Z","caller":"traceutil/trace.go:171","msg":"trace[2075431800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:161; }","duration":"105.308694ms","start":"2026-04-20T02:07:59.301498Z","end":"2026-04-20T02:07:59.406806Z","steps":["trace[2075431800] 'agreement among raft nodes before linearized reading' (duration: 105.027028ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:59.563814Z","caller":"traceutil/trace.go:171","msg":"trace[1493728057] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"144.422348ms","start":"2026-04-20T02:07:59.419353Z","end":"2026-04-20T02:07:59.563775Z","steps":["trace[1493728057] 'process raft request' (duration: 144.329596ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:07:59.56393Z","caller":"traceutil/trace.go:171","msg":"trace[301615290] transaction","detail":"{read_only:false; response_revision:164; number_of_response:1; }","duration":"144.532691ms","start":"2026-04-20T02:07:59.419349Z","end":"2026-04-20T02:07:59.563881Z","steps":["trace[301615290] 'process raft request' (duration: 124.084654ms)","trace[301615290] 'compare' (duration: 20.031518ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:07:59.719119Z","caller":"traceutil/trace.go:171","msg":"trace[570247099] transaction","detail":"{read_only:false; response_revision:166; number_of_response:1; }","duration":"148.55092ms","start":"2026-04-20T02:07:59.570535Z","end":"2026-04-20T02:07:59.719086Z","steps":["trace[570247099] 'process raft request' (duration: 144.141112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:07:59.925306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.970864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:07:59.92546Z","caller":"traceutil/trace.go:171","msg":"trace[1286580745] transaction","detail":"{read_only:false; response_revision:170; number_of_response:1; }","duration":"125.149348ms","start":"2026-04-20T02:07:59.80029Z","end":"2026-04-20T02:07:59.925439Z","steps":["trace[1286580745] 'process raft request' (duration: 20.506679ms)","trace[1286580745] 'compare' (duration: 103.79126ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:08:00.143143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.819691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:ttl-after-finished-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:00.143284Z","caller":"traceutil/trace.go:171","msg":"trace[25036807] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:ttl-after-finished-controller; range_end:; response_count:0; response_revision:177; }","duration":"116.994665ms","start":"2026-04-20T02:08:00.026262Z","end":"2026-04-20T02:08:00.143257Z","steps":["trace[25036807] 'range keys from in-memory index tree' (duration: 116.772161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:00.493315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.561373ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:08:00.493537Z","caller":"traceutil/trace.go:171","msg":"trace[356715994] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"280.175092ms","start":"2026-04-20T02:08:00.213316Z","end":"2026-04-20T02:08:00.493491Z","steps":["trace[356715994] 'process raft request' (duration: 164.340173ms)","trace[356715994] 'compare' (duration: 115.381569ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:00.493746Z","caller":"traceutil/trace.go:171","msg":"trace[634560107] linearizableReadLoop","detail":"{readStateIndex:184; appliedIndex:183; }","duration":"280.179073ms","start":"2026-04-20T02:08:00.213498Z","end":"2026-04-20T02:08:00.493677Z","steps":["trace[634560107] 'read index received' (duration: 164.186539ms)","trace[634560107] 'applied index is now lower than readState.Index' (duration: 115.987323ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:08:00.493998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.470639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-20T02:08:00.49401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.797794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:00.494058Z","caller":"traceutil/trace.go:171","msg":"trace[2033926240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:180; }","duration":"280.588321ms","start":"2026-04-20T02:08:00.213449Z","end":"2026-04-20T02:08:00.494037Z","steps":["trace[2033926240] 'agreement among raft nodes before linearized reading' (duration: 280.445108ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:00.494077Z","caller":"traceutil/trace.go:171","msg":"trace[562660036] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:180; }","duration":"147.936057ms","start":"2026-04-20T02:08:00.346125Z","end":"2026-04-20T02:08:00.494061Z","steps":["trace[562660036] 'agreement among raft nodes before linearized reading' (duration: 147.805954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:01.882386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.85779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:01.882506Z","caller":"traceutil/trace.go:171","msg":"trace[906330841] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:0; response_revision:226; }","duration":"176.032674ms","start":"2026-04-20T02:08:01.70645Z","end":"2026-04-20T02:08:01.882483Z","steps":["trace[906330841] 'range keys from in-memory index tree' (duration: 175.705817ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:02.08972Z","caller":"traceutil/trace.go:171","msg":"trace[559859016] linearizableReadLoop","detail":"{readStateIndex:234; appliedIndex:233; }","duration":"170.91588ms","start":"2026-04-20T02:08:01.918778Z","end":"2026-04-20T02:08:02.089694Z","steps":["trace[559859016] 'read index received' (duration: 170.849858ms)","trace[559859016] 'applied index is now lower than readState.Index' (duration: 65.292µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:02.089826Z","caller":"traceutil/trace.go:171","msg":"trace[327195796] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"201.070344ms","start":"2026-04-20T02:08:01.88871Z","end":"2026-04-20T02:08:02.089781Z","steps":["trace[327195796] 'process raft request' (duration: 200.862289ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:02.089877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.085055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T02:08:02.089918Z","caller":"traceutil/trace.go:171","msg":"trace[1248028638] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:228; }","duration":"171.145937ms","start":"2026-04-20T02:08:01.91876Z","end":"2026-04-20T02:08:02.089906Z","steps":["trace[1248028638] 'agreement among raft nodes before linearized reading' (duration: 171.033123ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:02.091599Z","caller":"traceutil/trace.go:171","msg":"trace[153233481] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"171.400821ms","start":"2026-04-20T02:08:01.920166Z","end":"2026-04-20T02:08:02.091567Z","steps":["trace[153233481] 'process raft request' (duration: 171.252858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:02.65029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.365594ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:08:02.650445Z","caller":"traceutil/trace.go:171","msg":"trace[44912911] linearizableReadLoop","detail":"{readStateIndex:236; appliedIndex:235; }","duration":"554.884812ms","start":"2026-04-20T02:08:02.095535Z","end":"2026-04-20T02:08:02.65042Z","steps":["trace[44912911] 'read index received' (duration: 177.031017ms)","trace[44912911] 'applied index is now lower than readState.Index' (duration: 377.852115ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:02.650514Z","caller":"traceutil/trace.go:171","msg":"trace[731379981] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"555.856844ms","start":"2026-04-20T02:08:02.094614Z","end":"2026-04-20T02:08:02.650471Z","steps":["trace[731379981] 'process raft request' (duration: 177.975958ms)","trace[731379981] 'compare' (duration: 377.20972ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:08:02.650577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.007265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"warn","ts":"2026-04-20T02:08:02.650642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.738245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3712"} {"level":"info","ts":"2026-04-20T02:08:02.650652Z","caller":"traceutil/trace.go:171","msg":"trace[146886105] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:230; }","duration":"555.116337ms","start":"2026-04-20T02:08:02.095516Z","end":"2026-04-20T02:08:02.650632Z","steps":["trace[146886105] 'agreement among raft nodes before linearized reading' (duration: 554.975014ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:02.650694Z","caller":"traceutil/trace.go:171","msg":"trace[813811725] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:230; }","duration":"514.788566ms","start":"2026-04-20T02:08:02.135887Z","end":"2026-04-20T02:08:02.650676Z","steps":["trace[813811725] 'agreement among raft nodes before linearized reading' (duration: 514.670083ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:02.650764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.532657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T02:08:02.650697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:02.095507Z","time spent":"555.180768ms","remote":"127.0.0.1:38298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" "} {"level":"warn","ts":"2026-04-20T02:08:02.650688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:02.094585Z","time spent":"556.018478ms","remote":"127.0.0.1:38380","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-04-20T02:08:02.650823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:02.135835Z","time spent":"514.97289ms","remote":"127.0.0.1:38268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3736,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-20T02:08:02.650867Z","caller":"traceutil/trace.go:171","msg":"trace[470931491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:230; }","duration":"104.68276ms","start":"2026-04-20T02:08:02.546158Z","end":"2026-04-20T02:08:02.650841Z","steps":["trace[470931491] 'agreement among raft nodes before linearized reading' (duration: 104.439175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:02.650934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.353361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-20T02:08:02.651069Z","caller":"traceutil/trace.go:171","msg":"trace[826512239] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:230; }","duration":"146.568046ms","start":"2026-04-20T02:08:02.504477Z","end":"2026-04-20T02:08:02.651045Z","steps":["trace[826512239] 'agreement among raft nodes before linearized reading' (duration: 146.31131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:03.164917Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189512765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:03.665586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189512765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:04.091846Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.435831532s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-20T02:08:06.698946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.606649823s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:08:06.699138Z","caller":"traceutil/trace.go:171","msg":"trace[1708684151] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"4.04320317s","start":"2026-04-20T02:08:02.655908Z","end":"2026-04-20T02:08:06.699111Z","steps":["trace[1708684151] 'process raft request' (duration: 1.436266511s)","trace[1708684151] 'compare' (duration: 2.606222083s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:08:06.699227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:02.655886Z","time spent":"4.043295693s","remote":"127.0.0.1:38380","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-04-20T02:08:07.088736Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.996544626s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T02:08:07.089384Z","caller":"traceutil/trace.go:171","msg":"trace[1998845546] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"4.433445742s","start":"2026-04-20T02:08:02.655903Z","end":"2026-04-20T02:08:07.089349Z","steps":["trace[1998845546] 'process raft request' (duration: 4.433129425s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:07.08958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:02.65588Z","time spent":"4.433573145s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:08:07.090376Z","caller":"traceutil/trace.go:171","msg":"trace[328037837] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"4.432384s","start":"2026-04-20T02:08:02.65791Z","end":"2026-04-20T02:08:07.090294Z","steps":["trace[328037837] 'process raft request' (duration: 4.431283855s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:07.090716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:02.657884Z","time spent":"4.432719408s","remote":"127.0.0.1:38268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4006,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:08:07.09098Z","caller":"traceutil/trace.go:171","msg":"trace[537252384] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"3.528405785s","start":"2026-04-20T02:08:03.562408Z","end":"2026-04-20T02:08:07.090814Z","steps":["trace[537252384] 'process raft request' (duration: 3.527244398s)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:07.091135Z","caller":"traceutil/trace.go:171","msg":"trace[984459699] linearizableReadLoop","detail":"{readStateIndex:240; appliedIndex:236; }","duration":"4.426479069s","start":"2026-04-20T02:08:02.663798Z","end":"2026-04-20T02:08:07.090277Z","steps":["trace[984459699] 'read index received' (duration: 1.428398277s)","trace[984459699] 'applied index is now lower than readState.Index' (duration: 2.998078322s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:08:07.091163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.427359098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:08:07.091149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:03.562371Z","time spent":"3.528706751s","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T02:08:07.091285Z","caller":"traceutil/trace.go:171","msg":"trace[1835415625] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:234; }","duration":"4.427514721s","start":"2026-04-20T02:08:02.663749Z","end":"2026-04-20T02:08:07.091263Z","steps":["trace[1835415625] 'agreement among raft nodes before linearized reading' (duration: 4.426635122s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:07.091517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:02.66373Z","time spent":"4.427778447s","remote":"127.0.0.1:38298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"info","ts":"2026-04-20T02:08:07.186821Z","caller":"traceutil/trace.go:171","msg":"trace[437146837] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"1.57667146s","start":"2026-04-20T02:08:05.610098Z","end":"2026-04-20T02:08:07.186769Z","steps":["trace[437146837] 'process raft request' (duration: 1.576595999s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:07.186869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.530716534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T02:08:07.186974Z","caller":"traceutil/trace.go:171","msg":"trace[990596123] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:237; }","duration":"2.530863417s","start":"2026-04-20T02:08:04.656083Z","end":"2026-04-20T02:08:07.186946Z","steps":["trace[990596123] 'agreement among raft nodes before linearized reading' (duration: 2.530680553s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:07.187019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:04.656055Z","time spent":"2.530955349s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T02:08:07.187036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:05.610059Z","time spent":"1.576881305s","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T02:08:07.187117Z","caller":"traceutil/trace.go:171","msg":"trace[1393110683] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"2.587166195s","start":"2026-04-20T02:08:04.599887Z","end":"2026-04-20T02:08:07.187054Z","steps":["trace[1393110683] 'process raft request' (duration: 2.586734706s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:07.187307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.554995729s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T02:08:07.187367Z","caller":"traceutil/trace.go:171","msg":"trace[1847507840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:237; }","duration":"3.555106632s","start":"2026-04-20T02:08:03.632244Z","end":"2026-04-20T02:08:07.187351Z","steps":["trace[1847507840] 'agreement among raft nodes before linearized reading' (duration: 3.554976729s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:07.187379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:04.599851Z","time spent":"2.587426021s","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T02:08:07.187409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:03.632222Z","time spent":"3.555176593s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-20T02:08:07.187158Z","caller":"traceutil/trace.go:171","msg":"trace[1246099038] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"2.950964828s","start":"2026-04-20T02:08:04.236139Z","end":"2026-04-20T02:08:07.187104Z","steps":["trace[1246099038] 'process raft request' (duration: 2.950295873s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:07.187484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.587305539s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:08:07.187579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:04.236115Z","time spent":"2.951434637s","remote":"127.0.0.1:38184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T02:08:07.187584Z","caller":"traceutil/trace.go:171","msg":"trace[896267998] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:237; }","duration":"2.587408621s","start":"2026-04-20T02:08:04.600161Z","end":"2026-04-20T02:08:07.18757Z","steps":["trace[896267998] 'agreement among raft nodes before linearized reading' (duration: 2.587284418s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:07.187629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.461457978s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5892"} {"level":"info","ts":"2026-04-20T02:08:07.187658Z","caller":"traceutil/trace.go:171","msg":"trace[594992020] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:237; }","duration":"3.4615293s","start":"2026-04-20T02:08:03.726121Z","end":"2026-04-20T02:08:07.18765Z","steps":["trace[594992020] 'agreement among raft nodes before linearized reading' (duration: 3.461469048s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:07.187682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:03.726097Z","time spent":"3.46157934s","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":5916,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"info","ts":"2026-04-20T02:08:07.452204Z","caller":"traceutil/trace.go:171","msg":"trace[1410002672] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"186.030559ms","start":"2026-04-20T02:08:07.266144Z","end":"2026-04-20T02:08:07.452175Z","steps":["trace[1410002672] 'process raft request' (duration: 185.964917ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:07.452245Z","caller":"traceutil/trace.go:171","msg":"trace[164240093] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"190.083219ms","start":"2026-04-20T02:08:07.262136Z","end":"2026-04-20T02:08:07.452219Z","steps":["trace[164240093] 'process raft request' (duration: 189.904165ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:07.452208Z","caller":"traceutil/trace.go:171","msg":"trace[1930100469] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"191.852428ms","start":"2026-04-20T02:08:07.260319Z","end":"2026-04-20T02:08:07.452171Z","steps":["trace[1930100469] 'process raft request' (duration: 185.113897ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:07.452975Z","caller":"traceutil/trace.go:171","msg":"trace[944543901] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"148.504359ms","start":"2026-04-20T02:08:07.304455Z","end":"2026-04-20T02:08:07.45296Z","steps":["trace[944543901] 'process raft request' (duration: 148.10154ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:07.672861Z","caller":"traceutil/trace.go:171","msg":"trace[1413697896] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"123.960671ms","start":"2026-04-20T02:08:07.548865Z","end":"2026-04-20T02:08:07.672826Z","steps":["trace[1413697896] 'process raft request' (duration: 123.736906ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:07.672974Z","caller":"traceutil/trace.go:171","msg":"trace[1302271459] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"122.433487ms","start":"2026-04-20T02:08:07.550512Z","end":"2026-04-20T02:08:07.672945Z","steps":["trace[1302271459] 'process raft request' (duration: 122.210111ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:07.67295Z","caller":"traceutil/trace.go:171","msg":"trace[1231990773] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"121.928955ms","start":"2026-04-20T02:08:07.550996Z","end":"2026-04-20T02:08:07.672925Z","steps":["trace[1231990773] 'process raft request' (duration: 121.852333ms)"],"step_count":1} 2026/04/20 02:08:08 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-04-20T02:08:10.231897Z","caller":"traceutil/trace.go:171","msg":"trace[1706751924] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:363; }","duration":"111.925791ms","start":"2026-04-20T02:08:10.119944Z","end":"2026-04-20T02:08:10.231869Z","steps":["trace[1706751924] 'read index received' (duration: 104.998666ms)","trace[1706751924] 'applied index is now lower than readState.Index' (duration: 6.925785ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:08:10.232089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.385698ms","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-04-20T02:08:10.232178Z","caller":"traceutil/trace.go:171","msg":"trace[495902112] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:349; }","duration":"123.533951ms","start":"2026-04-20T02:08:10.108629Z","end":"2026-04-20T02:08:10.232163Z","steps":["trace[495902112] 'agreement among raft nodes before linearized reading' (duration: 123.312476ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:10.232414Z","caller":"traceutil/trace.go:171","msg":"trace[2146370306] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"120.709028ms","start":"2026-04-20T02:08:10.111185Z","end":"2026-04-20T02:08:10.231894Z","steps":["trace[2146370306] 'process raft request' (duration: 113.896356ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:11.715263Z","caller":"traceutil/trace.go:171","msg":"trace[727699711] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"436.860955ms","start":"2026-04-20T02:08:11.278067Z","end":"2026-04-20T02:08:11.714928Z","steps":["trace[727699711] 'process raft request' (duration: 436.611849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:11.715422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:11.278044Z","time spent":"437.304585ms","remote":"127.0.0.1:38380","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-04-20T02:08:11.741293Z","caller":"traceutil/trace.go:171","msg":"trace[757160189] linearizableReadLoop","detail":"{readStateIndex:398; appliedIndex:396; }","duration":"442.00573ms","start":"2026-04-20T02:08:11.299258Z","end":"2026-04-20T02:08:11.741263Z","steps":["trace[757160189] 'read index received' (duration: 415.339793ms)","trace[757160189] 'applied index is now lower than readState.Index' (duration: 26.664687ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:11.741451Z","caller":"traceutil/trace.go:171","msg":"trace[942585439] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"461.904194ms","start":"2026-04-20T02:08:11.279514Z","end":"2026-04-20T02:08:11.741418Z","steps":["trace[942585439] 'process raft request' (duration: 461.581037ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:11.741525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.227235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-f6h54\" ","response":"range_response_count:1 size:4462"} {"level":"info","ts":"2026-04-20T02:08:11.741659Z","caller":"traceutil/trace.go:171","msg":"trace[1046645597] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-f6h54; range_end:; response_count:1; response_revision:381; }","duration":"442.387588ms","start":"2026-04-20T02:08:11.299247Z","end":"2026-04-20T02:08:11.741635Z","steps":["trace[1046645597] 'agreement among raft nodes before linearized reading' (duration: 442.140213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:11.741716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:11.299166Z","time spent":"442.536152ms","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":4486,"request content":"key:\"/registry/pods/kube-system/kube-proxy-f6h54\" "} {"level":"warn","ts":"2026-04-20T02:08:11.741845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:11.279484Z","time spent":"462.17966ms","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:08:12.116536Z","caller":"traceutil/trace.go:171","msg":"trace[250999595] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"160.801025ms","start":"2026-04-20T02:08:11.955693Z","end":"2026-04-20T02:08:12.116494Z","steps":["trace[250999595] 'process raft request' (duration: 160.56424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:12.811101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.284871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:08:12.811257Z","caller":"traceutil/trace.go:171","msg":"trace[1854901259] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"505.283424ms","start":"2026-04-20T02:08:12.305951Z","end":"2026-04-20T02:08:12.811234Z","steps":["trace[1854901259] 'read index received' (duration: 68.696596ms)","trace[1854901259] 'applied index is now lower than readState.Index' (duration: 436.585218ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:12.811385Z","caller":"traceutil/trace.go:171","msg":"trace[416718622] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"682.823132ms","start":"2026-04-20T02:08:12.128545Z","end":"2026-04-20T02:08:12.811368Z","steps":["trace[416718622] 'process raft request' (duration: 246.07563ms)","trace[416718622] 'compare' (duration: 436.128638ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:08:12.811479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:12.128518Z","time spent":"682.914784ms","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:08:12.811879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.900757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:12.812095Z","caller":"traceutil/trace.go:171","msg":"trace[1290428897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:385; }","duration":"506.168103ms","start":"2026-04-20T02:08:12.305893Z","end":"2026-04-20T02:08:12.812061Z","steps":["trace[1290428897] 'agreement among raft nodes before linearized reading' (duration: 505.864036ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:12.813059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:12.30587Z","time spent":"507.168946ms","remote":"127.0.0.1:38124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T02:08:12.959216Z","caller":"traceutil/trace.go:171","msg":"trace[574204885] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"130.109019ms","start":"2026-04-20T02:08:12.829068Z","end":"2026-04-20T02:08:12.959177Z","steps":["trace[574204885] 'process raft request' (duration: 127.813477ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:13.117959Z","caller":"traceutil/trace.go:171","msg":"trace[1813592108] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"147.097087ms","start":"2026-04-20T02:08:12.970823Z","end":"2026-04-20T02:08:13.11792Z","steps":["trace[1813592108] 'process raft request' (duration: 122.683351ms)","trace[1813592108] 'compare' (duration: 24.280083ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:17.125899Z","caller":"traceutil/trace.go:171","msg":"trace[2076734681] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"284.628782ms","start":"2026-04-20T02:08:16.841215Z","end":"2026-04-20T02:08:17.125844Z","steps":["trace[2076734681] 'process raft request' (duration: 284.431058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:19.388892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.820179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:08:19.389106Z","caller":"traceutil/trace.go:171","msg":"trace[97277001] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"162.303827ms","start":"2026-04-20T02:08:19.226751Z","end":"2026-04-20T02:08:19.389055Z","steps":["trace[97277001] 'process raft request' (duration: 42.203733ms)","trace[97277001] 'compare' (duration: 119.684705ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:08:22.363014Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189513149,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:22.864192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189513149,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:23.317376Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.45765729s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T02:08:23.319075Z","caller":"traceutil/trace.go:171","msg":"trace[805647885] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"1.457030466s","start":"2026-04-20T02:08:21.862015Z","end":"2026-04-20T02:08:23.319045Z","steps":["trace[805647885] 'read index received' (duration: 1.456701368s)","trace[805647885] 'applied index is now lower than readState.Index' (duration: 326.598µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:23.319197Z","caller":"traceutil/trace.go:171","msg":"trace[844407816] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"1.460940863s","start":"2026-04-20T02:08:21.858222Z","end":"2026-04-20T02:08:23.319163Z","steps":["trace[844407816] 'process raft request' (duration: 1.460644567s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:23.319363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.457317132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4378"} {"level":"warn","ts":"2026-04-20T02:08:23.319424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.455037122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T02:08:23.319462Z","caller":"traceutil/trace.go:171","msg":"trace[634207294] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:414; }","duration":"1.455182865s","start":"2026-04-20T02:08:21.864269Z","end":"2026-04-20T02:08:23.319451Z","steps":["trace[634207294] 'agreement among raft nodes before linearized reading' (duration: 1.454945849s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:23.319442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.981761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-20T02:08:23.31945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:21.85819Z","time spent":"1.461097137s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:08:23.319478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"941.663037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:23.319539Z","caller":"traceutil/trace.go:171","msg":"trace[807129849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:414; }","duration":"145.131654ms","start":"2026-04-20T02:08:23.174387Z","end":"2026-04-20T02:08:23.319518Z","steps":["trace[807129849] 'agreement among raft nodes before linearized reading' (duration: 144.892859ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:23.319596Z","caller":"traceutil/trace.go:171","msg":"trace[680635173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:414; }","duration":"941.82923ms","start":"2026-04-20T02:08:22.377735Z","end":"2026-04-20T02:08:23.319565Z","steps":["trace[680635173] 'agreement among raft nodes before linearized reading' (duration: 941.617235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:23.319655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:22.377714Z","time spent":"941.930753ms","remote":"127.0.0.1:38110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T02:08:23.319467Z","caller":"traceutil/trace.go:171","msg":"trace[1286880550] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:414; }","duration":"1.457469086s","start":"2026-04-20T02:08:21.861977Z","end":"2026-04-20T02:08:23.319446Z","steps":["trace[1286880550] 'agreement among raft nodes before linearized reading' (duration: 1.4572419s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:23.319843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:21.861966Z","time spent":"1.457865464s","remote":"127.0.0.1:38268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4402,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-20T02:08:23.319495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:21.864262Z","time spent":"1.455226295s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T02:08:24.574513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.154606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:08:24.574625Z","caller":"traceutil/trace.go:171","msg":"trace[1750621921] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"195.684624ms","start":"2026-04-20T02:08:24.378923Z","end":"2026-04-20T02:08:24.574608Z","steps":["trace[1750621921] 'read index received' (duration: 62.282411ms)","trace[1750621921] 'applied index is now lower than readState.Index' (duration: 133.401003ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:08:24.574719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.842998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:24.574687Z","caller":"traceutil/trace.go:171","msg":"trace[77036806] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"306.007359ms","start":"2026-04-20T02:08:24.268632Z","end":"2026-04-20T02:08:24.574639Z","steps":["trace[77036806] 'process raft request' (duration: 172.632688ms)","trace[77036806] 'compare' (duration: 132.956702ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:24.574756Z","caller":"traceutil/trace.go:171","msg":"trace[425547146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:419; }","duration":"195.916459ms","start":"2026-04-20T02:08:24.378829Z","end":"2026-04-20T02:08:24.574745Z","steps":["trace[425547146] 'agreement among raft nodes before linearized reading' (duration: 195.823427ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:24.574867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:24.268606Z","time spent":"306.174453ms","remote":"127.0.0.1:38602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T02:08:24.795125Z","caller":"traceutil/trace.go:171","msg":"trace[1951785112] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"213.347829ms","start":"2026-04-20T02:08:24.581748Z","end":"2026-04-20T02:08:24.795096Z","steps":["trace[1951785112] 'process raft request' (duration: 213.272967ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:24.795156Z","caller":"traceutil/trace.go:171","msg":"trace[332825641] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"216.241724ms","start":"2026-04-20T02:08:24.578865Z","end":"2026-04-20T02:08:24.795107Z","steps":["trace[332825641] 'process raft request' (duration: 181.395715ms)","trace[332825641] 'compare' (duration: 34.514241ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:24.795242Z","caller":"traceutil/trace.go:171","msg":"trace[1657766740] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"215.335303ms","start":"2026-04-20T02:08:24.579832Z","end":"2026-04-20T02:08:24.795167Z","steps":["trace[1657766740] 'process raft request' (duration: 215.106228ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:28.308765Z","caller":"traceutil/trace.go:171","msg":"trace[1872788271] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"127.218114ms","start":"2026-04-20T02:08:28.181519Z","end":"2026-04-20T02:08:28.308737Z","steps":["trace[1872788271] 'process raft request' (duration: 127.04579ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:29.481745Z","caller":"traceutil/trace.go:171","msg":"trace[2038026636] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:477; }","duration":"117.721631ms","start":"2026-04-20T02:08:29.363999Z","end":"2026-04-20T02:08:29.481721Z","steps":["trace[2038026636] 'read index received' (duration: 117.511856ms)","trace[2038026636] 'applied index is now lower than readState.Index' (duration: 209.045µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:29.48185Z","caller":"traceutil/trace.go:171","msg":"trace[300687592] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"121.330932ms","start":"2026-04-20T02:08:29.360488Z","end":"2026-04-20T02:08:29.481819Z","steps":["trace[300687592] 'process raft request' (duration: 121.092976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.482076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.055078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T02:08:29.48213Z","caller":"traceutil/trace.go:171","msg":"trace[983085859] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:457; }","duration":"118.162592ms","start":"2026-04-20T02:08:29.363954Z","end":"2026-04-20T02:08:29.482116Z","steps":["trace[983085859] 'agreement among raft nodes before linearized reading' (duration: 117.954567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.482184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.096429ms","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-04-20T02:08:29.482268Z","caller":"traceutil/trace.go:171","msg":"trace[2014422664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:457; }","duration":"118.245463ms","start":"2026-04-20T02:08:29.364003Z","end":"2026-04-20T02:08:29.482248Z","steps":["trace[2014422664] 'agreement among raft nodes before linearized reading' (duration: 118.059079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:29.482273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.769032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:29.482312Z","caller":"traceutil/trace.go:171","msg":"trace[1310602810] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:457; }","duration":"104.834424ms","start":"2026-04-20T02:08:29.377468Z","end":"2026-04-20T02:08:29.482302Z","steps":["trace[1310602810] 'agreement among raft nodes before linearized reading' (duration: 104.774553ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:29.632773Z","caller":"traceutil/trace.go:171","msg":"trace[1863759039] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"146.682418ms","start":"2026-04-20T02:08:29.48606Z","end":"2026-04-20T02:08:29.632743Z","steps":["trace[1863759039] 'process raft request' (duration: 146.525084ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:29.641321Z","caller":"traceutil/trace.go:171","msg":"trace[2112179638] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"155.200139ms","start":"2026-04-20T02:08:29.486106Z","end":"2026-04-20T02:08:29.641306Z","steps":["trace[2112179638] 'process raft request' (duration: 155.053415ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:29.641539Z","caller":"traceutil/trace.go:171","msg":"trace[127491196] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"155.324672ms","start":"2026-04-20T02:08:29.486203Z","end":"2026-04-20T02:08:29.641527Z","steps":["trace[127491196] 'process raft request' (duration: 155.049455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:30.97545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.383116ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:08:30.975646Z","caller":"traceutil/trace.go:171","msg":"trace[371721004] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"125.573167ms","start":"2026-04-20T02:08:30.850045Z","end":"2026-04-20T02:08:30.975618Z","steps":["trace[371721004] 'process raft request' (duration: 19.881415ms)","trace[371721004] 'compare' (duration: 105.175441ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:31.597684Z","caller":"traceutil/trace.go:171","msg":"trace[2018562345] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"105.11524ms","start":"2026-04-20T02:08:31.492543Z","end":"2026-04-20T02:08:31.597659Z","steps":["trace[2018562345] 'process raft request' (duration: 104.927755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:32.267535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.781813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:08:32.26766Z","caller":"traceutil/trace.go:171","msg":"trace[872268887] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"621.475771ms","start":"2026-04-20T02:08:31.646163Z","end":"2026-04-20T02:08:32.267639Z","steps":["trace[872268887] 'read index received' (duration: 184.404692ms)","trace[872268887] 'applied index is now lower than readState.Index' (duration: 437.069659ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:32.267697Z","caller":"traceutil/trace.go:171","msg":"trace[2103673058] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"626.373751ms","start":"2026-04-20T02:08:31.641294Z","end":"2026-04-20T02:08:32.267668Z","steps":["trace[2103673058] 'process raft request' (duration: 189.360733ms)","trace[2103673058] 'compare' (duration: 436.65066ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:08:32.267792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.622224ms","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-04-20T02:08:32.267838Z","caller":"traceutil/trace.go:171","msg":"trace[1965274089] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:463; }","duration":"621.724976ms","start":"2026-04-20T02:08:31.6461Z","end":"2026-04-20T02:08:32.267825Z","steps":["trace[1965274089] 'agreement among raft nodes before linearized reading' (duration: 621.644285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:32.267852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:31.641278Z","time spent":"626.481453ms","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:08:32.267842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.606703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T02:08:32.267886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:31.64609Z","time spent":"621.780647ms","remote":"127.0.0.1:38380","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-04-20T02:08:32.267905Z","caller":"traceutil/trace.go:171","msg":"trace[1511143916] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:463; }","duration":"621.698516ms","start":"2026-04-20T02:08:31.646193Z","end":"2026-04-20T02:08:32.267892Z","steps":["trace[1511143916] 'agreement among raft nodes before linearized reading' (duration: 621.591853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:32.267938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:31.64618Z","time spent":"621.750847ms","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T02:08:33.659021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.396309ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:08:33.659171Z","caller":"traceutil/trace.go:171","msg":"trace[748791191] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"1.387197275s","start":"2026-04-20T02:08:32.271951Z","end":"2026-04-20T02:08:33.659148Z","steps":["trace[748791191] 'process raft request' (duration: 1.118391357s)","trace[748791191] 'compare' (duration: 267.869517ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:08:33.659244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:32.271928Z","time spent":"1.387280697s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:08:33.661041Z","caller":"traceutil/trace.go:171","msg":"trace[1843341375] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:485; }","duration":"285.527362ms","start":"2026-04-20T02:08:33.375499Z","end":"2026-04-20T02:08:33.661026Z","steps":["trace[1843341375] 'read index received' (duration: 14.940055ms)","trace[1843341375] 'applied index is now lower than readState.Index' (duration: 270.586327ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:33.66112Z","caller":"traceutil/trace.go:171","msg":"trace[1869036642] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"1.387318118s","start":"2026-04-20T02:08:32.273768Z","end":"2026-04-20T02:08:33.661086Z","steps":["trace[1869036642] 'process raft request' (duration: 1.387161574s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:33.661162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.662745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:33.661198Z","caller":"traceutil/trace.go:171","msg":"trace[52808107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:465; }","duration":"285.740897ms","start":"2026-04-20T02:08:33.375448Z","end":"2026-04-20T02:08:33.661189Z","steps":["trace[52808107] 'agreement among raft nodes before linearized reading' (duration: 285.676066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:33.661372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:32.273743Z","time spent":"1.387451151s","remote":"127.0.0.1:38380","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-04-20T02:08:40.858995Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189513368,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:41.359937Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189513368,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:41.610386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475443739s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:08:41.610702Z","caller":"traceutil/trace.go:171","msg":"trace[728259157] transaction","detail":"{read_only:false; number_of_response:1; response_revision:530; }","duration":"1.866631232s","start":"2026-04-20T02:08:39.744049Z","end":"2026-04-20T02:08:41.61068Z","steps":["trace[728259157] 'process raft request' (duration: 1.866495499s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:41.610782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:39.744023Z","time spent":"1.866712614s","remote":"127.0.0.1:38142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10113,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:08:41.610853Z","caller":"traceutil/trace.go:171","msg":"trace[629462562] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.870255982s","start":"2026-04-20T02:08:39.74055Z","end":"2026-04-20T02:08:41.610806Z","steps":["trace[629462562] 'process raft request' (duration: 394.308782ms)","trace[629462562] 'compare' (duration: 1.475251925s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:08:41.611056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:39.740538Z","time spent":"1.870414747s","remote":"127.0.0.1:38142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T02:08:41.611213Z","caller":"traceutil/trace.go:171","msg":"trace[1500524026] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.870313634s","start":"2026-04-20T02:08:39.740801Z","end":"2026-04-20T02:08:41.611115Z","steps":["trace[1500524026] 'process raft request' (duration: 1.86968839s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:41.611458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:39.740792Z","time spent":"1.870557559s","remote":"127.0.0.1:38646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T02:08:41.860389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189513368,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:08:42.233741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.677857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:08:42.234035Z","caller":"traceutil/trace.go:171","msg":"trace[1318249627] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"2.487024639s","start":"2026-04-20T02:08:39.746964Z","end":"2026-04-20T02:08:42.233989Z","steps":["trace[1318249627] 'process raft request' (duration: 2.362009384s)","trace[1318249627] 'compare' (duration: 124.511234ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:08:42.234254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:39.746944Z","time spent":"2.487180283s","remote":"127.0.0.1:38142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:08:42.234635Z","caller":"traceutil/trace.go:171","msg":"trace[1467695721] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"2.487574661s","start":"2026-04-20T02:08:39.747047Z","end":"2026-04-20T02:08:42.234622Z","steps":["trace[1467695721] 'process raft request' (duration: 2.486833314s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:42.23471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:39.746989Z","time spent":"2.487684344s","remote":"127.0.0.1:38142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:08:42.234871Z","caller":"traceutil/trace.go:171","msg":"trace[1155743127] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"2.485346171s","start":"2026-04-20T02:08:39.749457Z","end":"2026-04-20T02:08:42.234803Z","steps":["trace[1155743127] 'process raft request' (duration: 2.484986173s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:42.235105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:39.749441Z","time spent":"2.485548196s","remote":"127.0.0.1:38380","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-04-20T02:08:42.23639Z","caller":"traceutil/trace.go:171","msg":"trace[1711017] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"2.486885276s","start":"2026-04-20T02:08:39.749484Z","end":"2026-04-20T02:08:42.23637Z","steps":["trace[1711017] 'process raft request' (duration: 2.486229601s)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:42.236498Z","caller":"traceutil/trace.go:171","msg":"trace[1470419154] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"2.474424877s","start":"2026-04-20T02:08:39.762046Z","end":"2026-04-20T02:08:42.236471Z","steps":["trace[1470419154] 'process raft request' (duration: 2.473952716s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:42.236536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:39.749416Z","time spent":"2.487042649s","remote":"127.0.0.1:38142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:08:42.236568Z","caller":"traceutil/trace.go:171","msg":"trace[223966782] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"2.313346067s","start":"2026-04-20T02:08:39.923169Z","end":"2026-04-20T02:08:42.236515Z","steps":["trace[223966782] 'process raft request' (duration: 2.313159693s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:42.236635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:39.762023Z","time spent":"2.47454043s","remote":"127.0.0.1:38142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:08:42.236783Z","caller":"traceutil/trace.go:171","msg":"trace[1105075061] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"2.454260046s","start":"2026-04-20T02:08:39.78249Z","end":"2026-04-20T02:08:42.23675Z","steps":["trace[1105075061] 'process raft request' (duration: 2.453716513s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:42.236858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:39.923144Z","time spent":"2.313524211s","remote":"127.0.0.1:38142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T02:08:42.236913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:39.782455Z","time spent":"2.45441198s","remote":"127.0.0.1:38142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T02:08:42.289475Z","caller":"traceutil/trace.go:171","msg":"trace[803757151] linearizableReadLoop","detail":"{readStateIndex:564; appliedIndex:555; }","duration":"1.931105123s","start":"2026-04-20T02:08:40.358309Z","end":"2026-04-20T02:08:42.289414Z","steps":["trace[803757151] 'read index received' (duration: 1.750681491s)","trace[803757151] 'applied index is now lower than readState.Index' (duration: 180.422312ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:42.289829Z","caller":"traceutil/trace.go:171","msg":"trace[1398330684] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"653.376544ms","start":"2026-04-20T02:08:41.636412Z","end":"2026-04-20T02:08:42.289789Z","steps":["trace[1398330684] 'process raft request' (duration: 652.884102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:42.290046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:41.636365Z","time spent":"653.600199ms","remote":"127.0.0.1:38142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:08:42.290148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.931830669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T02:08:42.290199Z","caller":"traceutil/trace.go:171","msg":"trace[1732880571] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"667.112991ms","start":"2026-04-20T02:08:41.623078Z","end":"2026-04-20T02:08:42.290191Z","steps":["trace[1732880571] 'process raft request' (duration: 665.977595ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:42.290207Z","caller":"traceutil/trace.go:171","msg":"trace[1591746705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:539; }","duration":"1.932021874s","start":"2026-04-20T02:08:40.358171Z","end":"2026-04-20T02:08:42.290192Z","steps":["trace[1591746705] 'agreement among raft nodes before linearized reading' (duration: 1.931903931s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:42.290233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:41.623057Z","time spent":"667.158461ms","remote":"127.0.0.1:38142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:08:42.290255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:40.35814Z","time spent":"1.932102446s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T02:08:42.366806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.989795694s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:42.366882Z","caller":"traceutil/trace.go:171","msg":"trace[1694861334] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"1.989929877s","start":"2026-04-20T02:08:40.376935Z","end":"2026-04-20T02:08:42.366865Z","steps":["trace[1694861334] 'agreement among raft nodes before linearized reading' (duration: 1.989798954s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:42.366875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.125422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T02:08:42.366957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.787803ms","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-04-20T02:08:42.36697Z","caller":"traceutil/trace.go:171","msg":"trace[1779554081] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"616.285195ms","start":"2026-04-20T02:08:41.750664Z","end":"2026-04-20T02:08:42.366949Z","steps":["trace[1779554081] 'agreement among raft nodes before linearized reading' (duration: 616.103951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:42.367013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.664442613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-20T02:08:42.367024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:41.750644Z","time spent":"616.368036ms","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T02:08:42.367034Z","caller":"traceutil/trace.go:171","msg":"trace[88767429] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:540; }","duration":"1.664495054s","start":"2026-04-20T02:08:40.702535Z","end":"2026-04-20T02:08:42.36703Z","steps":["trace[88767429] 'agreement among raft nodes before linearized reading' (duration: 1.664460693s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:42.367057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:40.702518Z","time spent":"1.664535555s","remote":"127.0.0.1:38134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"info","ts":"2026-04-20T02:08:42.366982Z","caller":"traceutil/trace.go:171","msg":"trace[1253239807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"636.854375ms","start":"2026-04-20T02:08:41.730122Z","end":"2026-04-20T02:08:42.366976Z","steps":["trace[1253239807] 'agreement among raft nodes before linearized reading' (duration: 636.811814ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:42.366916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:40.376905Z","time spent":"1.990004899s","remote":"127.0.0.1:38110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T02:08:42.367342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:41.730098Z","time spent":"637.12301ms","remote":"127.0.0.1:38380","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":"warn","ts":"2026-04-20T02:08:49.882759Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189513531,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T02:08:49.934254Z","caller":"traceutil/trace.go:171","msg":"trace[631252076] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"928.370288ms","start":"2026-04-20T02:08:49.00585Z","end":"2026-04-20T02:08:49.93422Z","steps":["trace[631252076] 'process raft request' (duration: 927.654892ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:49.934425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:49.005817Z","time spent":"928.516791ms","remote":"127.0.0.1:38206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:08:51.378993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000083425s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-20T02:08:51.3791Z","caller":"traceutil/trace.go:171","msg":"trace[1953364926] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000240037s","start":"2026-04-20T02:08:49.378835Z","end":"2026-04-20T02:08:51.379075Z","steps":["trace[1953364926] 'agreement among raft nodes before linearized reading' (duration: 2.000116705s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:51.379155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:49.378802Z","time spent":"2.00034077s","remote":"127.0.0.1:38110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/20 02:08:51 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T02:08:52.199045Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.265811202s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T02:08:52.199412Z","caller":"traceutil/trace.go:171","msg":"trace[1250935535] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:651; }","duration":"2.817583888s","start":"2026-04-20T02:08:49.38181Z","end":"2026-04-20T02:08:52.199394Z","steps":["trace[1250935535] 'read index received' (duration: 551.52796ms)","trace[1250935535] 'applied index is now lower than readState.Index' (duration: 2.266055198s)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:52.199477Z","caller":"traceutil/trace.go:171","msg":"trace[1136775917] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"3.134283479s","start":"2026-04-20T02:08:49.065155Z","end":"2026-04-20T02:08:52.199438Z","steps":["trace[1136775917] 'process raft request' (duration: 3.134098845s)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:52.199501Z","caller":"traceutil/trace.go:171","msg":"trace[1069790470] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"3.069791815s","start":"2026-04-20T02:08:49.129687Z","end":"2026-04-20T02:08:52.199479Z","steps":["trace[1069790470] 'process raft request' (duration: 3.069680202s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:52.199681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:49.065123Z","time spent":"3.134449382s","remote":"127.0.0.1:38380","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-04-20T02:08:52.199772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:49.129655Z","time spent":"3.07004207s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:08:53.379806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000451122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-20T02:08:53.379881Z","caller":"traceutil/trace.go:171","msg":"trace[577680135] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000571834s","start":"2026-04-20T02:08:51.379292Z","end":"2026-04-20T02:08:53.379864Z","steps":["trace[577680135] 'agreement among raft nodes before linearized reading' (duration: 2.000480772s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:53.379924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:51.379277Z","time spent":"2.000637255s","remote":"127.0.0.1:38124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/20 02:08:53 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T02:08:55.388279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000088813s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-20T02:08:55.388366Z","caller":"traceutil/trace.go:171","msg":"trace[1347436785] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000233957s","start":"2026-04-20T02:08:53.388114Z","end":"2026-04-20T02:08:55.388348Z","steps":["trace[1347436785] 'agreement among raft nodes before linearized reading' (duration: 2.000126964s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:55.388416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:53.3881Z","time spent":"2.000303938s","remote":"127.0.0.1:38110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/20 02:08:55 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T02:08:55.408575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999477904s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T02:08:55.408684Z","caller":"traceutil/trace.go:171","msg":"trace[1477692885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999642428s","start":"2026-04-20T02:08:50.409015Z","end":"2026-04-20T02:08:55.408658Z","steps":["trace[1477692885] 'agreement among raft nodes before linearized reading' (duration: 4.999510715s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:55.408758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:50.408994Z","time spent":"4.99974826s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/04/20 02:08:55 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T02:08:55.423948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999594347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-20T02:08:55.424057Z","caller":"traceutil/trace.go:171","msg":"trace[459465462] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.9997641s","start":"2026-04-20T02:08:50.424274Z","end":"2026-04-20T02:08:55.424038Z","steps":["trace[459465462] 'agreement among raft nodes before linearized reading' (duration: 4.999635298s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:55.424111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:50.424252Z","time spent":"4.999847602s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/04/20 02:08:55 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T02:08:56.994407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:49.99408Z","time spent":"7.000318774s","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-04-20T02:08:57.088904Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.705862573s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-20T02:08:57.389212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000598626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-04-20T02:08:57.389332Z","caller":"traceutil/trace.go:171","msg":"trace[795765907] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00077036s","start":"2026-04-20T02:08:55.388547Z","end":"2026-04-20T02:08:57.389318Z","steps":["trace[795765907] 'agreement among raft nodes before linearized reading' (duration: 2.000634307s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:57.389364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:55.388524Z","time spent":"2.000832932s","remote":"127.0.0.1:38124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/04/20 02:08:57 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T02:08:59.05864Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.655987535s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-20T02:08:59.393419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.215111556s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:59.393556Z","caller":"traceutil/trace.go:171","msg":"trace[665965733] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:627; }","duration":"4.215260739s","start":"2026-04-20T02:08:55.178275Z","end":"2026-04-20T02:08:59.393536Z","steps":["trace[665965733] 'range keys from in-memory index tree' (duration: 4.215095176s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:59.393604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.036572019s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:08:59.393891Z","caller":"traceutil/trace.go:171","msg":"trace[875200401] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"7.194388988s","start":"2026-04-20T02:08:52.199464Z","end":"2026-04-20T02:08:59.393853Z","steps":["trace[875200401] 'read index received' (duration: 157.43024ms)","trace[875200401] 'applied index is now lower than readState.Index' (duration: 7.036952898s)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:08:59.393972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.111117918s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-20T02:08:59.393962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999929221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T02:08:59.394007Z","caller":"traceutil/trace.go:171","msg":"trace[1920180292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"9.11120195s","start":"2026-04-20T02:08:50.282796Z","end":"2026-04-20T02:08:59.393998Z","steps":["trace[1920180292] 'agreement among raft nodes before linearized reading' (duration: 9.111077587s)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:59.394034Z","caller":"traceutil/trace.go:171","msg":"trace[256506021] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000003822s","start":"2026-04-20T02:08:57.394006Z","end":"2026-04-20T02:08:59.39401Z","steps":["trace[256506021] 'agreement among raft nodes before linearized reading' (duration: 1.999927071s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:59.394035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:50.282772Z","time spent":"9.111255272s","remote":"127.0.0.1:38380","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-04-20T02:08:59.394063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.970129684s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T02:08:59.394081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:57.393964Z","time spent":"2.000106955s","remote":"127.0.0.1:38110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T02:08:59.394107Z","caller":"traceutil/trace.go:171","msg":"trace[987161030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"8.970212426s","start":"2026-04-20T02:08:50.423883Z","end":"2026-04-20T02:08:59.394095Z","steps":["trace[987161030] 'agreement among raft nodes before linearized reading' (duration: 8.969987011s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:59.394143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:50.423866Z","time spent":"8.970266648s","remote":"127.0.0.1:38380","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":"warn","ts":"2026-04-20T02:08:59.394204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.976393194s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gc6rf\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-04-20T02:08:59.394232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.690898287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-20T02:08:59.394279Z","caller":"traceutil/trace.go:171","msg":"trace[2077881199] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"6.011322336s","start":"2026-04-20T02:08:53.38294Z","end":"2026-04-20T02:08:59.394262Z","steps":["trace[2077881199] 'process raft request' (duration: 6.01104578s)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:59.394291Z","caller":"traceutil/trace.go:171","msg":"trace[696675929] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gc6rf; range_end:; response_count:1; response_revision:628; }","duration":"9.976558658s","start":"2026-04-20T02:08:49.417714Z","end":"2026-04-20T02:08:59.394273Z","steps":["trace[696675929] 'agreement among raft nodes before linearized reading' (duration: 9.976256172s)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:59.394306Z","caller":"traceutil/trace.go:171","msg":"trace[143028913] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:628; }","duration":"8.69101582s","start":"2026-04-20T02:08:50.703272Z","end":"2026-04-20T02:08:59.394288Z","steps":["trace[143028913] 'agreement among raft nodes before linearized reading' (duration: 8.6905997s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:59.394343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:50.703255Z","time spent":"8.691080691s","remote":"127.0.0.1:38134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.53\" "} {"level":"warn","ts":"2026-04-20T02:08:59.394347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:49.417691Z","time spent":"9.976646231s","remote":"127.0.0.1:38284","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-gc6rf\" "} {"level":"warn","ts":"2026-04-20T02:08:59.394386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:53.38292Z","time spent":"6.011406409s","remote":"127.0.0.1:38184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T02:08:59.412652Z","caller":"traceutil/trace.go:171","msg":"trace[1447241535] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.987863543s","start":"2026-04-20T02:08:57.424768Z","end":"2026-04-20T02:08:59.412632Z","steps":["trace[1447241535] 'process raft request' (duration: 1.987373982s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:59.41281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:57.424739Z","time spent":"1.987993975s","remote":"127.0.0.1:38206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036400,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:08:59.412982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.986816639s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T02:08:59.413069Z","caller":"traceutil/trace.go:171","msg":"trace[683167380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"1.986932251s","start":"2026-04-20T02:08:57.426116Z","end":"2026-04-20T02:08:59.413048Z","steps":["trace[683167380] 'agreement among raft nodes before linearized reading' (duration: 1.986489441s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:59.413123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:57.426105Z","time spent":"1.987005513s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T02:08:59.413121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001982946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T02:08:59.413782Z","caller":"traceutil/trace.go:171","msg":"trace[1118656905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"2.002245962s","start":"2026-04-20T02:08:57.411068Z","end":"2026-04-20T02:08:59.413314Z","steps":["trace[1118656905] 'agreement among raft nodes before linearized reading' (duration: 2.001548297s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:59.413986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:57.41104Z","time spent":"2.002811625s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T02:08:59.414295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.662813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T02:08:59.415254Z","caller":"traceutil/trace.go:171","msg":"trace[1843228123] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:631; }","duration":"502.648945ms","start":"2026-04-20T02:08:58.912556Z","end":"2026-04-20T02:08:59.415205Z","steps":["trace[1843228123] 'agreement among raft nodes before linearized reading' (duration: 500.054937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:59.415419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:08:58.912532Z","time spent":"502.833529ms","remote":"127.0.0.1:38298","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-04-20T02:08:59.617797Z","caller":"traceutil/trace.go:171","msg":"trace[1831038946] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"123.7288ms","start":"2026-04-20T02:08:59.49404Z","end":"2026-04-20T02:08:59.617769Z","steps":["trace[1831038946] 'read index received' (duration: 114.516695ms)","trace[1831038946] 'applied index is now lower than readState.Index' (duration: 9.211105ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:08:59.617845Z","caller":"traceutil/trace.go:171","msg":"trace[1554803239] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"125.000168ms","start":"2026-04-20T02:08:59.492805Z","end":"2026-04-20T02:08:59.617805Z","steps":["trace[1554803239] 'process raft request' (duration: 115.639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:59.618248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.145869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a7ee9c5e002394\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-04-20T02:08:59.618378Z","caller":"traceutil/trace.go:171","msg":"trace[464027178] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18a7ee9c5e002394; range_end:; response_count:1; response_revision:638; }","duration":"124.327593ms","start":"2026-04-20T02:08:59.494018Z","end":"2026-04-20T02:08:59.618346Z","steps":["trace[464027178] 'agreement among raft nodes before linearized reading' (duration: 123.898584ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:08:59.694716Z","caller":"traceutil/trace.go:171","msg":"trace[300990443] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"196.838446ms","start":"2026-04-20T02:08:59.497846Z","end":"2026-04-20T02:08:59.694684Z","steps":["trace[300990443] 'process raft request' (duration: 196.676713ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:08:59.694942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.173937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:08:59.695008Z","caller":"traceutil/trace.go:171","msg":"trace[2057785310] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:639; }","duration":"199.27262ms","start":"2026-04-20T02:08:59.495719Z","end":"2026-04-20T02:08:59.694992Z","steps":["trace[2057785310] 'agreement among raft nodes before linearized reading' (duration: 199.149497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:00.845784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.373744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.53\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-20T02:09:00.845875Z","caller":"traceutil/trace.go:171","msg":"trace[1618858398] range","detail":"{range_begin:/registry/masterleases/199.204.45.53; range_end:; response_count:1; response_revision:648; }","duration":"142.516628ms","start":"2026-04-20T02:09:00.703337Z","end":"2026-04-20T02:09:00.845854Z","steps":["trace[1618858398] 'range keys from in-memory index tree' (duration: 142.264162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:02.958506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189513600,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:09:03.461018Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189513600,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:09:03.961722Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189513600,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:09:04.175434Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.966328433s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T02:09:04.175736Z","caller":"traceutil/trace.go:171","msg":"trace[1120611997] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"1.966738432s","start":"2026-04-20T02:09:02.208979Z","end":"2026-04-20T02:09:04.175717Z","steps":["trace[1120611997] 'process raft request' (duration: 1.966624649s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:04.175873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:02.208951Z","time spent":"1.966848255s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:09:04.202107Z","caller":"traceutil/trace.go:171","msg":"trace[2125120443] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:691; }","duration":"1.74389876s","start":"2026-04-20T02:09:02.458186Z","end":"2026-04-20T02:09:04.202085Z","steps":["trace[2125120443] 'read index received' (duration: 1.717461221s)","trace[2125120443] 'applied index is now lower than readState.Index' (duration: 26.436148ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:04.20221Z","caller":"traceutil/trace.go:171","msg":"trace[2114265381] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"1.923293827s","start":"2026-04-20T02:09:02.278882Z","end":"2026-04-20T02:09:04.202176Z","steps":["trace[2114265381] 'process raft request' (duration: 1.923061111s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:04.20232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.172969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:09:04.202343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:02.278847Z","time spent":"1.92343388s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:09:04.202364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.744170265s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gc6rf\" ","response":"range_response_count:1 size:17520"} {"level":"info","ts":"2026-04-20T02:09:04.20238Z","caller":"traceutil/trace.go:171","msg":"trace[1606558047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"827.236971ms","start":"2026-04-20T02:09:03.375125Z","end":"2026-04-20T02:09:04.202362Z","steps":["trace[1606558047] 'agreement among raft nodes before linearized reading' (duration: 827.132518ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:04.202404Z","caller":"traceutil/trace.go:171","msg":"trace[605457999] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gc6rf; range_end:; response_count:1; response_revision:661; }","duration":"1.744250347s","start":"2026-04-20T02:09:02.458143Z","end":"2026-04-20T02:09:04.202393Z","steps":["trace[605457999] 'agreement among raft nodes before linearized reading' (duration: 1.744064823s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:04.202423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:03.375055Z","time spent":"827.358174ms","remote":"127.0.0.1:38110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T02:09:04.202431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.170658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-20T02:09:04.202447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:02.458123Z","time spent":"1.744317618s","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17544,"request content":"key:\"/registry/pods/kube-system/cilium-gc6rf\" "} {"level":"info","ts":"2026-04-20T02:09:04.202476Z","caller":"traceutil/trace.go:171","msg":"trace[636657513] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:661; }","duration":"629.216969ms","start":"2026-04-20T02:09:03.573246Z","end":"2026-04-20T02:09:04.202463Z","steps":["trace[636657513] 'agreement among raft nodes before linearized reading' (duration: 629.100666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:04.202521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:03.57321Z","time spent":"629.302851ms","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-20T02:09:04.202524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.040016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-20T02:09:04.202524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.193658ms","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-04-20T02:09:04.202601Z","caller":"traceutil/trace.go:171","msg":"trace[225546806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:661; }","duration":"630.175889ms","start":"2026-04-20T02:09:03.572407Z","end":"2026-04-20T02:09:04.202583Z","steps":["trace[225546806] 'agreement among raft nodes before linearized reading' (duration: 629.923044ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:04.202616Z","caller":"traceutil/trace.go:171","msg":"trace[1729760457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:661; }","duration":"768.332211ms","start":"2026-04-20T02:09:03.434265Z","end":"2026-04-20T02:09:04.202597Z","steps":["trace[1729760457] 'agreement among raft nodes before linearized reading' (duration: 768.120646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:04.202608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.336082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-20T02:09:04.202651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:03.572383Z","time spent":"630.258282ms","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-20T02:09:04.202686Z","caller":"traceutil/trace.go:171","msg":"trace[382274136] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:661; }","duration":"768.455934ms","start":"2026-04-20T02:09:03.434212Z","end":"2026-04-20T02:09:04.202668Z","steps":["trace[382274136] 'agreement among raft nodes before linearized reading' (duration: 768.225759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:04.202739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:03.434198Z","time spent":"768.535067ms","remote":"127.0.0.1:38380","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-04-20T02:09:04.202735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:03.434238Z","time spent":"768.411104ms","remote":"127.0.0.1:38380","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-04-20T02:09:04.450269Z","caller":"traceutil/trace.go:171","msg":"trace[2077340684] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"243.256298ms","start":"2026-04-20T02:09:04.206988Z","end":"2026-04-20T02:09:04.450244Z","steps":["trace[2077340684] 'process raft request' (duration: 243.161015ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:04.450314Z","caller":"traceutil/trace.go:171","msg":"trace[485873590] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"243.37062ms","start":"2026-04-20T02:09:04.206913Z","end":"2026-04-20T02:09:04.450283Z","steps":["trace[485873590] 'process raft request' (duration: 243.061674ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:04.450736Z","caller":"traceutil/trace.go:171","msg":"trace[2058511632] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"243.729648ms","start":"2026-04-20T02:09:04.206988Z","end":"2026-04-20T02:09:04.450718Z","steps":["trace[2058511632] 'process raft request' (duration: 243.218297ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:04.450993Z","caller":"traceutil/trace.go:171","msg":"trace[1839764045] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"234.275377ms","start":"2026-04-20T02:09:04.216694Z","end":"2026-04-20T02:09:04.450969Z","steps":["trace[1839764045] 'process raft request' (duration: 233.787817ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:04.451151Z","caller":"traceutil/trace.go:171","msg":"trace[2135885998] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:694; }","duration":"161.684044ms","start":"2026-04-20T02:09:04.289215Z","end":"2026-04-20T02:09:04.450899Z","steps":["trace[2135885998] 'read index received' (duration: 160.793624ms)","trace[2135885998] 'applied index is now lower than readState.Index' (duration: 889.4µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:09:04.451376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.141335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:5 size:1788"} {"level":"info","ts":"2026-04-20T02:09:04.451445Z","caller":"traceutil/trace.go:171","msg":"trace[164980821] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:5; response_revision:666; }","duration":"162.262457ms","start":"2026-04-20T02:09:04.289166Z","end":"2026-04-20T02:09:04.451429Z","steps":["trace[164980821] 'agreement among raft nodes before linearized reading' (duration: 162.034202ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:06.124407Z","caller":"traceutil/trace.go:171","msg":"trace[927029941] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"153.730677ms","start":"2026-04-20T02:09:05.970657Z","end":"2026-04-20T02:09:06.124388Z","steps":["trace[927029941] 'process raft request' (duration: 153.518312ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:07.858414Z","caller":"traceutil/trace.go:171","msg":"trace[326324371] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:745; }","duration":"337.724119ms","start":"2026-04-20T02:09:07.520661Z","end":"2026-04-20T02:09:07.858386Z","steps":["trace[326324371] 'read index received' (duration: 25.020177ms)","trace[326324371] 'applied index is now lower than readState.Index' (duration: 312.702472ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:07.858499Z","caller":"traceutil/trace.go:171","msg":"trace[1999856133] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"352.719521ms","start":"2026-04-20T02:09:07.505743Z","end":"2026-04-20T02:09:07.858463Z","steps":["trace[1999856133] 'process raft request' (duration: 331.363566ms)","trace[1999856133] 'compare' (duration: 21.14079ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:09:07.858625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.938884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:1 size:3548"} {"level":"warn","ts":"2026-04-20T02:09:07.858653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:07.505723Z","time spent":"352.846584ms","remote":"127.0.0.1:38284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18318,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:09:07.858675Z","caller":"traceutil/trace.go:171","msg":"trace[137592540] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:1; response_revision:715; }","duration":"338.040635ms","start":"2026-04-20T02:09:07.52062Z","end":"2026-04-20T02:09:07.858661Z","steps":["trace[137592540] 'agreement among raft nodes before linearized reading' (duration: 337.920342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:07.858725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:07.520603Z","time spent":"338.108897ms","remote":"127.0.0.1:38476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":1,"response size":3572,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"warn","ts":"2026-04-20T02:09:07.858822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.886124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:09:07.858842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.245965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:5 size:1788"} {"level":"info","ts":"2026-04-20T02:09:07.858887Z","caller":"traceutil/trace.go:171","msg":"trace[437577735] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:715; }","duration":"281.009757ms","start":"2026-04-20T02:09:07.577868Z","end":"2026-04-20T02:09:07.858877Z","steps":["trace[437577735] 'agreement among raft nodes before linearized reading' (duration: 280.887924ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:07.858898Z","caller":"traceutil/trace.go:171","msg":"trace[377016204] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:5; response_revision:715; }","duration":"273.315566ms","start":"2026-04-20T02:09:07.585567Z","end":"2026-04-20T02:09:07.858882Z","steps":["trace[377016204] 'agreement among raft nodes before linearized reading' (duration: 273.096591ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:07.858919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.783424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-20T02:09:07.858977Z","caller":"traceutil/trace.go:171","msg":"trace[626840578] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:715; }","duration":"124.871807ms","start":"2026-04-20T02:09:07.734087Z","end":"2026-04-20T02:09:07.858959Z","steps":["trace[626840578] 'agreement among raft nodes before linearized reading' (duration: 124.639132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:07.85898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.054869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-20T02:09:07.859085Z","caller":"traceutil/trace.go:171","msg":"trace[996973816] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:715; }","duration":"326.183302ms","start":"2026-04-20T02:09:07.532854Z","end":"2026-04-20T02:09:07.859038Z","steps":["trace[996973816] 'agreement among raft nodes before linearized reading' (duration: 325.927667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:07.859134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:07.532838Z","time spent":"326.286514ms","remote":"127.0.0.1:38142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4357,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-04-20T02:09:07.859316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.556506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium\" ","response":"range_response_count:1 size:1654"} {"level":"info","ts":"2026-04-20T02:09:07.859364Z","caller":"traceutil/trace.go:171","msg":"trace[984580581] range","detail":"{range_begin:/registry/clusterroles/cilium; range_end:; response_count:1; response_revision:715; }","duration":"333.634487ms","start":"2026-04-20T02:09:07.525718Z","end":"2026-04-20T02:09:07.859353Z","steps":["trace[984580581] 'agreement among raft nodes before linearized reading' (duration: 332.922292ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:07.859392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:07.525702Z","time spent":"333.683389ms","remote":"127.0.0.1:38476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":31,"response count":1,"response size":1678,"request content":"key:\"/registry/clusterroles/cilium\" "} {"level":"warn","ts":"2026-04-20T02:09:08.302455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.075465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-04-20T02:09:08.302568Z","caller":"traceutil/trace.go:171","msg":"trace[1928573666] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:1; response_revision:720; }","duration":"154.222138ms","start":"2026-04-20T02:09:08.148322Z","end":"2026-04-20T02:09:08.302544Z","steps":["trace[1928573666] 'range keys from in-memory index tree' (duration: 154.010613ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:08.413004Z","caller":"traceutil/trace.go:171","msg":"trace[1493195942] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"106.476646ms","start":"2026-04-20T02:09:08.306503Z","end":"2026-04-20T02:09:08.41298Z","steps":["trace[1493195942] 'process raft request' (duration: 106.282552ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:15.190582Z","caller":"traceutil/trace.go:171","msg":"trace[1020532069] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"159.559667ms","start":"2026-04-20T02:09:15.031001Z","end":"2026-04-20T02:09:15.19056Z","steps":["trace[1020532069] 'read index received' (duration: 159.351613ms)","trace[1020532069] 'applied index is now lower than readState.Index' (duration: 207.294µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:15.190628Z","caller":"traceutil/trace.go:171","msg":"trace[616694774] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"161.124172ms","start":"2026-04-20T02:09:15.029476Z","end":"2026-04-20T02:09:15.1906Z","steps":["trace[616694774] 'process raft request' (duration: 160.859766ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:15.190716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.69209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pvc-protection-controller\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-04-20T02:09:15.190747Z","caller":"traceutil/trace.go:171","msg":"trace[1580515902] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pvc-protection-controller; range_end:; response_count:1; response_revision:790; }","duration":"159.740311ms","start":"2026-04-20T02:09:15.030998Z","end":"2026-04-20T02:09:15.190738Z","steps":["trace[1580515902] 'agreement among raft nodes before linearized reading' (duration: 159.656499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:17.506709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.145036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:09:17.506708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.072588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-scheduler\" ","response":"range_response_count:1 size:1381"} {"level":"warn","ts":"2026-04-20T02:09:17.50671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.403142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:monitoring\" ","response":"range_response_count:1 size:694"} {"level":"info","ts":"2026-04-20T02:09:17.506815Z","caller":"traceutil/trace.go:171","msg":"trace[1985032843] range","detail":"{range_begin:/registry/clusterroles/system:kube-scheduler; range_end:; response_count:1; response_revision:795; }","duration":"124.223771ms","start":"2026-04-20T02:09:17.382568Z","end":"2026-04-20T02:09:17.506792Z","steps":["trace[1985032843] 'range keys from in-memory index tree' (duration: 123.926375ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:17.50682Z","caller":"traceutil/trace.go:171","msg":"trace[1540858822] range","detail":"{range_begin:/registry/clusterrolebindings/system:monitoring; range_end:; response_count:1; response_revision:795; }","duration":"118.566766ms","start":"2026-04-20T02:09:17.388239Z","end":"2026-04-20T02:09:17.506806Z","steps":["trace[1540858822] 'range keys from in-memory index tree' (duration: 118.302359ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:17.506778Z","caller":"traceutil/trace.go:171","msg":"trace[221574979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"131.262468ms","start":"2026-04-20T02:09:17.375502Z","end":"2026-04-20T02:09:17.506764Z","steps":["trace[221574979] 'range keys from in-memory index tree' (duration: 131.088665ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:18.61534Z","caller":"traceutil/trace.go:171","msg":"trace[1443733709] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"114.799332ms","start":"2026-04-20T02:09:18.500518Z","end":"2026-04-20T02:09:18.615318Z","steps":["trace[1443733709] 'read index received' (duration: 114.601168ms)","trace[1443733709] 'applied index is now lower than readState.Index' (duration: 197.234µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:18.615442Z","caller":"traceutil/trace.go:171","msg":"trace[1571621630] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"150.474396ms","start":"2026-04-20T02:09:18.464955Z","end":"2026-04-20T02:09:18.615429Z","steps":["trace[1571621630] 'process raft request' (duration: 150.24478ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:18.615527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.998256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-problem-detector\" ","response":"range_response_count:1 size:655"} {"level":"info","ts":"2026-04-20T02:09:18.615565Z","caller":"traceutil/trace.go:171","msg":"trace[1660418499] range","detail":"{range_begin:/registry/clusterroles/system:node-problem-detector; range_end:; response_count:1; response_revision:796; }","duration":"115.085778ms","start":"2026-04-20T02:09:18.50047Z","end":"2026-04-20T02:09:18.615555Z","steps":["trace[1660418499] 'agreement among raft nodes before linearized reading' (duration: 114.999086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:19.119791Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189514318,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T02:09:19.224772Z","caller":"traceutil/trace.go:171","msg":"trace[565781635] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"605.824058ms","start":"2026-04-20T02:09:18.61892Z","end":"2026-04-20T02:09:19.224744Z","steps":["trace[565781635] 'process raft request' (duration: 605.651225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:19.224986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:18.618898Z","time spent":"605.998593ms","remote":"127.0.0.1:38380","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-04-20T02:09:19.427575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.777237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:09:19.427736Z","caller":"traceutil/trace.go:171","msg":"trace[641194028] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"808.210699ms","start":"2026-04-20T02:09:18.619492Z","end":"2026-04-20T02:09:19.427703Z","steps":["trace[641194028] 'process raft request' (duration: 664.222337ms)","trace[641194028] 'compare' (duration: 143.657605ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:19.427772Z","caller":"traceutil/trace.go:171","msg":"trace[1893918231] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"807.771269ms","start":"2026-04-20T02:09:18.619987Z","end":"2026-04-20T02:09:19.427758Z","steps":["trace[1893918231] 'process raft request' (duration: 807.686627ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:19.427874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:18.619982Z","time spent":"807.82653ms","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:09:19.427874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:18.619461Z","time spent":"808.348941ms","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:09:19.427865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.186308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-problem-detector\" ","response":"range_response_count:1 size:655"} {"level":"info","ts":"2026-04-20T02:09:19.427758Z","caller":"traceutil/trace.go:171","msg":"trace[1070741072] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:833; }","duration":"808.072826ms","start":"2026-04-20T02:09:18.619669Z","end":"2026-04-20T02:09:19.427741Z","steps":["trace[1070741072] 'read index received' (duration: 604.887098ms)","trace[1070741072] 'applied index is now lower than readState.Index' (duration: 203.184428ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:19.42793Z","caller":"traceutil/trace.go:171","msg":"trace[231230957] range","detail":"{range_begin:/registry/clusterroles/system:node-problem-detector; range_end:; response_count:1; response_revision:799; }","duration":"808.27347ms","start":"2026-04-20T02:09:18.619642Z","end":"2026-04-20T02:09:19.427915Z","steps":["trace[231230957] 'agreement among raft nodes before linearized reading' (duration: 808.148387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:19.427987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:18.619636Z","time spent":"808.328941ms","remote":"127.0.0.1:38476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":679,"request content":"key:\"/registry/clusterroles/system:node-problem-detector\" "} {"level":"warn","ts":"2026-04-20T02:09:19.428079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.054333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-proxier\" ","response":"range_response_count:1 size:712"} {"level":"info","ts":"2026-04-20T02:09:19.428153Z","caller":"traceutil/trace.go:171","msg":"trace[1320121536] range","detail":"{range_begin:/registry/clusterroles/system:node-proxier; range_end:; response_count:1; response_revision:799; }","duration":"686.157674ms","start":"2026-04-20T02:09:18.741974Z","end":"2026-04-20T02:09:19.428131Z","steps":["trace[1320121536] 'agreement among raft nodes before linearized reading' (duration: 686.031282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:19.4282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:18.741956Z","time spent":"686.235287ms","remote":"127.0.0.1:38476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":736,"request content":"key:\"/registry/clusterroles/system:node-proxier\" "} {"level":"warn","ts":"2026-04-20T02:09:19.428231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.676896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-04-20T02:09:19.428265Z","caller":"traceutil/trace.go:171","msg":"trace[1488301328] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:799; }","duration":"126.752148ms","start":"2026-04-20T02:09:19.301505Z","end":"2026-04-20T02:09:19.428257Z","steps":["trace[1488301328] 'agreement among raft nodes before linearized reading' (duration: 126.640706ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:19.742279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.053987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T02:09:19.742411Z","caller":"traceutil/trace.go:171","msg":"trace[151212773] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:836; }","duration":"308.791104ms","start":"2026-04-20T02:09:19.433582Z","end":"2026-04-20T02:09:19.742374Z","steps":["trace[151212773] 'read index received' (duration: 162.480202ms)","trace[151212773] 'applied index is now lower than readState.Index' (duration: 146.309912ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:19.742429Z","caller":"traceutil/trace.go:171","msg":"trace[1919671212] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"310.231917ms","start":"2026-04-20T02:09:19.432159Z","end":"2026-04-20T02:09:19.742391Z","steps":["trace[1919671212] 'process raft request' (duration: 163.985676ms)","trace[1919671212] 'compare' (duration: 145.945944ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:09:19.742549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.964628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-proxier\" ","response":"range_response_count:1 size:712"} {"level":"warn","ts":"2026-04-20T02:09:19.742561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.57389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:persistent-volume-provisioner\" ","response":"range_response_count:1 size:812"} {"level":"info","ts":"2026-04-20T02:09:19.742582Z","caller":"traceutil/trace.go:171","msg":"trace[1770802977] range","detail":"{range_begin:/registry/clusterroles/system:node-proxier; range_end:; response_count:1; response_revision:800; }","duration":"309.03492ms","start":"2026-04-20T02:09:19.433537Z","end":"2026-04-20T02:09:19.742572Z","steps":["trace[1770802977] 'agreement among raft nodes before linearized reading' (duration: 308.956718ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:19.74259Z","caller":"traceutil/trace.go:171","msg":"trace[1361607830] range","detail":"{range_begin:/registry/clusterroles/system:persistent-volume-provisioner; range_end:; response_count:1; response_revision:800; }","duration":"205.642331ms","start":"2026-04-20T02:09:19.536938Z","end":"2026-04-20T02:09:19.742581Z","steps":["trace[1361607830] 'agreement among raft nodes before linearized reading' (duration: 205.57911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:19.742607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:19.433521Z","time spent":"309.080211ms","remote":"127.0.0.1:38476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":736,"request content":"key:\"/registry/clusterroles/system:node-proxier\" "} {"level":"warn","ts":"2026-04-20T02:09:19.742625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:19.43214Z","time spent":"310.379861ms","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:09:20.870192Z","caller":"traceutil/trace.go:171","msg":"trace[1057025729] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:843; }","duration":"135.592045ms","start":"2026-04-20T02:09:20.734579Z","end":"2026-04-20T02:09:20.870171Z","steps":["trace[1057025729] 'read index received' (duration: 59.272188ms)","trace[1057025729] 'applied index is now lower than readState.Index' (duration: 76.318987ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T02:09:20.870246Z","caller":"traceutil/trace.go:171","msg":"trace[24241111] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"136.701419ms","start":"2026-04-20T02:09:20.73351Z","end":"2026-04-20T02:09:20.870212Z","steps":["trace[24241111] 'process raft request' (duration: 135.461581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:20.870344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.552374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:09:20.870375Z","caller":"traceutil/trace.go:171","msg":"trace[102073306] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:807; }","duration":"135.625576ms","start":"2026-04-20T02:09:20.734742Z","end":"2026-04-20T02:09:20.870367Z","steps":["trace[102073306] 'agreement among raft nodes before linearized reading' (duration: 135.534654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:20.87036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.761459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:09:20.870427Z","caller":"traceutil/trace.go:171","msg":"trace[1839098018] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:807; }","duration":"135.870422ms","start":"2026-04-20T02:09:20.734539Z","end":"2026-04-20T02:09:20.87041Z","steps":["trace[1839098018] 'agreement among raft nodes before linearized reading' (duration: 135.742259ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:21.790204Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189514389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:09:22.291068Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189514389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:09:22.792181Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189514389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:09:23.293137Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189514389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:09:23.377294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000079123s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T02:09:23.377384Z","caller":"traceutil/trace.go:171","msg":"trace[1127685185] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000227346s","start":"2026-04-20T02:09:21.377136Z","end":"2026-04-20T02:09:23.377364Z","steps":["trace[1127685185] 'agreement among raft nodes before linearized reading' (duration: 2.000115394s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:23.377441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:21.377111Z","time spent":"2.000313609s","remote":"127.0.0.1:38110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T02:09:23.794316Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189514389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:09:24.294531Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189514389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:09:24.797857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189514389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:09:25.298659Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5794898693189514389,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T02:09:25.378381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000407732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T02:09:25.37845Z","caller":"traceutil/trace.go:171","msg":"trace[731269535] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000530225s","start":"2026-04-20T02:09:23.377904Z","end":"2026-04-20T02:09:25.378435Z","steps":["trace[731269535] 'agreement among raft nodes before linearized reading' (duration: 2.000445323s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:25.378485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:23.377876Z","time spent":"2.000601076s","remote":"127.0.0.1:38124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T02:09:25.618984Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.384933032s","expected-duration":"1s"} {"level":"info","ts":"2026-04-20T02:09:25.619464Z","caller":"traceutil/trace.go:171","msg":"trace[1135287874] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"4.385493254s","start":"2026-04-20T02:09:21.233945Z","end":"2026-04-20T02:09:25.619438Z","steps":["trace[1135287874] 'process raft request' (duration: 4.385355521s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:25.619656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:21.233924Z","time spent":"4.385600597s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:09:26.212311Z","caller":"traceutil/trace.go:171","msg":"trace[998517461] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"1.719299702s","start":"2026-04-20T02:09:24.492985Z","end":"2026-04-20T02:09:26.212285Z","steps":["trace[998517461] 'process raft request' (duration: 1.719210989s)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:26.212316Z","caller":"traceutil/trace.go:171","msg":"trace[217714337] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"4.637975958s","start":"2026-04-20T02:09:21.574305Z","end":"2026-04-20T02:09:26.212281Z","steps":["trace[217714337] 'process raft request' (duration: 4.637735592s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:26.212491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:24.492955Z","time spent":"1.719438195s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:09:26.212503Z","caller":"traceutil/trace.go:171","msg":"trace[2043518284] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:849; }","duration":"4.923126747s","start":"2026-04-20T02:09:21.289341Z","end":"2026-04-20T02:09:26.212468Z","steps":["trace[2043518284] 'read index received' (duration: 4.32993091s)","trace[2043518284] 'applied index is now lower than readState.Index' (duration: 593.193167ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:09:26.21258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:21.574278Z","time spent":"4.638208163s","remote":"127.0.0.1:38338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T02:09:26.21263Z","caller":"traceutil/trace.go:171","msg":"trace[472459427] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"1.493216646s","start":"2026-04-20T02:09:24.719371Z","end":"2026-04-20T02:09:26.212588Z","steps":["trace[472459427] 'process raft request' (duration: 1.492876998s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:26.214495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.923301231s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:09:26.214547Z","caller":"traceutil/trace.go:171","msg":"trace[757025485] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:815; }","duration":"4.925237954s","start":"2026-04-20T02:09:21.289298Z","end":"2026-04-20T02:09:26.214536Z","steps":["trace[757025485] 'agreement among raft nodes before linearized reading' (duration: 4.923301001s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:26.214574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:24.71934Z","time spent":"1.493411969s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T02:09:26.214602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:21.289284Z","time spent":"4.925288464s","remote":"127.0.0.1:38394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-20T02:09:26.431071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999405943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T02:09:26.431265Z","caller":"traceutil/trace.go:171","msg":"trace[1990760634] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999641967s","start":"2026-04-20T02:09:21.431591Z","end":"2026-04-20T02:09:26.431233Z","steps":["trace[1990760634] 'agreement among raft nodes before linearized reading' (duration: 4.999419633s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:26.431326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:21.431577Z","time spent":"4.99973582s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/04/20 02:09:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T02:09:26.745629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999411882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-20T02:09:26.745745Z","caller":"traceutil/trace.go:171","msg":"trace[564994989] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999581776s","start":"2026-04-20T02:09:21.746137Z","end":"2026-04-20T02:09:26.745719Z","steps":["trace[564994989] 'agreement among raft nodes before linearized reading' (duration: 4.999443643s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:26.745809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:21.746116Z","time spent":"4.999678788s","remote":"127.0.0.1:38380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/04/20 02:09:26 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-04-20T02:09:26.825323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.30434ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-20T02:09:26.825434Z","caller":"traceutil/trace.go:171","msg":"trace[954117797] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:853; }","duration":"612.852593ms","start":"2026-04-20T02:09:26.212564Z","end":"2026-04-20T02:09:26.825416Z","steps":["trace[954117797] 'read index received' (duration: 274.443911ms)","trace[954117797] 'applied index is now lower than readState.Index' (duration: 338.407302ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T02:09:26.825528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.438418916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T02:09:26.825567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.392718435s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-20T02:09:26.825541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.3228931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:09:26.82559Z","caller":"traceutil/trace.go:171","msg":"trace[2015875774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:815; }","duration":"5.392782507s","start":"2026-04-20T02:09:21.4328Z","end":"2026-04-20T02:09:26.825582Z","steps":["trace[2015875774] 'agreement among raft nodes before linearized reading' (duration: 5.392656784s)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:26.825586Z","caller":"traceutil/trace.go:171","msg":"trace[463130727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:815; }","duration":"1.438533399s","start":"2026-04-20T02:09:25.387037Z","end":"2026-04-20T02:09:26.825571Z","steps":["trace[463130727] 'agreement among raft nodes before linearized reading' (duration: 1.438425937s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:26.825622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:21.432793Z","time spent":"5.392822668s","remote":"127.0.0.1:38380","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-04-20T02:09:26.82563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:25.387014Z","time spent":"1.438604841s","remote":"127.0.0.1:38110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-20T02:09:26.825619Z","caller":"traceutil/trace.go:171","msg":"trace[1156260690] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:815; }","duration":"2.323023203s","start":"2026-04-20T02:09:24.502581Z","end":"2026-04-20T02:09:26.825604Z","steps":["trace[1156260690] 'agreement among raft nodes before linearized reading' (duration: 2.322856599s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:26.825685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:24.502567Z","time spent":"2.323107974s","remote":"127.0.0.1:38532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":29,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-20T02:09:26.82574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.931714166s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T02:09:26.825726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.308525132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-20T02:09:26.825819Z","caller":"traceutil/trace.go:171","msg":"trace[1599789833] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:815; }","duration":"3.931827269s","start":"2026-04-20T02:09:22.893973Z","end":"2026-04-20T02:09:26.8258Z","steps":["trace[1599789833] 'agreement among raft nodes before linearized reading' (duration: 3.931657674s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:26.825839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.103365372s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-20T02:09:26.825904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.831465489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 ","response":"range_response_count:5 size:1788"} {"level":"warn","ts":"2026-04-20T02:09:26.825908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.135919233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T02:09:26.825933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.533012095s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T02:09:26.825936Z","caller":"traceutil/trace.go:171","msg":"trace[1497575469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:815; }","duration":"4.103511785s","start":"2026-04-20T02:09:22.722398Z","end":"2026-04-20T02:09:26.82591Z","steps":["trace[1497575469] 'agreement among raft nodes before linearized reading' (duration: 4.103198687s)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:26.825965Z","caller":"traceutil/trace.go:171","msg":"trace[886612554] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:815; }","duration":"4.533109956s","start":"2026-04-20T02:09:22.292847Z","end":"2026-04-20T02:09:26.825957Z","steps":["trace[886612554] 'agreement among raft nodes before linearized reading' (duration: 4.533058785s)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:26.825962Z","caller":"traceutil/trace.go:171","msg":"trace[732155601] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:815; }","duration":"2.135974034s","start":"2026-04-20T02:09:24.689978Z","end":"2026-04-20T02:09:26.825952Z","steps":["trace[732155601] 'agreement among raft nodes before linearized reading' (duration: 2.135894722s)"],"step_count":1} {"level":"info","ts":"2026-04-20T02:09:26.825956Z","caller":"traceutil/trace.go:171","msg":"trace[1667802958] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:5; response_revision:815; }","duration":"4.831538761s","start":"2026-04-20T02:09:21.994399Z","end":"2026-04-20T02:09:26.825938Z","steps":["trace[1667802958] 'agreement among raft nodes before linearized reading' (duration: 4.831410138s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:26.825989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:22.292792Z","time spent":"4.533191208s","remote":"127.0.0.1:38430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-04-20T02:09:26.825839Z","caller":"traceutil/trace.go:171","msg":"trace[1666690184] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:815; }","duration":"3.308708287s","start":"2026-04-20T02:09:23.517108Z","end":"2026-04-20T02:09:26.825816Z","steps":["trace[1666690184] 'agreement among raft nodes before linearized reading' (duration: 3.308498512s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:26.826005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:22.722371Z","time spent":"4.103620507s","remote":"127.0.0.1:38380","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":"warn","ts":"2026-04-20T02:09:26.825886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:22.893953Z","time spent":"3.93192199s","remote":"127.0.0.1:38488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":52,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-04-20T02:09:26.826025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:23.517084Z","time spent":"3.308931733s","remote":"127.0.0.1:38268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":31,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-04-20T02:09:26.825733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.443626672s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a7ee9c5e002394\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-04-20T02:09:26.826204Z","caller":"traceutil/trace.go:171","msg":"trace[506415546] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18a7ee9c5e002394; range_end:; response_count:1; response_revision:815; }","duration":"1.444165075s","start":"2026-04-20T02:09:25.382026Z","end":"2026-04-20T02:09:26.826192Z","steps":["trace[506415546] 'agreement among raft nodes before linearized reading' (duration: 1.443474759s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T02:09:26.82625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:25.382004Z","time spent":"1.444235566s","remote":"127.0.0.1:38184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":798,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a7ee9c5e002394\" "} {"level":"warn","ts":"2026-04-20T02:09:26.82602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:21.994378Z","time spent":"4.831632412s","remote":"127.0.0.1:38226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":5,"response size":1812,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:500 "} {"level":"warn","ts":"2026-04-20T02:09:26.826022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T02:09:24.689916Z","time spent":"2.136087347s","remote":"127.0.0.1:38226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-04-20T02:09:42.931836Z","caller":"traceutil/trace.go:171","msg":"trace[2029160786] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"100.239979ms","start":"2026-04-20T02:09:42.831568Z","end":"2026-04-20T02:09:42.931808Z","steps":["trace[2029160786] 'process raft request' (duration: 99.922342ms)"],"step_count":1}