{"level":"warn","ts":"2026-02-27T16:23:56.929863Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T16:23:56.92997Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-02-27T16:23:56.930045Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-27T16:23:56.930055Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-02-27T16:23:56.930078Z","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-02-27T16:23:56.930931Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-02-27T16:23:56.931041Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-02-27T16:23:56.936482Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.219548ms"} {"level":"info","ts":"2026-02-27T16:23:56.945081Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-02-27T16:23:56.945187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-02-27T16:23:56.945218Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-02-27T16:23:56.945231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-27T16:23:56.945246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-02-27T16:23:56.945301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-02-27T16:23:56.952745Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-27T16:23:56.954182Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-27T16:23:56.9557Z","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-02-27T16:23:56.958353Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-27T16:23:56.960104Z","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-02-27T16:23:56.960172Z","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-02-27T16:23:56.96018Z","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-02-27T16:23:56.960519Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-27T16:23:56.962971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-02-27T16:23:56.963085Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-02-27T16:23:56.964012Z","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-02-27T16:23:56.964202Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-02-27T16:23:56.964235Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-02-27T16:23:56.964377Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-27T16:23:56.964441Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-27T16:23:57.946235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-02-27T16:23:57.946308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-27T16:23:57.946334Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-02-27T16:23:57.946355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-02-27T16:23:57.946368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-02-27T16:23:57.946614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-02-27T16:23:57.946629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-02-27T16:23:57.948665Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-02-27T16:23:57.948673Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T16:23:57.948713Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-27T16:23:57.948783Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T16:23:57.949079Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-27T16:23:57.949153Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-27T16:23:57.949724Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T16:23:57.949809Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T16:23:57.949835Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-27T16:23:57.951411Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-27T16:23:57.959192Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"warn","ts":"2026-02-27T16:23:59.77826Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968798890135925,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T16:24:00.278625Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968798890135925,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-27T16:24:00.59981Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.322536759s","expected-duration":"1s"} {"level":"info","ts":"2026-02-27T16:24:00.600777Z","caller":"traceutil/trace.go:171","msg":"trace[871177260] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"1.323578134s","start":"2026-02-27T16:23:59.277167Z","end":"2026-02-27T16:24:00.600746Z","steps":["trace[871177260] 'process raft request' (duration: 1.323165752s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:00.601446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.277134Z","time spent":"1.323767904s","remote":"127.0.0.1:39118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T16:24:01.278225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00084053s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-27T16:24:01.278369Z","caller":"traceutil/trace.go:171","msg":"trace[690965861] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001053681s","start":"2026-02-27T16:23:59.277288Z","end":"2026-02-27T16:24:01.278342Z","steps":["trace[690965861] 'agreement among raft nodes before linearized reading' (duration: 2.000844439s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:01.278585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.277283Z","time spent":"2.001134885s","remote":"127.0.0.1:39062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/27 16:24:01 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-27T16:24:02.185468Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.585104133s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-27T16:24:02.509509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.19915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-02-27T16:24:02.510044Z","caller":"traceutil/trace.go:171","msg":"trace[1428283255] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"3.231297706s","start":"2026-02-27T16:23:59.2787Z","end":"2026-02-27T16:24:02.509998Z","steps":["trace[1428283255] 'process raft request' (duration: 2.929355656s)","trace[1428283255] 'compare' (duration: 301.071414ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:24:02.510152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.278694Z","time spent":"3.231411212s","remote":"127.0.0.1:39134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-27T16:24:02.510148Z","caller":"traceutil/trace.go:171","msg":"trace[847808104] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"3.155390675s","start":"2026-02-27T16:23:59.354724Z","end":"2026-02-27T16:24:02.510115Z","steps":["trace[847808104] 'process raft request' (duration: 3.155258888s)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:02.510168Z","caller":"traceutil/trace.go:171","msg":"trace[358959432] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:4; }","duration":"3.23284955s","start":"2026-02-27T16:23:59.277301Z","end":"2026-02-27T16:24:02.51015Z","steps":["trace[358959432] 'read index received' (duration: 1.323067537s)","trace[358959432] 'applied index is now lower than readState.Index' (duration: 1.909780903s)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:24:02.51026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.354711Z","time spent":"3.15549369s","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T16:24:02.510268Z","caller":"traceutil/trace.go:171","msg":"trace[243892263] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"3.155463769s","start":"2026-02-27T16:23:59.354769Z","end":"2026-02-27T16:24:02.510233Z","steps":["trace[243892263] 'process raft request' (duration: 3.155245137s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:02.510365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.354752Z","time spent":"3.155565195s","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T16:24:02.510419Z","caller":"traceutil/trace.go:171","msg":"trace[1402516921] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"3.156153137s","start":"2026-02-27T16:23:59.35425Z","end":"2026-02-27T16:24:02.510404Z","steps":["trace[1402516921] 'process raft request' (duration: 3.155560224s)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:02.510382Z","caller":"traceutil/trace.go:171","msg":"trace[290945804] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"3.156139034s","start":"2026-02-27T16:23:59.354166Z","end":"2026-02-27T16:24:02.510305Z","steps":["trace[290945804] 'process raft request' (duration: 3.155570774s)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:02.510521Z","caller":"traceutil/trace.go:171","msg":"trace[1244005724] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"2.1208599s","start":"2026-02-27T16:24:00.389647Z","end":"2026-02-27T16:24:02.510507Z","steps":["trace[1244005724] 'process raft request' (duration: 2.120453097s)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:02.510547Z","caller":"traceutil/trace.go:171","msg":"trace[22593752] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"3.144889345s","start":"2026-02-27T16:23:59.365646Z","end":"2026-02-27T16:24:02.510535Z","steps":["trace[22593752] 'process raft request' (duration: 3.144415348s)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:02.51059Z","caller":"traceutil/trace.go:171","msg":"trace[487293003] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"3.155882742s","start":"2026-02-27T16:23:59.354696Z","end":"2026-02-27T16:24:02.510578Z","steps":["trace[487293003] 'process raft request' (duration: 3.155160993s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:02.510602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:00.389617Z","time spent":"2.120953736s","remote":"127.0.0.1:39332","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T16:24:02.510626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.365633Z","time spent":"3.144951377s","remote":"127.0.0.1:39250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T16:24:02.51065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.35468Z","time spent":"3.155939205s","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T16:24:02.510668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.354122Z","time spent":"3.156437372s","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T16:24:02.510478Z","caller":"traceutil/trace.go:171","msg":"trace[1607413933] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"3.155516213s","start":"2026-02-27T16:23:59.354874Z","end":"2026-02-27T16:24:02.51039Z","steps":["trace[1607413933] 'process raft request' (duration: 3.155162293s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:02.510542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.354224Z","time spent":"3.156283653s","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T16:24:02.510854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.354845Z","time spent":"3.155933185s","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T16:24:02.510949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.228134528s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-02-27T16:24:02.510997Z","caller":"traceutil/trace.go:171","msg":"trace[26986030] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"3.228184251s","start":"2026-02-27T16:23:59.282798Z","end":"2026-02-27T16:24:02.510982Z","steps":["trace[26986030] 'agreement among raft nodes before linearized reading' (duration: 3.228057224s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:02.511038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.282752Z","time spent":"3.228275696s","remote":"127.0.0.1:39250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-27T16:24:02.511047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.258926757s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T16:24:02.511086Z","caller":"traceutil/trace.go:171","msg":"trace[1162380465] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"2.259006872s","start":"2026-02-27T16:24:00.252068Z","end":"2026-02-27T16:24:02.511075Z","steps":["trace[1162380465] 'agreement among raft nodes before linearized reading' (duration: 2.258807761s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:02.511073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.177418774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T16:24:02.511127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:00.252039Z","time spent":"2.259071684s","remote":"127.0.0.1:39420","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-02-27T16:24:02.511144Z","caller":"traceutil/trace.go:171","msg":"trace[929529196] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"3.177490507s","start":"2026-02-27T16:23:59.333637Z","end":"2026-02-27T16:24:02.511128Z","steps":["trace[929529196] 'agreement among raft nodes before linearized reading' (duration: 3.177387332s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:02.511178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.156684635s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T16:24:02.511231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.691006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T16:24:02.511245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.158114461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T16:24:02.511259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.177677118s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T16:24:02.511286Z","caller":"traceutil/trace.go:171","msg":"trace[1019047444] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"3.158206677s","start":"2026-02-27T16:23:59.353066Z","end":"2026-02-27T16:24:02.511273Z","steps":["trace[1019047444] 'agreement among raft nodes before linearized reading' (duration: 3.158145854s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:02.511288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.326225061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T16:24:02.511305Z","caller":"traceutil/trace.go:171","msg":"trace[695188933] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"3.177736611s","start":"2026-02-27T16:23:59.333553Z","end":"2026-02-27T16:24:02.511289Z","steps":["trace[695188933] 'agreement among raft nodes before linearized reading' (duration: 3.177671097s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:02.511321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.353035Z","time spent":"3.158276641s","remote":"127.0.0.1:39188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-27T16:24:02.511324Z","caller":"traceutil/trace.go:171","msg":"trace[1003227839] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:11; }","duration":"2.326347208s","start":"2026-02-27T16:24:00.184966Z","end":"2026-02-27T16:24:02.511313Z","steps":["trace[1003227839] 'agreement among raft nodes before linearized reading' (duration: 2.326291945s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:02.511344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.105789302s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-02-27T16:24:02.511361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:00.184925Z","time spent":"2.326427122s","remote":"127.0.0.1:39336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-02-27T16:24:02.511365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.159650164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T16:24:02.511378Z","caller":"traceutil/trace.go:171","msg":"trace[985238612] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"3.105896638s","start":"2026-02-27T16:23:59.405474Z","end":"2026-02-27T16:24:02.511371Z","steps":["trace[985238612] 'agreement among raft nodes before linearized reading' (duration: 3.105841785s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:02.511394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.177853747s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T16:24:02.511403Z","caller":"traceutil/trace.go:171","msg":"trace[860468562] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:0; response_revision:11; }","duration":"3.159721978s","start":"2026-02-27T16:23:59.351672Z","end":"2026-02-27T16:24:02.511394Z","steps":["trace[860468562] 'agreement among raft nodes before linearized reading' (duration: 3.159671005s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:02.511415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.405432Z","time spent":"3.105975942s","remote":"127.0.0.1:39188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-27T16:24:02.511434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.351646Z","time spent":"3.15978213s","remote":"127.0.0.1:39090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.204.45.116\" "} {"level":"info","ts":"2026-02-27T16:24:02.511431Z","caller":"traceutil/trace.go:171","msg":"trace[258118502] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"3.177942262s","start":"2026-02-27T16:23:59.333478Z","end":"2026-02-27T16:24:02.51142Z","steps":["trace[258118502] 'agreement among raft nodes before linearized reading' (duration: 3.177891199s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:02.511467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.333452Z","time spent":"3.178006506s","remote":"127.0.0.1:39188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-27T16:24:02.511344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.333547Z","time spent":"3.177788453s","remote":"127.0.0.1:39188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-02-27T16:24:02.511232Z","caller":"traceutil/trace.go:171","msg":"trace[1107419834] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"3.156758009s","start":"2026-02-27T16:23:59.354456Z","end":"2026-02-27T16:24:02.511214Z","steps":["trace[1107419834] 'agreement among raft nodes before linearized reading' (duration: 3.156415481s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:02.511279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.258883914s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T16:24:02.51157Z","caller":"traceutil/trace.go:171","msg":"trace[1677859334] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"2.259173509s","start":"2026-02-27T16:24:00.252388Z","end":"2026-02-27T16:24:02.511562Z","steps":["trace[1677859334] 'agreement among raft nodes before linearized reading' (duration: 2.258868423s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:02.511568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.354447Z","time spent":"3.157105468s","remote":"127.0.0.1:39160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-02-27T16:24:02.511188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:23:59.333601Z","time spent":"3.177576532s","remote":"127.0.0.1:39188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-27T16:24:02.511603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:00.252266Z","time spent":"2.259324208s","remote":"127.0.0.1:39446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-02-27T16:24:02.511286Z","caller":"traceutil/trace.go:171","msg":"trace[222823096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"862.784341ms","start":"2026-02-27T16:24:01.648481Z","end":"2026-02-27T16:24:02.511266Z","steps":["trace[222823096] 'agreement among raft nodes before linearized reading' (duration: 862.688196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:02.511667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:01.648455Z","time spent":"863.196142ms","remote":"127.0.0.1:39062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T16:24:03.017981Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15853968798890135943,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-27T16:24:03.0751Z","caller":"traceutil/trace.go:171","msg":"trace[225923172] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"557.488785ms","start":"2026-02-27T16:24:02.517579Z","end":"2026-02-27T16:24:03.075068Z","steps":["trace[225923172] 'process raft request' (duration: 557.444623ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:03.075132Z","caller":"traceutil/trace.go:171","msg":"trace[851135471] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"557.619172ms","start":"2026-02-27T16:24:02.51748Z","end":"2026-02-27T16:24:03.075099Z","steps":["trace[851135471] 'process raft request' (duration: 553.86647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:03.075231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.51757Z","time spent":"557.59247ms","remote":"127.0.0.1:39446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T16:24:03.075284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.51746Z","time spent":"557.746428ms","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T16:24:03.075629Z","caller":"traceutil/trace.go:171","msg":"trace[1032007870] linearizableReadLoop","detail":"{readStateIndex:25; appliedIndex:14; }","duration":"557.686395ms","start":"2026-02-27T16:24:02.517931Z","end":"2026-02-27T16:24:03.075617Z","steps":["trace[1032007870] 'read index received' (duration: 553.418856ms)","trace[1032007870] 'applied index is now lower than readState.Index' (duration: 4.266449ms)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:24:03.07575Z","caller":"traceutil/trace.go:171","msg":"trace[195805158] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"557.015918ms","start":"2026-02-27T16:24:02.518729Z","end":"2026-02-27T16:24:03.075744Z","steps":["trace[195805158] 'process raft request' (duration: 556.712672ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:03.075779Z","caller":"traceutil/trace.go:171","msg":"trace[2094635639] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"557.844793ms","start":"2026-02-27T16:24:02.517918Z","end":"2026-02-27T16:24:03.075763Z","steps":["trace[2094635639] 'process raft request' (duration: 557.500665ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:03.07576Z","caller":"traceutil/trace.go:171","msg":"trace[1040383182] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"558.058785ms","start":"2026-02-27T16:24:02.517687Z","end":"2026-02-27T16:24:03.075745Z","steps":["trace[1040383182] 'process raft request' (duration: 557.690976ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:03.075813Z","caller":"traceutil/trace.go:171","msg":"trace[2066337485] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"536.957542ms","start":"2026-02-27T16:24:02.53885Z","end":"2026-02-27T16:24:03.075808Z","steps":["trace[2066337485] 'process raft request' (duration: 536.617263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:03.075832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.517911Z","time spent":"557.897447ms","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T16:24:03.075845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.53884Z","time spent":"536.992484ms","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T16:24:03.075854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.517678Z","time spent":"558.14829ms","remote":"127.0.0.1:39456","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T16:24:03.075791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.518715Z","time spent":"557.057482ms","remote":"127.0.0.1:39520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T16:24:03.075963Z","caller":"traceutil/trace.go:171","msg":"trace[645121158] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"536.630145ms","start":"2026-02-27T16:24:02.539328Z","end":"2026-02-27T16:24:03.075958Z","steps":["trace[645121158] 'process raft request' (duration: 536.189531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:03.075999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.539318Z","time spent":"536.666206ms","remote":"127.0.0.1:39188","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T16:24:03.075897Z","caller":"traceutil/trace.go:171","msg":"trace[551848920] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"536.751481ms","start":"2026-02-27T16:24:02.539141Z","end":"2026-02-27T16:24:03.075892Z","steps":["trace[551848920] 'process raft request' (duration: 536.347279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:03.076138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.324909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T16:24:03.076163Z","caller":"traceutil/trace.go:171","msg":"trace[1773554892] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:21; }","duration":"558.373842ms","start":"2026-02-27T16:24:02.517781Z","end":"2026-02-27T16:24:03.076155Z","steps":["trace[1773554892] 'agreement among raft nodes before linearized reading' (duration: 558.292758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:03.07618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.651324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-02-27T16:24:03.076189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.517774Z","time spent":"558.410734ms","remote":"127.0.0.1:39432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-02-27T16:24:03.076183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.255946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-02-27T16:24:03.076207Z","caller":"traceutil/trace.go:171","msg":"trace[1245899410] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"484.714177ms","start":"2026-02-27T16:24:02.591485Z","end":"2026-02-27T16:24:03.0762Z","steps":["trace[1245899410] 'agreement among raft nodes before linearized reading' (duration: 484.606332ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:03.076219Z","caller":"traceutil/trace.go:171","msg":"trace[2109728664] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:21; }","duration":"558.311169ms","start":"2026-02-27T16:24:02.517895Z","end":"2026-02-27T16:24:03.076206Z","steps":["trace[2109728664] 'agreement among raft nodes before linearized reading' (duration: 558.162191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:03.076235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.591459Z","time spent":"484.769712ms","remote":"127.0.0.1:39188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-27T16:24:03.076244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.517887Z","time spent":"558.352632ms","remote":"127.0.0.1:39250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3513,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-27T16:24:03.076305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.092133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T16:24:03.076326Z","caller":"traceutil/trace.go:171","msg":"trace[105601809] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21; }","duration":"356.131945ms","start":"2026-02-27T16:24:02.720188Z","end":"2026-02-27T16:24:03.07632Z","steps":["trace[105601809] 'agreement among raft nodes before linearized reading' (duration: 356.100043ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:03.076343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.720178Z","time spent":"356.161037ms","remote":"127.0.0.1:39062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T16:24:03.076363Z","caller":"traceutil/trace.go:171","msg":"trace[1667911710] transaction","detail":"{read_only:false; number_of_response:0; response_revision:19; }","duration":"536.955853ms","start":"2026-02-27T16:24:02.539399Z","end":"2026-02-27T16:24:03.076355Z","steps":["trace[1667911710] 'process raft request' (duration: 536.140118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:03.076408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.539389Z","time spent":"537.000505ms","remote":"127.0.0.1:39188","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T16:24:03.076437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.352685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-27T16:24:03.076461Z","caller":"traceutil/trace.go:171","msg":"trace[576388678] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"518.182954ms","start":"2026-02-27T16:24:02.55827Z","end":"2026-02-27T16:24:03.076453Z","steps":["trace[576388678] 'process raft request' (duration: 517.311396ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:03.076471Z","caller":"traceutil/trace.go:171","msg":"trace[899789071] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"407.43669ms","start":"2026-02-27T16:24:02.669028Z","end":"2026-02-27T16:24:03.076465Z","steps":["trace[899789071] 'agreement among raft nodes before linearized reading' (duration: 407.387977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:03.076492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.668999Z","time spent":"407.488622ms","remote":"127.0.0.1:39188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-02-27T16:24:03.076161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.539134Z","time spent":"536.950601ms","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-27T16:24:03.076505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.558248Z","time spent":"518.228596ms","remote":"127.0.0.1:39250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T16:24:03.076653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.248524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-27T16:24:03.076669Z","caller":"traceutil/trace.go:171","msg":"trace[1722326622] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"536.808195ms","start":"2026-02-27T16:24:02.539842Z","end":"2026-02-27T16:24:03.07665Z","steps":["trace[1722326622] 'process raft request' (duration: 535.714145ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:03.076681Z","caller":"traceutil/trace.go:171","msg":"trace[283215842] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:21; }","duration":"357.310158ms","start":"2026-02-27T16:24:02.719363Z","end":"2026-02-27T16:24:03.076673Z","steps":["trace[283215842] 'agreement among raft nodes before linearized reading' (duration: 357.272806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:03.076701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.719291Z","time spent":"357.405093ms","remote":"127.0.0.1:39336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":26,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-02-27T16:24:03.076722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:02.539811Z","time spent":"536.886309ms","remote":"127.0.0.1:39598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-27T16:24:21.749246Z","caller":"traceutil/trace.go:171","msg":"trace[1512595527] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"144.729656ms","start":"2026-02-27T16:24:21.604485Z","end":"2026-02-27T16:24:21.749214Z","steps":["trace[1512595527] 'process raft request' (duration: 144.51675ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:29.223671Z","caller":"traceutil/trace.go:171","msg":"trace[1722155409] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"112.962417ms","start":"2026-02-27T16:24:29.11067Z","end":"2026-02-27T16:24:29.223632Z","steps":["trace[1722155409] 'read index received' (duration: 49.954141ms)","trace[1722155409] 'applied index is now lower than readState.Index' (duration: 63.006116ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:24:29.2239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.206205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-27T16:24:29.224102Z","caller":"traceutil/trace.go:171","msg":"trace[68673118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:473; }","duration":"113.440793ms","start":"2026-02-27T16:24:29.110594Z","end":"2026-02-27T16:24:29.224034Z","steps":["trace[68673118] 'agreement among raft nodes before linearized reading' (duration: 113.144245ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:33.522871Z","caller":"traceutil/trace.go:171","msg":"trace[1329493469] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"187.928192ms","start":"2026-02-27T16:24:33.334911Z","end":"2026-02-27T16:24:33.522839Z","steps":["trace[1329493469] 'process raft request' (duration: 187.077797ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:33.523138Z","caller":"traceutil/trace.go:171","msg":"trace[109866634] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:531; }","duration":"109.560082ms","start":"2026-02-27T16:24:33.413246Z","end":"2026-02-27T16:24:33.522806Z","steps":["trace[109866634] 'read index received' (duration: 108.312049ms)","trace[109866634] 'applied index is now lower than readState.Index' (duration: 1.246903ms)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:24:33.523468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.200338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-27T16:24:33.523685Z","caller":"traceutil/trace.go:171","msg":"trace[1924782791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"110.346147ms","start":"2026-02-27T16:24:33.413202Z","end":"2026-02-27T16:24:33.523548Z","steps":["trace[1924782791] 'agreement among raft nodes before linearized reading' (duration: 109.973018ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:38.229158Z","caller":"traceutil/trace.go:171","msg":"trace[2117877521] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"341.039649ms","start":"2026-02-27T16:24:37.888086Z","end":"2026-02-27T16:24:38.229126Z","steps":["trace[2117877521] 'process raft request' (duration: 340.788369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:38.229383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:37.888065Z","time spent":"341.197228ms","remote":"127.0.0.1:39336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T16:24:38.866532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.535579ms","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-02-27T16:24:38.866648Z","caller":"traceutil/trace.go:171","msg":"trace[903239485] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:552; }","duration":"403.732538ms","start":"2026-02-27T16:24:38.462891Z","end":"2026-02-27T16:24:38.866623Z","steps":["trace[903239485] 'range keys from in-memory index tree' (duration: 403.274039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:38.866724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:38.462862Z","time spent":"403.849728ms","remote":"127.0.0.1:39336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-27T16:24:38.867408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.146633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:24:38.867529Z","caller":"traceutil/trace.go:171","msg":"trace[652855500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"464.807492ms","start":"2026-02-27T16:24:38.402695Z","end":"2026-02-27T16:24:38.867502Z","steps":["trace[652855500] 'range keys from in-memory index tree' (duration: 464.076903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:38.867578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:38.402674Z","time spent":"464.892532ms","remote":"127.0.0.1:39062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-27T16:24:38.972521Z","caller":"traceutil/trace.go:171","msg":"trace[974861574] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"100.196941ms","start":"2026-02-27T16:24:38.872285Z","end":"2026-02-27T16:24:38.972481Z","steps":["trace[974861574] 'process raft request' (duration: 99.881241ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:40.135892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.518033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pvvw9\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-02-27T16:24:40.135991Z","caller":"traceutil/trace.go:171","msg":"trace[575906798] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pvvw9; range_end:; response_count:1; response_revision:558; }","duration":"237.690983ms","start":"2026-02-27T16:24:39.898276Z","end":"2026-02-27T16:24:40.135967Z","steps":["trace[575906798] 'range keys from in-memory index tree' (duration: 237.261363ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:40.275605Z","caller":"traceutil/trace.go:171","msg":"trace[236614501] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"124.282054ms","start":"2026-02-27T16:24:40.151292Z","end":"2026-02-27T16:24:40.275575Z","steps":["trace[236614501] 'process raft request' (duration: 123.520604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:42.432084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.460181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-27T16:24:42.432175Z","caller":"traceutil/trace.go:171","msg":"trace[465213326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"138.634651ms","start":"2026-02-27T16:24:42.293517Z","end":"2026-02-27T16:24:42.432151Z","steps":["trace[465213326] 'range keys from in-memory index tree' (duration: 138.324131ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:42.432432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.029443368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-27T16:24:42.432457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.914694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-27T16:24:42.432525Z","caller":"traceutil/trace.go:171","msg":"trace[476096205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"2.029615728s","start":"2026-02-27T16:24:40.402887Z","end":"2026-02-27T16:24:42.432503Z","steps":["trace[476096205] 'range keys from in-memory index tree' (duration: 2.029286188s)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:42.43254Z","caller":"traceutil/trace.go:171","msg":"trace[375493917] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:560; }","duration":"845.019054ms","start":"2026-02-27T16:24:41.587498Z","end":"2026-02-27T16:24:42.432517Z","steps":["trace[375493917] 'range keys from in-memory index tree' (duration: 844.766084ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:42.432574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:40.402858Z","time spent":"2.029707058s","remote":"127.0.0.1:39058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-27T16:24:42.432586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:41.587492Z","time spent":"845.085304ms","remote":"127.0.0.1:39336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-27T16:24:42.432905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:41.016843Z","time spent":"1.416057364s","remote":"127.0.0.1:56900","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-27T16:24:42.432927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.243848ms","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-02-27T16:24:42.432956Z","caller":"traceutil/trace.go:171","msg":"trace[105040575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:560; }","duration":"851.304078ms","start":"2026-02-27T16:24:41.581643Z","end":"2026-02-27T16:24:42.432947Z","steps":["trace[105040575] 'range keys from in-memory index tree' (duration: 851.135818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:42.432986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:41.581619Z","time spent":"851.360518ms","remote":"127.0.0.1:39336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-27T16:24:42.433231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456547658s","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-02-27T16:24:42.433264Z","caller":"traceutil/trace.go:171","msg":"trace[869282093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"1.456653318s","start":"2026-02-27T16:24:40.976599Z","end":"2026-02-27T16:24:42.433253Z","steps":["trace[869282093] 'range keys from in-memory index tree' (duration: 1.456322838s)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:42.433291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:40.976569Z","time spent":"1.456715528s","remote":"127.0.0.1:39336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-27T16:24:42.690011Z","caller":"traceutil/trace.go:171","msg":"trace[203262736] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"282.837178ms","start":"2026-02-27T16:24:42.407109Z","end":"2026-02-27T16:24:42.689946Z","steps":["trace[203262736] 'process raft request' (duration: 282.558348ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:42.690144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.330445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:24:42.690205Z","caller":"traceutil/trace.go:171","msg":"trace[1556671574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"279.462445ms","start":"2026-02-27T16:24:42.410726Z","end":"2026-02-27T16:24:42.690189Z","steps":["trace[1556671574] 'agreement among raft nodes before linearized reading' (duration: 279.295324ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:42.689953Z","caller":"traceutil/trace.go:171","msg":"trace[1638761671] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"279.112863ms","start":"2026-02-27T16:24:42.410781Z","end":"2026-02-27T16:24:42.689894Z","steps":["trace[1638761671] 'read index received' (duration: 278.848683ms)","trace[1638761671] 'applied index is now lower than readState.Index' (duration: 263.14µs)"],"step_count":2} {"level":"info","ts":"2026-02-27T16:24:42.692957Z","caller":"traceutil/trace.go:171","msg":"trace[1611788600] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"252.640188ms","start":"2026-02-27T16:24:42.440284Z","end":"2026-02-27T16:24:42.692924Z","steps":["trace[1611788600] 'process raft request' (duration: 252.492338ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:42.693176Z","caller":"traceutil/trace.go:171","msg":"trace[1629109560] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"255.138512ms","start":"2026-02-27T16:24:42.438026Z","end":"2026-02-27T16:24:42.693164Z","steps":["trace[1629109560] 'process raft request' (duration: 254.580852ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:42.693314Z","caller":"traceutil/trace.go:171","msg":"trace[521044338] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"252.874409ms","start":"2026-02-27T16:24:42.44043Z","end":"2026-02-27T16:24:42.693304Z","steps":["trace[521044338] 'process raft request' (duration: 252.390088ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:42.694288Z","caller":"traceutil/trace.go:171","msg":"trace[6799607] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"253.154849ms","start":"2026-02-27T16:24:42.440497Z","end":"2026-02-27T16:24:42.693652Z","steps":["trace[6799607] 'process raft request' (duration: 252.768119ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:44.557506Z","caller":"traceutil/trace.go:171","msg":"trace[183923519] linearizableReadLoop","detail":"{readStateIndex:597; appliedIndex:596; }","duration":"154.541624ms","start":"2026-02-27T16:24:44.402931Z","end":"2026-02-27T16:24:44.557472Z","steps":["trace[183923519] 'read index received' (duration: 154.237943ms)","trace[183923519] 'applied index is now lower than readState.Index' (duration: 302.381µs)"],"step_count":2} {"level":"warn","ts":"2026-02-27T16:24:44.557663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.720025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-27T16:24:44.557724Z","caller":"traceutil/trace.go:171","msg":"trace[1099079980] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"154.849395ms","start":"2026-02-27T16:24:44.40286Z","end":"2026-02-27T16:24:44.557709Z","steps":["trace[1099079980] 'agreement among raft nodes before linearized reading' (duration: 154.740074ms)"],"step_count":1} {"level":"info","ts":"2026-02-27T16:24:44.558116Z","caller":"traceutil/trace.go:171","msg":"trace[1574991290] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"418.15949ms","start":"2026-02-27T16:24:44.139379Z","end":"2026-02-27T16:24:44.557539Z","steps":["trace[1574991290] 'process raft request' (duration: 417.903749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:24:44.558267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-27T16:24:44.13935Z","time spent":"418.841013ms","remote":"127.0.0.1:39336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-27T16:26:17.338758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.1055ms","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-02-27T16:26:17.338842Z","caller":"traceutil/trace.go:171","msg":"trace[1159140184] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1015; }","duration":"103.242724ms","start":"2026-02-27T16:26:17.235582Z","end":"2026-02-27T16:26:17.338825Z","steps":["trace[1159140184] 'range keys from in-memory index tree' (duration: 102.934593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-27T16:26:17.338758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.976471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-27T16:26:17.338955Z","caller":"traceutil/trace.go:171","msg":"trace[1258135737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1015; }","duration":"116.215529ms","start":"2026-02-27T16:26:17.222714Z","end":"2026-02-27T16:26:17.338929Z","steps":["trace[1258135737] 'range keys from in-memory index tree' (duration: 115.835735ms)"],"step_count":1}