{"level":"warn","ts":"2026-05-29T21:52:41.281109Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T21:52:41.281191Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.74: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.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.74: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-05-29T21:52:41.281255Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-29T21:52:41.281265Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-05-29T21:52:41.281287Z","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-05-29T21:52:41.281879Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-05-29T21:52:41.281951Z","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.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.74: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-05-29T21:52:41.395954Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"113.794953ms"} {"level":"info","ts":"2026-05-29T21:52:41.602809Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-05-29T21:52:41.603052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-05-29T21:52:41.60322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-05-29T21:52:41.603313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-29T21:52:41.603431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-05-29T21:52:41.603707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-05-29T21:52:41.832802Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-29T21:52:41.841642Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-29T21:52:42.001467Z","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-05-29T21:52:42.151495Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-29T21:52:42.151962Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-29T21:52:42.152335Z","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-05-29T21:52:42.15249Z","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-05-29T21:52:42.152563Z","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-05-29T21:52:42.157812Z","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-05-29T21:52:42.157993Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-05-29T21:52:42.158027Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-05-29T21:52:42.159066Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-29T21:52:42.159113Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-29T21:52:42.212133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-05-29T21:52:42.212339Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-05-29T21:52:42.212404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-05-29T21:52:42.212424Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-29T21:52:42.212444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-05-29T21:52:42.212462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-05-29T21:52:42.21248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-05-29T21:52:42.212496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-05-29T21:52:42.212515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-05-29T21:52:42.24593Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:52:42.373476Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-05-29T21:52:42.373568Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T21:52:42.373571Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-29T21:52:42.373958Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-29T21:52:42.374048Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-29T21:52:42.376637Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-29T21:52:42.378049Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-05-29T21:52:42.396889Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:52:42.39709Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-29T21:52:42.397139Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-29T21:52:44.131875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.518521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T21:52:44.132043Z","caller":"traceutil/trace.go:171","msg":"trace[1394375871] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:0; response_revision:3; }","duration":"314.834205ms","start":"2026-05-29T21:52:43.817186Z","end":"2026-05-29T21:52:44.13202Z","steps":["trace[1394375871] 'range keys from in-memory index tree' (duration: 314.428493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.132135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:43.817169Z","time spent":"314.954462ms","remote":"127.0.0.1:56616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"warn","ts":"2026-05-29T21:52:44.132315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.934544ms","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":"info","ts":"2026-05-29T21:52:44.132443Z","caller":"traceutil/trace.go:171","msg":"trace[155891436] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:3; }","duration":"314.093301ms","start":"2026-05-29T21:52:43.81832Z","end":"2026-05-29T21:52:44.132413Z","steps":["trace[155891436] 'range keys from in-memory index tree' (duration: 313.887925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.132503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:43.818315Z","time spent":"314.17601ms","remote":"127.0.0.1:56664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-05-29T21:52:44.13251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.464933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T21:52:44.132744Z","caller":"traceutil/trace.go:171","msg":"trace[1100251304] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"314.828967ms","start":"2026-05-29T21:52:43.81789Z","end":"2026-05-29T21:52:44.132719Z","steps":["trace[1100251304] 'range keys from in-memory index tree' (duration: 314.425843ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.133022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:43.817885Z","time spent":"315.12097ms","remote":"127.0.0.1:56676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-29T21:52:44.133689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.314319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-29T21:52:44.133922Z","caller":"traceutil/trace.go:171","msg":"trace[1355940053] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"315.20408ms","start":"2026-05-29T21:52:43.818643Z","end":"2026-05-29T21:52:44.133847Z","steps":["trace[1355940053] 'process raft request' (duration: 206.930129ms)","trace[1355940053] 'compare' (duration: 106.180431ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:52:44.134172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:43.818633Z","time spent":"315.352057ms","remote":"127.0.0.1:56966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:52:44.540861Z","caller":"traceutil/trace.go:171","msg":"trace[1405760238] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:6; }","duration":"687.54051ms","start":"2026-05-29T21:52:43.853294Z","end":"2026-05-29T21:52:44.540834Z","steps":["trace[1405760238] 'read index received' (duration: 172.300625ms)","trace[1405760238] 'applied index is now lower than readState.Index' (duration: 515.238605ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:44.540943Z","caller":"traceutil/trace.go:171","msg":"trace[723948839] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"716.201838ms","start":"2026-05-29T21:52:43.824697Z","end":"2026-05-29T21:52:44.540899Z","steps":["trace[723948839] 'process raft request' (duration: 647.348857ms)","trace[723948839] 'compare' (duration: 68.423209ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:44.541014Z","caller":"traceutil/trace.go:171","msg":"trace[1444002236] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"711.30312ms","start":"2026-05-29T21:52:43.829699Z","end":"2026-05-29T21:52:44.541002Z","steps":["trace[1444002236] 'process raft request' (duration: 711.058114ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.541181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:43.824682Z","time spent":"716.390293ms","remote":"127.0.0.1:56792","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:52:44.541018Z","caller":"traceutil/trace.go:171","msg":"trace[524961152] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"712.208901ms","start":"2026-05-29T21:52:43.828798Z","end":"2026-05-29T21:52:44.541007Z","steps":["trace[524961152] 'process raft request' (duration: 711.806738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.541523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:43.82878Z","time spent":"712.642391ms","remote":"127.0.0.1:57042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:52:44.54165Z","caller":"traceutil/trace.go:171","msg":"trace[1852478872] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"712.79232ms","start":"2026-05-29T21:52:43.828816Z","end":"2026-05-29T21:52:44.541608Z","steps":["trace[1852478872] 'process raft request' (duration: 711.840167ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:44.540942Z","caller":"traceutil/trace.go:171","msg":"trace[1324760763] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"711.579374ms","start":"2026-05-29T21:52:43.829324Z","end":"2026-05-29T21:52:44.540903Z","steps":["trace[1324760763] 'process raft request' (duration: 711.388257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.541811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:43.829318Z","time spent":"712.395639ms","remote":"127.0.0.1:57042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:52:44.541838Z","caller":"traceutil/trace.go:171","msg":"trace[2139199931] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"712.417448ms","start":"2026-05-29T21:52:43.82925Z","end":"2026-05-29T21:52:44.541668Z","steps":["trace[2139199931] 'process raft request' (duration: 711.432116ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:44.542707Z","caller":"traceutil/trace.go:171","msg":"trace[1567652660] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"688.242456ms","start":"2026-05-29T21:52:43.853227Z","end":"2026-05-29T21:52:44.54147Z","steps":["trace[1567652660] 'process raft request' (duration: 687.561089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.542949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:43.853214Z","time spent":"689.628689ms","remote":"127.0.0.1:56734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:52:44.542724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:43.82924Z","time spent":"713.385839ms","remote":"127.0.0.1:57042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:52:44.542029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.714917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-29T21:52:44.542151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:43.829693Z","time spent":"712.428409ms","remote":"127.0.0.1:57042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:52:44.544022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:43.828802Z","time spent":"712.880098ms","remote":"127.0.0.1:57042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:52:44.544464Z","caller":"traceutil/trace.go:171","msg":"trace[688827175] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"690.997824ms","start":"2026-05-29T21:52:43.853262Z","end":"2026-05-29T21:52:44.54426Z","steps":["trace[688827175] 'agreement among raft nodes before linearized reading' (duration: 688.668668ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.544673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:43.853249Z","time spent":"691.331647ms","remote":"127.0.0.1:56920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-05-29T21:52:44.942153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.987007ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-29T21:52:44.942363Z","caller":"traceutil/trace.go:171","msg":"trace[1879136236] linearizableReadLoop","detail":"{readStateIndex:17; appliedIndex:14; }","duration":"401.394093ms","start":"2026-05-29T21:52:44.540951Z","end":"2026-05-29T21:52:44.942345Z","steps":["trace[1879136236] 'read index received' (duration: 106.104992ms)","trace[1879136236] 'applied index is now lower than readState.Index' (duration: 295.287941ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:44.942419Z","caller":"traceutil/trace.go:171","msg":"trace[1949063013] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"807.267009ms","start":"2026-05-29T21:52:44.135072Z","end":"2026-05-29T21:52:44.942339Z","steps":["trace[1949063013] 'process raft request' (duration: 512.016767ms)","trace[1949063013] 'compare' (duration: 294.802639ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:52:44.942549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.135043Z","time spent":"807.450636ms","remote":"127.0.0.1:56676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:52:44.942637Z","caller":"traceutil/trace.go:171","msg":"trace[1817181475] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"803.616699ms","start":"2026-05-29T21:52:44.13901Z","end":"2026-05-29T21:52:44.942627Z","steps":["trace[1817181475] 'process raft request' (duration: 803.278456ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:44.942641Z","caller":"traceutil/trace.go:171","msg":"trace[1306352816] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12; }","duration":"805.162649ms","start":"2026-05-29T21:52:44.137454Z","end":"2026-05-29T21:52:44.942616Z","steps":["trace[1306352816] 'process raft request' (duration: 804.803276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.942712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.138964Z","time spent":"803.710627ms","remote":"127.0.0.1:56966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:52:44.942719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.137435Z","time spent":"805.249148ms","remote":"127.0.0.1:56676","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:52:44.942799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.073273007s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-05-29T21:52:44.942802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.991583ms","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-05-29T21:52:44.942837Z","caller":"traceutil/trace.go:171","msg":"trace[1613769149] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"1.073334045s","start":"2026-05-29T21:52:43.869494Z","end":"2026-05-29T21:52:44.942828Z","steps":["trace[1613769149] 'agreement among raft nodes before linearized reading' (duration: 1.073258946s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.942867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:43.86948Z","time spent":"1.073380904s","remote":"127.0.0.1:56676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-29T21:52:44.942884Z","caller":"traceutil/trace.go:171","msg":"trace[54665281] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:13; }","duration":"951.083852ms","start":"2026-05-29T21:52:43.991764Z","end":"2026-05-29T21:52:44.942848Z","steps":["trace[54665281] 'agreement among raft nodes before linearized reading' (duration: 950.882896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.942911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.315327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-29T21:52:44.942925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:43.991699Z","time spent":"951.218199ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"info","ts":"2026-05-29T21:52:44.94295Z","caller":"traceutil/trace.go:171","msg":"trace[886341166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13; }","duration":"807.388355ms","start":"2026-05-29T21:52:44.135542Z","end":"2026-05-29T21:52:44.942931Z","steps":["trace[886341166] 'agreement among raft nodes before linearized reading' (duration: 807.326746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.942976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.135518Z","time spent":"807.453284ms","remote":"127.0.0.1:56578","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-05-29T21:52:44.946322Z","caller":"traceutil/trace.go:171","msg":"trace[1798084321] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"389.557281ms","start":"2026-05-29T21:52:44.55675Z","end":"2026-05-29T21:52:44.946308Z","steps":["trace[1798084321] 'process raft request' (duration: 389.503012ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:44.946553Z","caller":"traceutil/trace.go:171","msg":"trace[1488660919] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"394.918667ms","start":"2026-05-29T21:52:44.551616Z","end":"2026-05-29T21:52:44.946535Z","steps":["trace[1488660919] 'process raft request' (duration: 394.544124ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:44.946608Z","caller":"traceutil/trace.go:171","msg":"trace[1695304291] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"399.39903ms","start":"2026-05-29T21:52:44.547178Z","end":"2026-05-29T21:52:44.946577Z","steps":["trace[1695304291] 'process raft request' (duration: 398.88303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.946705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.547164Z","time spent":"399.485249ms","remote":"127.0.0.1:57042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:52:44.946685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.55673Z","time spent":"389.662628ms","remote":"127.0.0.1:56734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3478,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:52:44.946852Z","caller":"traceutil/trace.go:171","msg":"trace[1826339003] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"395.81004ms","start":"2026-05-29T21:52:44.551008Z","end":"2026-05-29T21:52:44.946818Z","steps":["trace[1826339003] 'process raft request' (duration: 395.107913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.946898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.694606ms","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-05-29T21:52:44.946928Z","caller":"traceutil/trace.go:171","msg":"trace[1241791924] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"395.241301ms","start":"2026-05-29T21:52:44.551675Z","end":"2026-05-29T21:52:44.946916Z","steps":["trace[1241791924] 'process raft request' (duration: 394.517295ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:44.946943Z","caller":"traceutil/trace.go:171","msg":"trace[2083639736] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:19; }","duration":"229.771784ms","start":"2026-05-29T21:52:44.71716Z","end":"2026-05-29T21:52:44.946932Z","steps":["trace[2083639736] 'agreement among raft nodes before linearized reading' (duration: 229.659667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.946988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.551659Z","time spent":"395.30346ms","remote":"127.0.0.1:57042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:52:44.947103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.550981Z","time spent":"395.917108ms","remote":"127.0.0.1:57042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:52:44.947159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.160757ms","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-05-29T21:52:44.947202Z","caller":"traceutil/trace.go:171","msg":"trace[1600009436] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"395.488897ms","start":"2026-05-29T21:52:44.55169Z","end":"2026-05-29T21:52:44.947179Z","steps":["trace[1600009436] 'process raft request' (duration: 394.525465ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:44.947234Z","caller":"traceutil/trace.go:171","msg":"trace[1644560024] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:19; }","duration":"230.274635ms","start":"2026-05-29T21:52:44.716921Z","end":"2026-05-29T21:52:44.947196Z","steps":["trace[1644560024] 'agreement among raft nodes before linearized reading' (duration: 230.202517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.947415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.209915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-hhhhv\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-05-29T21:52:44.94753Z","caller":"traceutil/trace.go:171","msg":"trace[395795963] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-hhhhv; range_end:; response_count:1; response_revision:19; }","duration":"401.351983ms","start":"2026-05-29T21:52:44.546165Z","end":"2026-05-29T21:52:44.947517Z","steps":["trace[395795963] 'agreement among raft nodes before linearized reading' (duration: 401.201276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.947568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.546146Z","time spent":"401.413032ms","remote":"127.0.0.1:56792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-hhhhv\" "} {"level":"warn","ts":"2026-05-29T21:52:44.94726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.551674Z","time spent":"395.559624ms","remote":"127.0.0.1:57042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:52:44.947734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.807121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-05-29T21:52:44.947766Z","caller":"traceutil/trace.go:171","msg":"trace[1128418711] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"395.86033ms","start":"2026-05-29T21:52:44.551898Z","end":"2026-05-29T21:52:44.947758Z","steps":["trace[1128418711] 'agreement among raft nodes before linearized reading' (duration: 395.801641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:44.947799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.551887Z","time spent":"395.901179ms","remote":"127.0.0.1:56734","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-05-29T21:52:44.946707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.551606Z","time spent":"395.036055ms","remote":"127.0.0.1:57042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:52:45.279021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.243769ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-05-29T21:52:45.279435Z","caller":"traceutil/trace.go:171","msg":"trace[59589375] linearizableReadLoop","detail":"{readStateIndex:26; appliedIndex:23; }","duration":"332.603205ms","start":"2026-05-29T21:52:44.946798Z","end":"2026-05-29T21:52:45.279402Z","steps":["trace[59589375] 'read index received' (duration: 123.931533ms)","trace[59589375] 'applied index is now lower than readState.Index' (duration: 208.670712ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:45.279518Z","caller":"traceutil/trace.go:171","msg":"trace[3520006] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"334.301832ms","start":"2026-05-29T21:52:44.945183Z","end":"2026-05-29T21:52:45.279457Z","steps":["trace[3520006] 'process raft request' (duration: 125.531132ms)","trace[3520006] 'compare' (duration: 208.100862ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:45.279589Z","caller":"traceutil/trace.go:171","msg":"trace[1642781035] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"333.191994ms","start":"2026-05-29T21:52:44.946381Z","end":"2026-05-29T21:52:45.279573Z","steps":["trace[1642781035] 'process raft request' (duration: 332.791221ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:45.279651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.945169Z","time spent":"334.4145ms","remote":"127.0.0.1:56670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:52:45.279698Z","caller":"traceutil/trace.go:171","msg":"trace[1199751041] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"327.66424ms","start":"2026-05-29T21:52:44.952027Z","end":"2026-05-29T21:52:45.279691Z","steps":["trace[1199751041] 'process raft request' (duration: 327.291347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:45.279741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.951938Z","time spent":"327.785298ms","remote":"127.0.0.1:56896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:52:45.279753Z","caller":"traceutil/trace.go:171","msg":"trace[1480864396] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"327.958495ms","start":"2026-05-29T21:52:44.951781Z","end":"2026-05-29T21:52:45.27974Z","steps":["trace[1480864396] 'process raft request' (duration: 327.419395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:45.279867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.951763Z","time spent":"328.040713ms","remote":"127.0.0.1:56920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:52:45.279954Z","caller":"traceutil/trace.go:171","msg":"trace[547175707] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"327.809568ms","start":"2026-05-29T21:52:44.952136Z","end":"2026-05-29T21:52:45.279945Z","steps":["trace[547175707] 'process raft request' (duration: 327.213609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:45.280008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.952128Z","time spent":"327.857736ms","remote":"127.0.0.1:57042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:52:45.279662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.946365Z","time spent":"333.267953ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:52:45.280127Z","caller":"traceutil/trace.go:171","msg":"trace[1643061647] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"328.19535ms","start":"2026-05-29T21:52:44.951924Z","end":"2026-05-29T21:52:45.28012Z","steps":["trace[1643061647] 'process raft request' (duration: 327.343755ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:45.280145Z","caller":"traceutil/trace.go:171","msg":"trace[1661778501] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"327.70059ms","start":"2026-05-29T21:52:44.952435Z","end":"2026-05-29T21:52:45.280135Z","steps":["trace[1661778501] 'process raft request' (duration: 326.936184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:45.280192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.952428Z","time spent":"327.745369ms","remote":"127.0.0.1:57042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:52:45.28021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.951918Z","time spent":"328.245579ms","remote":"127.0.0.1:57042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:52:45.280377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.333594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-29T21:52:45.280416Z","caller":"traceutil/trace.go:171","msg":"trace[2142730143] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:29; }","duration":"336.398093ms","start":"2026-05-29T21:52:44.944008Z","end":"2026-05-29T21:52:45.280406Z","steps":["trace[2142730143] 'agreement among raft nodes before linearized reading' (duration: 336.285045ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:45.280413Z","caller":"traceutil/trace.go:171","msg":"trace[1842748773] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"328.434856ms","start":"2026-05-29T21:52:44.951966Z","end":"2026-05-29T21:52:45.280401Z","steps":["trace[1842748773] 'process raft request' (duration: 327.327516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:45.280444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.943994Z","time spent":"336.444432ms","remote":"127.0.0.1:56676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-29T21:52:45.280094Z","caller":"traceutil/trace.go:171","msg":"trace[249356422] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"328.21807ms","start":"2026-05-29T21:52:44.951865Z","end":"2026-05-29T21:52:45.280083Z","steps":["trace[249356422] 'process raft request' (duration: 327.365195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:45.280474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.951935Z","time spent":"328.508135ms","remote":"127.0.0.1:57042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:52:45.280541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.951854Z","time spent":"328.634862ms","remote":"127.0.0.1:57042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:52:45.280609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.239924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-29T21:52:45.280641Z","caller":"traceutil/trace.go:171","msg":"trace[1652877363] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:29; }","duration":"333.302922ms","start":"2026-05-29T21:52:44.947328Z","end":"2026-05-29T21:52:45.280631Z","steps":["trace[1652877363] 'agreement among raft nodes before linearized reading' (duration: 333.251133ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:45.280674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.947311Z","time spent":"333.354412ms","remote":"127.0.0.1:56664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"warn","ts":"2026-05-29T21:52:45.281032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.385343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-29T21:52:45.281088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.563716ms","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-05-29T21:52:45.281104Z","caller":"traceutil/trace.go:171","msg":"trace[1055204178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:29; }","duration":"235.48683ms","start":"2026-05-29T21:52:45.045597Z","end":"2026-05-29T21:52:45.281084Z","steps":["trace[1055204178] 'agreement among raft nodes before linearized reading' (duration: 235.362691ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:45.281131Z","caller":"traceutil/trace.go:171","msg":"trace[924595677] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:29; }","duration":"330.598896ms","start":"2026-05-29T21:52:44.950514Z","end":"2026-05-29T21:52:45.281113Z","steps":["trace[924595677] 'agreement among raft nodes before linearized reading' (duration: 330.543857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:45.281175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.950486Z","time spent":"330.681064ms","remote":"127.0.0.1:56884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"info","ts":"2026-05-29T21:52:45.279418Z","caller":"traceutil/trace.go:171","msg":"trace[1089202620] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"333.391149ms","start":"2026-05-29T21:52:44.946Z","end":"2026-05-29T21:52:45.279391Z","steps":["trace[1089202620] 'process raft request' (duration: 333.115604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:45.282769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:44.945977Z","time spent":"336.753679ms","remote":"127.0.0.1:56966","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:52:45.942265Z","caller":"traceutil/trace.go:171","msg":"trace[1839365509] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"479.120053ms","start":"2026-05-29T21:52:45.463115Z","end":"2026-05-29T21:52:45.942235Z","steps":["trace[1839365509] 'process raft request' (duration: 478.944315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:45.942406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:45.463097Z","time spent":"479.245431ms","remote":"127.0.0.1:56868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":621,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:52:45.979671Z","caller":"traceutil/trace.go:171","msg":"trace[757253630] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"515.686885ms","start":"2026-05-29T21:52:45.463959Z","end":"2026-05-29T21:52:45.979646Z","steps":["trace[757253630] 'process raft request' (duration: 515.307172ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:45.979713Z","caller":"traceutil/trace.go:171","msg":"trace[43991871] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"516.414822ms","start":"2026-05-29T21:52:45.46327Z","end":"2026-05-29T21:52:45.979685Z","steps":["trace[43991871] 'process raft request' (duration: 515.872392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:45.979933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:45.463947Z","time spent":"515.845822ms","remote":"127.0.0.1:56962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1059,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:52:45.980097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:45.463257Z","time spent":"516.750856ms","remote":"127.0.0.1:56962","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":796,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:52:46.13986Z","caller":"traceutil/trace.go:171","msg":"trace[627491759] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"157.463526ms","start":"2026-05-29T21:52:45.982369Z","end":"2026-05-29T21:52:46.139833Z","steps":["trace[627491759] 'process raft request' (duration: 75.57725ms)","trace[627491759] 'compare' (duration: 81.751088ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:46.143919Z","caller":"traceutil/trace.go:171","msg":"trace[644415616] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"159.025374ms","start":"2026-05-29T21:52:45.984876Z","end":"2026-05-29T21:52:46.143901Z","steps":["trace[644415616] 'process raft request' (duration: 158.902036ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:46.144155Z","caller":"traceutil/trace.go:171","msg":"trace[776243731] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"160.129215ms","start":"2026-05-29T21:52:45.984013Z","end":"2026-05-29T21:52:46.144143Z","steps":["trace[776243731] 'process raft request' (duration: 159.660552ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:48.143509Z","caller":"traceutil/trace.go:171","msg":"trace[1442426454] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"133.767676ms","start":"2026-05-29T21:52:48.009721Z","end":"2026-05-29T21:52:48.143489Z","steps":["trace[1442426454] 'process raft request' (duration: 68.588424ms)","trace[1442426454] 'compare' (duration: 64.815507ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:48.558415Z","caller":"traceutil/trace.go:171","msg":"trace[562559840] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"120.139908ms","start":"2026-05-29T21:52:48.438247Z","end":"2026-05-29T21:52:48.558387Z","steps":["trace[562559840] 'process raft request' (duration: 78.970558ms)","trace[562559840] 'compare' (duration: 41.018393ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:52:49.201154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.940137ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:52:49.201269Z","caller":"traceutil/trace.go:171","msg":"trace[1355472384] transaction","detail":"{read_only:false; response_revision:217; number_of_response:1; }","duration":"179.053305ms","start":"2026-05-29T21:52:49.02219Z","end":"2026-05-29T21:52:49.201244Z","steps":["trace[1355472384] 'process raft request' (duration: 70.93581ms)","trace[1355472384] 'compare' (duration: 107.796149ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:49.201634Z","caller":"traceutil/trace.go:171","msg":"trace[1685180988] linearizableReadLoop","detail":"{readStateIndex:224; appliedIndex:222; }","duration":"179.192783ms","start":"2026-05-29T21:52:49.022413Z","end":"2026-05-29T21:52:49.201606Z","steps":["trace[1685180988] 'read index received' (duration: 70.709793ms)","trace[1685180988] 'applied index is now lower than readState.Index' (duration: 108.48186ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:52:49.201799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.361781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:52:49.201847Z","caller":"traceutil/trace.go:171","msg":"trace[1758379740] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:219; }","duration":"179.44296ms","start":"2026-05-29T21:52:49.022393Z","end":"2026-05-29T21:52:49.201836Z","steps":["trace[1758379740] 'agreement among raft nodes before linearized reading' (duration: 179.326171ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:49.201881Z","caller":"traceutil/trace.go:171","msg":"trace[1653829222] transaction","detail":"{read_only:false; response_revision:218; number_of_response:1; }","duration":"179.477409ms","start":"2026-05-29T21:52:49.022309Z","end":"2026-05-29T21:52:49.201786Z","steps":["trace[1653829222] 'process raft request' (duration: 179.144294ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:49.201862Z","caller":"traceutil/trace.go:171","msg":"trace[1789784324] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"178.499332ms","start":"2026-05-29T21:52:49.023328Z","end":"2026-05-29T21:52:49.201828Z","steps":["trace[1789784324] 'process raft request' (duration: 178.222466ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:49.374708Z","caller":"traceutil/trace.go:171","msg":"trace[624919875] linearizableReadLoop","detail":"{readStateIndex:227; appliedIndex:225; }","duration":"118.908517ms","start":"2026-05-29T21:52:49.255772Z","end":"2026-05-29T21:52:49.374681Z","steps":["trace[624919875] 'read index received' (duration: 7.539294ms)","trace[624919875] 'applied index is now lower than readState.Index' (duration: 111.368243ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:49.374797Z","caller":"traceutil/trace.go:171","msg":"trace[254681351] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"167.723351ms","start":"2026-05-29T21:52:49.207036Z","end":"2026-05-29T21:52:49.37476Z","steps":["trace[254681351] 'process raft request' (duration: 125.932046ms)","trace[254681351] 'compare' (duration: 41.590317ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:52:49.374915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.120433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-05-29T21:52:49.374994Z","caller":"traceutil/trace.go:171","msg":"trace[1940515287] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:221; }","duration":"119.263272ms","start":"2026-05-29T21:52:49.255718Z","end":"2026-05-29T21:52:49.374981Z","steps":["trace[1940515287] 'agreement among raft nodes before linearized reading' (duration: 119.101684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:49.375065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.925274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18b427d6e60827fa\" ","response":"range_response_count:1 size:657"} {"level":"info","ts":"2026-05-29T21:52:49.375192Z","caller":"traceutil/trace.go:171","msg":"trace[976170676] range","detail":"{range_begin:/registry/events/default/instance.18b427d6e60827fa; range_end:; response_count:1; response_revision:221; }","duration":"109.055523ms","start":"2026-05-29T21:52:49.266097Z","end":"2026-05-29T21:52:49.375152Z","steps":["trace[976170676] 'agreement among raft nodes before linearized reading' (duration: 108.824286ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:49.593479Z","caller":"traceutil/trace.go:171","msg":"trace[1789639102] linearizableReadLoop","detail":"{readStateIndex:235; appliedIndex:234; }","duration":"152.757843ms","start":"2026-05-29T21:52:49.440704Z","end":"2026-05-29T21:52:49.593462Z","steps":["trace[1789639102] 'read index received' (duration: 60.613784ms)","trace[1789639102] 'applied index is now lower than readState.Index' (duration: 92.143259ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:49.593539Z","caller":"traceutil/trace.go:171","msg":"trace[48440669] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"153.993306ms","start":"2026-05-29T21:52:49.439514Z","end":"2026-05-29T21:52:49.593508Z","steps":["trace[48440669] 'process raft request' (duration: 61.853637ms)","trace[48440669] 'compare' (duration: 92.028931ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:52:49.593627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.630364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:52:49.593657Z","caller":"traceutil/trace.go:171","msg":"trace[155671860] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:229; }","duration":"152.687503ms","start":"2026-05-29T21:52:49.440963Z","end":"2026-05-29T21:52:49.593651Z","steps":["trace[155671860] 'agreement among raft nodes before linearized reading' (duration: 152.606224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:49.593747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.01139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-ubta78\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-05-29T21:52:49.593907Z","caller":"traceutil/trace.go:171","msg":"trace[1142185334] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-ubta78; range_end:; response_count:1; response_revision:229; }","duration":"153.211697ms","start":"2026-05-29T21:52:49.440677Z","end":"2026-05-29T21:52:49.593889Z","steps":["trace[1142185334] 'agreement among raft nodes before linearized reading' (duration: 152.872642ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:51.575769Z","caller":"traceutil/trace.go:171","msg":"trace[1275013787] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"245.478966ms","start":"2026-05-29T21:52:51.330262Z","end":"2026-05-29T21:52:51.575741Z","steps":["trace[1275013787] 'process raft request' (duration: 221.175607ms)","trace[1275013787] 'compare' (duration: 24.144621ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:52.149019Z","caller":"traceutil/trace.go:171","msg":"trace[2069040214] linearizableReadLoop","detail":"{readStateIndex:336; appliedIndex:335; }","duration":"104.921358ms","start":"2026-05-29T21:52:52.04407Z","end":"2026-05-29T21:52:52.148991Z","steps":["trace[2069040214] 'read index received' (duration: 27.567508ms)","trace[2069040214] 'applied index is now lower than readState.Index' (duration: 77.35256ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:52:52.149167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.072307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-29T21:52:52.149211Z","caller":"traceutil/trace.go:171","msg":"trace[1943443404] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:326; }","duration":"105.152956ms","start":"2026-05-29T21:52:52.044045Z","end":"2026-05-29T21:52:52.149198Z","steps":["trace[1943443404] 'agreement among raft nodes before linearized reading' (duration: 105.059617ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:52:52.149271Z","caller":"traceutil/trace.go:171","msg":"trace[879232055] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"158.544007ms","start":"2026-05-29T21:52:51.99067Z","end":"2026-05-29T21:52:52.149214Z","steps":["trace[879232055] 'process raft request' (duration: 80.979339ms)","trace[879232055] 'compare' (duration: 77.211281ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:52.407376Z","caller":"traceutil/trace.go:171","msg":"trace[964596750] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"169.10643ms","start":"2026-05-29T21:52:52.238247Z","end":"2026-05-29T21:52:52.407354Z","steps":["trace[964596750] 'process raft request' (duration: 102.219164ms)","trace[964596750] 'compare' (duration: 66.793457ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:52:52.40775Z","caller":"traceutil/trace.go:171","msg":"trace[1844335971] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:339; }","duration":"127.769169ms","start":"2026-05-29T21:52:52.27997Z","end":"2026-05-29T21:52:52.407739Z","steps":["trace[1844335971] 'read index received' (duration: 60.487096ms)","trace[1844335971] 'applied index is now lower than readState.Index' (duration: 66.873526ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:52:52.407766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.780149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:52:52.407989Z","caller":"traceutil/trace.go:171","msg":"trace[1912325971] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:330; }","duration":"128.074067ms","start":"2026-05-29T21:52:52.279898Z","end":"2026-05-29T21:52:52.407972Z","steps":["trace[1912325971] 'agreement among raft nodes before linearized reading' (duration: 127.814409ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:58.534191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.385153ms","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-05-29T21:52:58.534298Z","caller":"traceutil/trace.go:171","msg":"trace[1069700382] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:410; }","duration":"844.526483ms","start":"2026-05-29T21:52:57.689747Z","end":"2026-05-29T21:52:58.534273Z","steps":["trace[1069700382] 'range keys from in-memory index tree' (duration: 844.229824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:52:58.534356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:52:57.68973Z","time spent":"844.610373ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T21:52:58.534819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.073589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-29T21:52:58.534879Z","caller":"traceutil/trace.go:171","msg":"trace[547886283] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:410; }","duration":"187.168019ms","start":"2026-05-29T21:52:58.347697Z","end":"2026-05-29T21:52:58.534865Z","steps":["trace[547886283] 'range keys from in-memory index tree' (duration: 186.984729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:03.662472Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393688150280099,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T21:53:04.030993Z","caller":"traceutil/trace.go:171","msg":"trace[1078150309] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"928.088839ms","start":"2026-05-29T21:53:03.102882Z","end":"2026-05-29T21:53:04.030971Z","steps":["trace[1078150309] 'process raft request' (duration: 927.994669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:04.031105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:03.102867Z","time spent":"928.18436ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:53:04.031185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.747054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:04.031264Z","caller":"traceutil/trace.go:171","msg":"trace[770484615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"869.848484ms","start":"2026-05-29T21:53:03.161405Z","end":"2026-05-29T21:53:04.031253Z","steps":["trace[770484615] 'agreement among raft nodes before linearized reading' (duration: 869.697894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:04.031309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:03.161345Z","time spent":"869.956564ms","remote":"127.0.0.1:56562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T21:53:04.031631Z","caller":"traceutil/trace.go:171","msg":"trace[351036157] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:439; }","duration":"869.627124ms","start":"2026-05-29T21:53:03.161411Z","end":"2026-05-29T21:53:04.031038Z","steps":["trace[351036157] 'read index received' (duration: 869.543763ms)","trace[351036157] 'applied index is now lower than readState.Index' (duration: 80.721µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:53:04.321236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.671847ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T21:53:04.321388Z","caller":"traceutil/trace.go:171","msg":"trace[132361387] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"289.60643ms","start":"2026-05-29T21:53:04.031715Z","end":"2026-05-29T21:53:04.321322Z","steps":["trace[132361387] 'read index received' (duration: 18.766652ms)","trace[132361387] 'applied index is now lower than readState.Index' (duration: 270.838168ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:53:04.321525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037021168s","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-05-29T21:53:04.321621Z","caller":"traceutil/trace.go:171","msg":"trace[1192944819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:427; }","duration":"1.037140459s","start":"2026-05-29T21:53:03.28446Z","end":"2026-05-29T21:53:04.3216Z","steps":["trace[1192944819] 'agreement among raft nodes before linearized reading' (duration: 1.036897938s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:04.321657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.050121015s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-29T21:53:04.321673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:03.284455Z","time spent":"1.037207889s","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T21:53:04.321775Z","caller":"traceutil/trace.go:171","msg":"trace[524723783] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:427; }","duration":"1.050270855s","start":"2026-05-29T21:53:03.27149Z","end":"2026-05-29T21:53:04.321761Z","steps":["trace[524723783] 'agreement among raft nodes before linearized reading' (duration: 1.050053325s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:04.321915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.570771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:04.322108Z","caller":"traceutil/trace.go:171","msg":"trace[1278780719] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"289.704181ms","start":"2026-05-29T21:53:04.03226Z","end":"2026-05-29T21:53:04.321964Z","steps":["trace[1278780719] 'agreement among raft nodes before linearized reading' (duration: 289.50858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:04.32202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:03.271476Z","time spent":"1.050362826s","remote":"127.0.0.1:56800","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":"info","ts":"2026-05-29T21:53:12.223272Z","caller":"traceutil/trace.go:171","msg":"trace[468855550] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"118.613262ms","start":"2026-05-29T21:53:12.104634Z","end":"2026-05-29T21:53:12.223248Z","steps":["trace[468855550] 'process raft request' (duration: 118.45226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:13.185874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.354783ms","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-05-29T21:53:13.185933Z","caller":"traceutil/trace.go:171","msg":"trace[2014249759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:477; }","duration":"816.459024ms","start":"2026-05-29T21:53:12.36946Z","end":"2026-05-29T21:53:13.185919Z","steps":["trace[2014249759] 'range keys from in-memory index tree' (duration: 816.248211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:13.185968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:12.36944Z","time spent":"816.511585ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T21:53:13.18604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.738206ms","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-05-29T21:53:13.186123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:12.530474Z","time spent":"655.647887ms","remote":"127.0.0.1:33636","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-29T21:53:13.186178Z","caller":"traceutil/trace.go:171","msg":"trace[749143128] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:477; }","duration":"441.883117ms","start":"2026-05-29T21:53:12.744241Z","end":"2026-05-29T21:53:13.186124Z","steps":["trace[749143128] 'range keys from in-memory index tree' (duration: 441.569164ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:13.186253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:12.744222Z","time spent":"442.01825ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-29T21:53:13.186101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.726277ms","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-05-29T21:53:13.186714Z","caller":"traceutil/trace.go:171","msg":"trace[1874707196] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"785.316825ms","start":"2026-05-29T21:53:12.401333Z","end":"2026-05-29T21:53:13.18665Z","steps":["trace[1874707196] 'range keys from in-memory index tree' (duration: 784.612526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:13.186956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:12.401313Z","time spent":"785.544767ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T21:53:13.317969Z","caller":"traceutil/trace.go:171","msg":"trace[558081611] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"128.371522ms","start":"2026-05-29T21:53:13.189574Z","end":"2026-05-29T21:53:13.317945Z","steps":["trace[558081611] 'process raft request' (duration: 128.240731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:13.656085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.565489ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:53:13.656305Z","caller":"traceutil/trace.go:171","msg":"trace[1696642070] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"466.122947ms","start":"2026-05-29T21:53:13.190159Z","end":"2026-05-29T21:53:13.656282Z","steps":["trace[1696642070] 'process raft request' (duration: 364.252445ms)","trace[1696642070] 'compare' (duration: 101.390867ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:53:13.656409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:13.190137Z","time spent":"466.213928ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:53:13.656328Z","caller":"traceutil/trace.go:171","msg":"trace[868677759] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"465.247966ms","start":"2026-05-29T21:53:13.19101Z","end":"2026-05-29T21:53:13.656258Z","steps":["trace[868677759] 'process raft request' (duration: 465.186565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:13.656687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:13.190984Z","time spent":"465.66086ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:53:14.81106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.698989ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-29T21:53:14.811172Z","caller":"traceutil/trace.go:171","msg":"trace[2119131020] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"649.399092ms","start":"2026-05-29T21:53:14.161746Z","end":"2026-05-29T21:53:14.811145Z","steps":["trace[2119131020] 'read index received' (duration: 219.672263ms)","trace[2119131020] 'applied index is now lower than readState.Index' (duration: 429.725499ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:53:14.811316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.952038ms","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-05-29T21:53:14.811393Z","caller":"traceutil/trace.go:171","msg":"trace[744381240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:480; }","duration":"585.05816ms","start":"2026-05-29T21:53:14.226325Z","end":"2026-05-29T21:53:14.811383Z","steps":["trace[744381240] 'agreement among raft nodes before linearized reading' (duration: 584.928898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:14.811433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:14.226311Z","time spent":"585.111421ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-29T21:53:14.811377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.580815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:14.811806Z","caller":"traceutil/trace.go:171","msg":"trace[59512957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:480; }","duration":"650.085002ms","start":"2026-05-29T21:53:14.161696Z","end":"2026-05-29T21:53:14.811781Z","steps":["trace[59512957] 'agreement among raft nodes before linearized reading' (duration: 649.521534ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:14.811894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:14.161676Z","time spent":"650.203903ms","remote":"127.0.0.1:56562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T21:53:16.004568Z","caller":"traceutil/trace.go:171","msg":"trace[1225014692] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"136.569831ms","start":"2026-05-29T21:53:15.867964Z","end":"2026-05-29T21:53:16.004534Z","steps":["trace[1225014692] 'process raft request' (duration: 136.413719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:25.663197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393688150280341,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-29T21:53:25.908833Z","caller":"traceutil/trace.go:171","msg":"trace[190248147] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"984.054031ms","start":"2026-05-29T21:53:24.924751Z","end":"2026-05-29T21:53:25.908805Z","steps":["trace[190248147] 'process raft request' (duration: 983.794416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:25.909173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:24.924711Z","time spent":"984.291357ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:53:25.93491Z","caller":"traceutil/trace.go:171","msg":"trace[414444797] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"920.505648ms","start":"2026-05-29T21:53:25.014376Z","end":"2026-05-29T21:53:25.934881Z","steps":["trace[414444797] 'process raft request' (duration: 920.390015ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:25.934911Z","caller":"traceutil/trace.go:171","msg":"trace[468971720] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"925.967024ms","start":"2026-05-29T21:53:25.008901Z","end":"2026-05-29T21:53:25.934868Z","steps":["trace[468971720] 'process raft request' (duration: 925.74158ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:25.935034Z","caller":"traceutil/trace.go:171","msg":"trace[1036446555] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"910.599324ms","start":"2026-05-29T21:53:25.0244Z","end":"2026-05-29T21:53:25.934999Z","steps":["trace[1036446555] 'process raft request' (duration: 910.461971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:25.935066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:25.008863Z","time spent":"926.126268ms","remote":"127.0.0.1:56638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:53:25.935101Z","caller":"traceutil/trace.go:171","msg":"trace[2055779964] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"910.028343ms","start":"2026-05-29T21:53:25.025039Z","end":"2026-05-29T21:53:25.935068Z","steps":["trace[2055779964] 'process raft request' (duration: 909.865939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:25.93516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:25.024387Z","time spent":"910.714996ms","remote":"127.0.0.1:56638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:53:25.935227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:25.025025Z","time spent":"910.151275ms","remote":"127.0.0.1:56638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:53:25.935388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:25.014352Z","time spent":"920.606199ms","remote":"127.0.0.1:56638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:53:25.935321Z","caller":"traceutil/trace.go:171","msg":"trace[1249240324] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"911.906632ms","start":"2026-05-29T21:53:25.023396Z","end":"2026-05-29T21:53:25.935303Z","steps":["trace[1249240324] 'process raft request' (duration: 911.421041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:25.935526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:25.023365Z","time spent":"912.077615ms","remote":"127.0.0.1:56638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:53:25.935835Z","caller":"traceutil/trace.go:171","msg":"trace[1973548104] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"909.06015ms","start":"2026-05-29T21:53:25.026749Z","end":"2026-05-29T21:53:25.935809Z","steps":["trace[1973548104] 'process raft request' (duration: 908.783514ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:25.935907Z","caller":"traceutil/trace.go:171","msg":"trace[1959303065] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"905.531296ms","start":"2026-05-29T21:53:25.030363Z","end":"2026-05-29T21:53:25.935894Z","steps":["trace[1959303065] 'process raft request' (duration: 905.369573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:25.93595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:25.026731Z","time spent":"909.165223ms","remote":"127.0.0.1:56638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:53:25.935978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:25.030355Z","time spent":"905.590017ms","remote":"127.0.0.1:56638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:53:25.93609Z","caller":"traceutil/trace.go:171","msg":"trace[188076161] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"902.47979ms","start":"2026-05-29T21:53:25.033596Z","end":"2026-05-29T21:53:25.936076Z","steps":["trace[188076161] 'process raft request' (duration: 902.195654ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:25.936206Z","caller":"traceutil/trace.go:171","msg":"trace[73985929] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"897.479082ms","start":"2026-05-29T21:53:25.038718Z","end":"2026-05-29T21:53:25.936197Z","steps":["trace[73985929] 'process raft request' (duration: 897.130525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:25.936252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:25.038704Z","time spent":"897.529824ms","remote":"127.0.0.1:56638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:53:25.936354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:25.033564Z","time spent":"902.586022ms","remote":"127.0.0.1:56638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:53:25.936736Z","caller":"traceutil/trace.go:171","msg":"trace[1715451546] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"877.452264ms","start":"2026-05-29T21:53:25.059266Z","end":"2026-05-29T21:53:25.936718Z","steps":["trace[1715451546] 'process raft request' (duration: 876.932152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:25.936809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:25.059236Z","time spent":"877.539347ms","remote":"127.0.0.1:56638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:53:25.936852Z","caller":"traceutil/trace.go:171","msg":"trace[2089123443] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"848.530395ms","start":"2026-05-29T21:53:25.088303Z","end":"2026-05-29T21:53:25.936833Z","steps":["trace[2089123443] 'process raft request' (duration: 848.359171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:25.936942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:25.088283Z","time spent":"848.622206ms","remote":"127.0.0.1:56638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:53:25.937105Z","caller":"traceutil/trace.go:171","msg":"trace[393966166] linearizableReadLoop","detail":"{readStateIndex:570; appliedIndex:557; }","duration":"774.528079ms","start":"2026-05-29T21:53:25.162546Z","end":"2026-05-29T21:53:25.937075Z","steps":["trace[393966166] 'read index received' (duration: 745.945536ms)","trace[393966166] 'applied index is now lower than readState.Index' (duration: 28.581563ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:53:25.937397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.854445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:25.937438Z","caller":"traceutil/trace.go:171","msg":"trace[213506532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:551; }","duration":"774.971288ms","start":"2026-05-29T21:53:25.162455Z","end":"2026-05-29T21:53:25.937427Z","steps":["trace[213506532] 'agreement among raft nodes before linearized reading' (duration: 774.831525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:25.937467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:25.162396Z","time spent":"775.06462ms","remote":"127.0.0.1:56562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:53:25.93751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.152788ms","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-05-29T21:53:25.93757Z","caller":"traceutil/trace.go:171","msg":"trace[1767323642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"164.347952ms","start":"2026-05-29T21:53:25.773211Z","end":"2026-05-29T21:53:25.937559Z","steps":["trace[1767323642] 'agreement among raft nodes before linearized reading' (duration: 164.23711ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:25.937145Z","caller":"traceutil/trace.go:171","msg":"trace[1774869586] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"728.015872ms","start":"2026-05-29T21:53:25.209093Z","end":"2026-05-29T21:53:25.937109Z","steps":["trace[1774869586] 'process raft request' (duration: 727.647084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:25.937635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.319223ms","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-05-29T21:53:25.937691Z","caller":"traceutil/trace.go:171","msg":"trace[393467023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"515.439316ms","start":"2026-05-29T21:53:25.422236Z","end":"2026-05-29T21:53:25.937676Z","steps":["trace[393467023] 'agreement among raft nodes before linearized reading' (duration: 515.212611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:25.937731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:25.422215Z","time spent":"515.507058ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-29T21:53:25.937732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.717668ms","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-05-29T21:53:25.93777Z","caller":"traceutil/trace.go:171","msg":"trace[1081123077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:551; }","duration":"169.792028ms","start":"2026-05-29T21:53:25.767967Z","end":"2026-05-29T21:53:25.937759Z","steps":["trace[1081123077] 'agreement among raft nodes before linearized reading' (duration: 169.716477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:25.937828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:25.20906Z","time spent":"728.718297ms","remote":"127.0.0.1:56638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:53:30.610177Z","caller":"traceutil/trace.go:171","msg":"trace[2121770491] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"242.271716ms","start":"2026-05-29T21:53:30.367889Z","end":"2026-05-29T21:53:30.610161Z","steps":["trace[2121770491] 'process raft request' (duration: 241.927937ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:32.65669Z","caller":"traceutil/trace.go:171","msg":"trace[1538707692] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"571.839285ms","start":"2026-05-29T21:53:32.084811Z","end":"2026-05-29T21:53:32.65665Z","steps":["trace[1538707692] 'process raft request' (duration: 571.514036ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:32.656884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:32.084789Z","time spent":"571.993798ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:53:32.657794Z","caller":"traceutil/trace.go:171","msg":"trace[47116953] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"540.792114ms","start":"2026-05-29T21:53:32.116981Z","end":"2026-05-29T21:53:32.657773Z","steps":["trace[47116953] 'process raft request' (duration: 539.682704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:32.65793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:32.116938Z","time spent":"540.916257ms","remote":"127.0.0.1:56666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:53:33.07144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.369819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:53:33.071608Z","caller":"traceutil/trace.go:171","msg":"trace[861025201] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"155.83068ms","start":"2026-05-29T21:53:32.915755Z","end":"2026-05-29T21:53:33.071586Z","steps":["trace[861025201] 'read index received' (duration: 55.011µs)","trace[861025201] 'applied index is now lower than readState.Index' (duration: 155.774269ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:53:33.071824Z","caller":"traceutil/trace.go:171","msg":"trace[2035941439] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"357.799336ms","start":"2026-05-29T21:53:32.713851Z","end":"2026-05-29T21:53:33.07165Z","steps":["trace[2035941439] 'process raft request' (duration: 35.115437ms)","trace[2035941439] 'compare' (duration: 322.151084ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:53:33.071899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.119568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l9qjg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-29T21:53:33.071934Z","caller":"traceutil/trace.go:171","msg":"trace[273223064] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l9qjg; range_end:; response_count:1; response_revision:651; }","duration":"156.218491ms","start":"2026-05-29T21:53:32.915705Z","end":"2026-05-29T21:53:33.071924Z","steps":["trace[273223064] 'agreement among raft nodes before linearized reading' (duration: 155.943134ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:33.072126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:32.713825Z","time spent":"358.090704ms","remote":"127.0.0.1:57018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-29T21:53:33.660231Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393688150280531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:53:34.161266Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393688150280531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:53:34.661455Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393688150280531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:53:35.159826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000127388s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-29T21:53:35.159907Z","caller":"traceutil/trace.go:171","msg":"trace[1766984579] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000253302s","start":"2026-05-29T21:53:33.159636Z","end":"2026-05-29T21:53:35.159889Z","steps":["trace[1766984579] 'agreement among raft nodes before linearized reading' (duration: 2.000153109s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:35.16001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:33.159619Z","time spent":"2.000353164s","remote":"127.0.0.1:56562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/29 21:53:35 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-29T21:53:35.162014Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393688150280531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:53:35.663039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393688150280531,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:53:35.966065Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.877084551s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T21:53:35.966623Z","caller":"traceutil/trace.go:171","msg":"trace[2123552599] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"2.87775044s","start":"2026-05-29T21:53:33.088846Z","end":"2026-05-29T21:53:35.966596Z","steps":["trace[2123552599] 'process raft request' (duration: 2.877512853s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:35.966801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:33.088825Z","time spent":"2.877871353s","remote":"127.0.0.1:56744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:53:37.160819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000705316s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-29T21:53:37.160995Z","caller":"traceutil/trace.go:171","msg":"trace[1721204427] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000899671s","start":"2026-05-29T21:53:35.16007Z","end":"2026-05-29T21:53:37.16097Z","steps":["trace[1721204427] 'agreement among raft nodes before linearized reading' (duration: 2.000701496s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:37.161074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:35.160028Z","time spent":"2.001033115s","remote":"127.0.0.1:56578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/29 21:53:37 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-29T21:53:37.308512Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.338911289s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-29T21:53:38.951044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999838686s","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-05-29T21:53:38.951188Z","caller":"traceutil/trace.go:171","msg":"trace[1923973484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.000061483s","start":"2026-05-29T21:53:33.951091Z","end":"2026-05-29T21:53:38.951152Z","steps":["trace[1923973484] 'agreement among raft nodes before linearized reading' (duration: 4.999872207s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:38.951258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:33.951068Z","time spent":"5.000175356s","remote":"127.0.0.1:56800","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/05/29 21:53:38 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-29T21:53:39.141376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999260794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-29T21:53:39.141459Z","caller":"traceutil/trace.go:171","msg":"trace[95853505] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999403569s","start":"2026-05-29T21:53:34.142035Z","end":"2026-05-29T21:53:39.141439Z","steps":["trace[95853505] 'agreement among raft nodes before linearized reading' (duration: 4.999294126s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:39.141507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:34.142008Z","time spent":"4.999490661s","remote":"127.0.0.1:56800","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/05/29 21:53:39 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-29T21:53:39.168917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000234876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-29T21:53:39.168981Z","caller":"traceutil/trace.go:171","msg":"trace[369148304] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000307948s","start":"2026-05-29T21:53:37.168657Z","end":"2026-05-29T21:53:39.168964Z","steps":["trace[369148304] 'agreement among raft nodes before linearized reading' (duration: 2.000232936s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:39.169019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:37.168581Z","time spent":"2.000429592s","remote":"127.0.0.1:56562","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-05-29T21:53:40.464906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:33.46363Z","time spent":"7.001261562s","remote":"127.0.0.1:56666","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-05-29T21:53:40.936719Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"5aa09e75b9a68825","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-05-29T21:53:40.974171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.531459797s","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-05-29T21:53:40.974303Z","caller":"traceutil/trace.go:171","msg":"trace[749659708] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:652; }","duration":"1.531604811s","start":"2026-05-29T21:53:39.442679Z","end":"2026-05-29T21:53:40.974284Z","steps":["trace[749659708] 'range keys from in-memory index tree' (duration: 1.531444306s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:40.974909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.663566316s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-05-29T21:53:40.993552Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.684667643s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T21:53:41.002389Z","caller":"traceutil/trace.go:171","msg":"trace[934930572] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:676; }","duration":"7.84255572s","start":"2026-05-29T21:53:33.159676Z","end":"2026-05-29T21:53:41.002232Z","steps":["trace[934930572] 'read index received' (duration: 2.80673857s)","trace[934930572] 'applied index is now lower than readState.Index' (duration: 5.03581457s)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:53:41.002419Z","caller":"traceutil/trace.go:171","msg":"trace[454206365] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"3.834375364s","start":"2026-05-29T21:53:37.168019Z","end":"2026-05-29T21:53:41.002394Z","steps":["trace[454206365] 'process raft request' (duration: 3.834310311s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:41.00257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:37.167999Z","time spent":"3.834507978s","remote":"127.0.0.1:56656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:53:41.002661Z","caller":"traceutil/trace.go:171","msg":"trace[1488782219] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"4.870002339s","start":"2026-05-29T21:53:36.132647Z","end":"2026-05-29T21:53:41.00265Z","steps":["trace[1488782219] 'process raft request' (duration: 4.869518733s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:41.002775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:36.13262Z","time spent":"4.870083872s","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:53:41.032738Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"57.772µs","request":"header: lease_revoke:","response":"size:28","error":"lease not found"} {"level":"info","ts":"2026-05-29T21:53:41.032845Z","caller":"traceutil/trace.go:171","msg":"trace[938737281] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"521.346491ms","start":"2026-05-29T21:53:40.511473Z","end":"2026-05-29T21:53:41.032819Z","steps":["trace[938737281] 'process raft request' (duration: 520.796314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:41.032874Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"5aa09e75b9a68825","error":"lease not found"} {"level":"warn","ts":"2026-05-29T21:53:41.032916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.863237791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-29T21:53:41.032967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:40.511442Z","time spent":"521.470765ms","remote":"127.0.0.1:56774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:53:41.032982Z","caller":"traceutil/trace.go:171","msg":"trace[621981350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:656; }","duration":"1.863350305s","start":"2026-05-29T21:53:39.169614Z","end":"2026-05-29T21:53:41.032964Z","steps":["trace[621981350] 'agreement among raft nodes before linearized reading' (duration: 1.863231682s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:41.032981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.946872938s","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-05-29T21:53:41.033026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:39.169592Z","time spent":"1.863426707s","remote":"127.0.0.1:56578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T21:53:41.033066Z","caller":"traceutil/trace.go:171","msg":"trace[1789070704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:656; }","duration":"6.947000252s","start":"2026-05-29T21:53:34.086051Z","end":"2026-05-29T21:53:41.033051Z","steps":["trace[1789070704] 'agreement among raft nodes before linearized reading' (duration: 6.946756324s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:41.033137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:34.08603Z","time spent":"6.947072225s","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T21:53:41.033162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.683894682s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-29T21:53:41.03318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.371581598s","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-05-29T21:53:41.033223Z","caller":"traceutil/trace.go:171","msg":"trace[857899804] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:656; }","duration":"2.683990597s","start":"2026-05-29T21:53:38.349215Z","end":"2026-05-29T21:53:41.033206Z","steps":["trace[857899804] 'agreement among raft nodes before linearized reading' (duration: 2.683876682s)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:41.033228Z","caller":"traceutil/trace.go:171","msg":"trace[362183764] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"6.371629921s","start":"2026-05-29T21:53:34.661585Z","end":"2026-05-29T21:53:41.033215Z","steps":["trace[362183764] 'agreement among raft nodes before linearized reading' (duration: 6.371502596s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:41.033257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:38.349193Z","time spent":"2.684055608s","remote":"127.0.0.1:56616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"warn","ts":"2026-05-29T21:53:41.033299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:34.661515Z","time spent":"6.371770834s","remote":"127.0.0.1:56800","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-05-29T21:53:43.273942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.547678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:43.274002Z","caller":"traceutil/trace.go:171","msg":"trace[126728291] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"112.658212ms","start":"2026-05-29T21:53:43.161328Z","end":"2026-05-29T21:53:43.273986Z","steps":["trace[126728291] 'range keys from in-memory index tree' (duration: 112.488767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:43.274027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.034215ms","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-05-29T21:53:43.274083Z","caller":"traceutil/trace.go:171","msg":"trace[1257606814] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:679; }","duration":"121.121207ms","start":"2026-05-29T21:53:43.152939Z","end":"2026-05-29T21:53:43.27406Z","steps":["trace[1257606814] 'range keys from in-memory index tree' (duration: 120.87814ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:44.768459Z","caller":"traceutil/trace.go:171","msg":"trace[485125515] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"580.985636ms","start":"2026-05-29T21:53:44.187446Z","end":"2026-05-29T21:53:44.768431Z","steps":["trace[485125515] 'process raft request' (duration: 580.80749ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:44.768771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:44.187416Z","time spent":"581.127981ms","remote":"127.0.0.1:56638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:53:44.774716Z","caller":"traceutil/trace.go:171","msg":"trace[214370169] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"189.76954ms","start":"2026-05-29T21:53:44.584901Z","end":"2026-05-29T21:53:44.77467Z","steps":["trace[214370169] 'process raft request' (duration: 189.665267ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:44.774765Z","caller":"traceutil/trace.go:171","msg":"trace[455692062] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"390.039746ms","start":"2026-05-29T21:53:44.384701Z","end":"2026-05-29T21:53:44.77474Z","steps":["trace[455692062] 'process raft request' (duration: 389.713406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:44.775058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:44.384676Z","time spent":"390.324136ms","remote":"127.0.0.1:56638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:53:54.250304Z","caller":"traceutil/trace.go:171","msg":"trace[1537596663] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:831; }","duration":"125.921582ms","start":"2026-05-29T21:53:54.124361Z","end":"2026-05-29T21:53:54.250282Z","steps":["trace[1537596663] 'read index received' (duration: 125.736464ms)","trace[1537596663] 'applied index is now lower than readState.Index' (duration: 184.168µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:53:54.250534Z","caller":"traceutil/trace.go:171","msg":"trace[2059407129] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"153.485556ms","start":"2026-05-29T21:53:54.097026Z","end":"2026-05-29T21:53:54.250511Z","steps":["trace[2059407129] 'process raft request' (duration: 153.129162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:54.250721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.306736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:54.250812Z","caller":"traceutil/trace.go:171","msg":"trace[589342195] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:800; }","duration":"126.466722ms","start":"2026-05-29T21:53:54.124331Z","end":"2026-05-29T21:53:54.250798Z","steps":["trace[589342195] 'agreement among raft nodes before linearized reading' (duration: 126.199302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:57.616188Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393688150280924,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:53:58.116886Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393688150280924,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:53:58.617366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6530393688150280924,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-29T21:53:58.987642Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.934980282s","expected-duration":"1s"} {"level":"info","ts":"2026-05-29T21:53:58.98813Z","caller":"traceutil/trace.go:171","msg":"trace[923470863] linearizableReadLoop","detail":"{readStateIndex:847; appliedIndex:846; }","duration":"1.872327906s","start":"2026-05-29T21:53:57.115776Z","end":"2026-05-29T21:53:58.988104Z","steps":["trace[923470863] 'read index received' (duration: 1.87217564s)","trace[923470863] 'applied index is now lower than readState.Index' (duration: 151.116µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:53:58.988277Z","caller":"traceutil/trace.go:171","msg":"trace[558482521] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"1.935615076s","start":"2026-05-29T21:53:57.052558Z","end":"2026-05-29T21:53:58.988173Z","steps":["trace[558482521] 'process raft request' (duration: 1.935398467s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:58.988371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.872247212s","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-05-29T21:53:58.988411Z","caller":"traceutil/trace.go:171","msg":"trace[494692629] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:814; }","duration":"1.872325655s","start":"2026-05-29T21:53:57.116075Z","end":"2026-05-29T21:53:58.988401Z","steps":["trace[494692629] 'agreement among raft nodes before linearized reading' (duration: 1.872172109s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:58.988469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:57.116065Z","time spent":"1.872395958s","remote":"127.0.0.1:56800","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-05-29T21:53:58.988464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.657100439s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-29T21:53:58.988483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.603113061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:58.988569Z","caller":"traceutil/trace.go:171","msg":"trace[1667249556] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:814; }","duration":"1.657243115s","start":"2026-05-29T21:53:57.331303Z","end":"2026-05-29T21:53:58.988546Z","steps":["trace[1667249556] 'agreement among raft nodes before linearized reading' (duration: 1.657055077s)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:53:58.988617Z","caller":"traceutil/trace.go:171","msg":"trace[284673120] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:814; }","duration":"1.60333301s","start":"2026-05-29T21:53:57.385268Z","end":"2026-05-29T21:53:58.988601Z","steps":["trace[284673120] 'agreement among raft nodes before linearized reading' (duration: 1.603056489s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:58.988641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:57.331286Z","time spent":"1.657338488s","remote":"127.0.0.1:56792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:53:58.988681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.825939488s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:53:58.988711Z","caller":"traceutil/trace.go:171","msg":"trace[1631515601] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:814; }","duration":"1.82599146s","start":"2026-05-29T21:53:57.162711Z","end":"2026-05-29T21:53:58.988702Z","steps":["trace[1631515601] 'agreement among raft nodes before linearized reading' (duration: 1.825945758s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:58.988696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:57.385254Z","time spent":"1.603430604s","remote":"127.0.0.1:56908","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-29T21:53:58.988813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.872660698s","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-05-29T21:53:58.988844Z","caller":"traceutil/trace.go:171","msg":"trace[1850499455] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:814; }","duration":"1.872721321s","start":"2026-05-29T21:53:57.116115Z","end":"2026-05-29T21:53:58.988837Z","steps":["trace[1850499455] 'agreement among raft nodes before linearized reading' (duration: 1.872644148s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:58.988868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:57.11611Z","time spent":"1.872752672s","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-29T21:53:58.989103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.647580355s","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-05-29T21:53:58.988743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:57.162699Z","time spent":"1.826039341s","remote":"127.0.0.1:56562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-29T21:53:58.989181Z","caller":"traceutil/trace.go:171","msg":"trace[647830478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:814; }","duration":"1.647672869s","start":"2026-05-29T21:53:57.341477Z","end":"2026-05-29T21:53:58.98915Z","steps":["trace[647830478] 'agreement among raft nodes before linearized reading' (duration: 1.647493902s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:58.989224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.427115ms","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":"warn","ts":"2026-05-29T21:53:58.989245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:57.341454Z","time spent":"1.647778093s","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-29T21:53:58.989275Z","caller":"traceutil/trace.go:171","msg":"trace[756536125] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:814; }","duration":"723.459886ms","start":"2026-05-29T21:53:58.265787Z","end":"2026-05-29T21:53:58.989247Z","steps":["trace[756536125] 'agreement among raft nodes before linearized reading' (duration: 723.375613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:58.989312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:58.265733Z","time spent":"723.572671ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-29T21:53:58.989156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.167466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.74\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-29T21:53:58.98941Z","caller":"traceutil/trace.go:171","msg":"trace[1662879245] range","detail":"{range_begin:/registry/masterleases/199.204.45.74; range_end:; response_count:1; response_revision:814; }","duration":"639.452877ms","start":"2026-05-29T21:53:58.349934Z","end":"2026-05-29T21:53:58.989387Z","steps":["trace[1662879245] 'agreement among raft nodes before linearized reading' (duration: 639.131185ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:58.989488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:58.349905Z","time spent":"639.568812ms","remote":"127.0.0.1:56616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.74\" "} {"level":"warn","ts":"2026-05-29T21:53:58.989722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:57.052541Z","time spent":"1.935835935s","remote":"127.0.0.1:56744","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:53:58.989772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.874001031s","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-05-29T21:53:58.989884Z","caller":"traceutil/trace.go:171","msg":"trace[862476282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:814; }","duration":"1.874150408s","start":"2026-05-29T21:53:57.115724Z","end":"2026-05-29T21:53:58.989875Z","steps":["trace[862476282] 'agreement among raft nodes before linearized reading' (duration: 1.872511173s)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:53:58.98999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:53:57.115703Z","time spent":"1.874278453s","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T21:54:09.287383Z","caller":"traceutil/trace.go:171","msg":"trace[827628000] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"202.595798ms","start":"2026-05-29T21:54:09.084763Z","end":"2026-05-29T21:54:09.287359Z","steps":["trace[827628000] 'read index received' (duration: 202.374848ms)","trace[827628000] 'applied index is now lower than readState.Index' (duration: 219.82µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:09.287437Z","caller":"traceutil/trace.go:171","msg":"trace[799526489] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"202.706163ms","start":"2026-05-29T21:54:09.084695Z","end":"2026-05-29T21:54:09.287402Z","steps":["trace[799526489] 'process raft request' (duration: 202.510354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:09.287611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.816567ms","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-05-29T21:54:09.287613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.822758ms","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-05-29T21:54:09.287692Z","caller":"traceutil/trace.go:171","msg":"trace[924681385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:943; }","duration":"202.950503ms","start":"2026-05-29T21:54:09.084723Z","end":"2026-05-29T21:54:09.287673Z","steps":["trace[924681385] 'agreement among raft nodes before linearized reading' (duration: 202.708493ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:09.287701Z","caller":"traceutil/trace.go:171","msg":"trace[1670847728] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:943; }","duration":"202.965544ms","start":"2026-05-29T21:54:09.084719Z","end":"2026-05-29T21:54:09.287684Z","steps":["trace[1670847728] 'agreement among raft nodes before linearized reading' (duration: 202.732894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:09.287783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.953294ms","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-05-29T21:54:09.287879Z","caller":"traceutil/trace.go:171","msg":"trace[317331325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:943; }","duration":"203.068258ms","start":"2026-05-29T21:54:09.084788Z","end":"2026-05-29T21:54:09.287856Z","steps":["trace[317331325] 'agreement among raft nodes before linearized reading' (duration: 202.861149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:09.288804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.259916ms","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-05-29T21:54:09.288887Z","caller":"traceutil/trace.go:171","msg":"trace[368835924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:943; }","duration":"204.123275ms","start":"2026-05-29T21:54:09.084743Z","end":"2026-05-29T21:54:09.288866Z","steps":["trace[368835924] 'agreement among raft nodes before linearized reading' (duration: 202.765065ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:09.434044Z","caller":"traceutil/trace.go:171","msg":"trace[2144129658] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"142.055082ms","start":"2026-05-29T21:54:09.291964Z","end":"2026-05-29T21:54:09.434019Z","steps":["trace[2144129658] 'process raft request' (duration: 141.906715ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:09.473385Z","caller":"traceutil/trace.go:171","msg":"trace[1907118050] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"180.633105ms","start":"2026-05-29T21:54:09.292729Z","end":"2026-05-29T21:54:09.473362Z","steps":["trace[1907118050] 'process raft request' (duration: 180.551961ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:09.473433Z","caller":"traceutil/trace.go:171","msg":"trace[400227934] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"181.378447ms","start":"2026-05-29T21:54:09.292011Z","end":"2026-05-29T21:54:09.473389Z","steps":["trace[400227934] 'process raft request' (duration: 181.150587ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:09.473919Z","caller":"traceutil/trace.go:171","msg":"trace[670491556] linearizableReadLoop","detail":"{readStateIndex:981; appliedIndex:979; }","duration":"181.556345ms","start":"2026-05-29T21:54:09.292348Z","end":"2026-05-29T21:54:09.473904Z","steps":["trace[670491556] 'read index received' (duration: 141.483596ms)","trace[670491556] 'applied index is now lower than readState.Index' (duration: 40.071039ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:09.473925Z","caller":"traceutil/trace.go:171","msg":"trace[1614846123] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"181.127176ms","start":"2026-05-29T21:54:09.292779Z","end":"2026-05-29T21:54:09.473906Z","steps":["trace[1614846123] 'process raft request' (duration: 180.53998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:09.474115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.536619ms","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-05-29T21:54:09.474207Z","caller":"traceutil/trace.go:171","msg":"trace[1990341406] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:947; }","duration":"118.681146ms","start":"2026-05-29T21:54:09.35551Z","end":"2026-05-29T21:54:09.474192Z","steps":["trace[1990341406] 'agreement among raft nodes before linearized reading' (duration: 118.515019ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:09.474129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.757184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/local-path-config\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:09.474351Z","caller":"traceutil/trace.go:171","msg":"trace[1557687469] range","detail":"{range_begin:/registry/configmaps/local-path-storage/local-path-config; range_end:; response_count:0; response_revision:947; }","duration":"182.013885ms","start":"2026-05-29T21:54:09.292312Z","end":"2026-05-29T21:54:09.474326Z","steps":["trace[1557687469] 'agreement among raft nodes before linearized reading' (duration: 181.717022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:09.719517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.975238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:54:09.719642Z","caller":"traceutil/trace.go:171","msg":"trace[753582763] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"201.087111ms","start":"2026-05-29T21:54:09.518533Z","end":"2026-05-29T21:54:09.71962Z","steps":["trace[753582763] 'process raft request' (duration: 48.924556ms)","trace[753582763] 'compare' (duration: 151.832271ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:09.738314Z","caller":"traceutil/trace.go:171","msg":"trace[298647688] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"219.304121ms","start":"2026-05-29T21:54:09.518978Z","end":"2026-05-29T21:54:09.738282Z","steps":["trace[298647688] 'process raft request' (duration: 219.143674ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:09.738428Z","caller":"traceutil/trace.go:171","msg":"trace[1658128628] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"214.741302ms","start":"2026-05-29T21:54:09.523512Z","end":"2026-05-29T21:54:09.738253Z","steps":["trace[1658128628] 'process raft request' (duration: 214.69562ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:10.061871Z","caller":"traceutil/trace.go:171","msg":"trace[2142871206] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"123.407174ms","start":"2026-05-29T21:54:09.938394Z","end":"2026-05-29T21:54:10.061801Z","steps":["trace[2142871206] 'process raft request' (duration: 56.506269ms)","trace[2142871206] 'compare' (duration: 66.79487ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:10.367194Z","caller":"traceutil/trace.go:171","msg":"trace[449846406] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"205.53175ms","start":"2026-05-29T21:54:10.161618Z","end":"2026-05-29T21:54:10.36715Z","steps":["trace[449846406] 'read index received' (duration: 201.755112ms)","trace[449846406] 'applied index is now lower than readState.Index' (duration: 3.774478ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:10.36729Z","caller":"traceutil/trace.go:171","msg":"trace[1032616857] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"300.713009ms","start":"2026-05-29T21:54:10.066552Z","end":"2026-05-29T21:54:10.367265Z","steps":["trace[1032616857] 'process raft request' (duration: 296.877899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:10.367439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.77559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:10.367488Z","caller":"traceutil/trace.go:171","msg":"trace[660173122] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"205.907216ms","start":"2026-05-29T21:54:10.161569Z","end":"2026-05-29T21:54:10.367476Z","steps":["trace[660173122] 'agreement among raft nodes before linearized reading' (duration: 205.731989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:10.367653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:10.06653Z","time spent":"300.824634ms","remote":"127.0.0.1:56978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2872,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-29T21:54:10.631885Z","caller":"traceutil/trace.go:171","msg":"trace[1969946825] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"258.180147ms","start":"2026-05-29T21:54:10.37365Z","end":"2026-05-29T21:54:10.63183Z","steps":["trace[1969946825] 'process raft request' (duration: 189.908658ms)","trace[1969946825] 'compare' (duration: 68.112472ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:10.658948Z","caller":"traceutil/trace.go:171","msg":"trace[1324427368] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"282.478142ms","start":"2026-05-29T21:54:10.376447Z","end":"2026-05-29T21:54:10.658926Z","steps":["trace[1324427368] 'process raft request' (duration: 282.320665ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:10.775539Z","caller":"traceutil/trace.go:171","msg":"trace[1303099869] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:994; }","duration":"139.133202ms","start":"2026-05-29T21:54:10.636379Z","end":"2026-05-29T21:54:10.775512Z","steps":["trace[1303099869] 'read index received' (duration: 22.427722ms)","trace[1303099869] 'applied index is now lower than readState.Index' (duration: 116.70445ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:10.775613Z","caller":"traceutil/trace.go:171","msg":"trace[571715665] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"138.486694ms","start":"2026-05-29T21:54:10.637092Z","end":"2026-05-29T21:54:10.775579Z","steps":["trace[571715665] 'process raft request' (duration: 138.355528ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:10.77566Z","caller":"traceutil/trace.go:171","msg":"trace[1588798409] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"139.847814ms","start":"2026-05-29T21:54:10.635775Z","end":"2026-05-29T21:54:10.775623Z","steps":["trace[1588798409] 'process raft request' (duration: 137.139144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:10.775773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.369203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:10.775832Z","caller":"traceutil/trace.go:171","msg":"trace[1072593298] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:961; }","duration":"139.485767ms","start":"2026-05-29T21:54:10.636331Z","end":"2026-05-29T21:54:10.775816Z","steps":["trace[1072593298] 'agreement among raft nodes before linearized reading' (duration: 139.348421ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:39.804653Z","caller":"traceutil/trace.go:171","msg":"trace[1339568533] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1130; }","duration":"198.150988ms","start":"2026-05-29T21:54:39.606478Z","end":"2026-05-29T21:54:39.804629Z","steps":["trace[1339568533] 'read index received' (duration: 198.011681ms)","trace[1339568533] 'applied index is now lower than readState.Index' (duration: 138.397µs)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:39.804957Z","caller":"traceutil/trace.go:171","msg":"trace[612616439] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"274.336675ms","start":"2026-05-29T21:54:39.530608Z","end":"2026-05-29T21:54:39.804945Z","steps":["trace[612616439] 'process raft request' (duration: 273.880761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:39.805261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.802182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T21:54:39.805326Z","caller":"traceutil/trace.go:171","msg":"trace[223649843] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1089; }","duration":"198.914328ms","start":"2026-05-29T21:54:39.606394Z","end":"2026-05-29T21:54:39.805308Z","steps":["trace[223649843] 'agreement among raft nodes before linearized reading' (duration: 198.749629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:39.805643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.429186ms","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-05-29T21:54:39.805719Z","caller":"traceutil/trace.go:171","msg":"trace[1395644011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1089; }","duration":"178.542162ms","start":"2026-05-29T21:54:39.627163Z","end":"2026-05-29T21:54:39.805705Z","steps":["trace[1395644011] 'agreement among raft nodes before linearized reading' (duration: 178.402175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:39.805961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.22607ms","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-05-29T21:54:39.805958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.222847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T21:54:39.806007Z","caller":"traceutil/trace.go:171","msg":"trace[1750048976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1089; }","duration":"124.306984ms","start":"2026-05-29T21:54:39.681688Z","end":"2026-05-29T21:54:39.805995Z","steps":["trace[1750048976] 'agreement among raft nodes before linearized reading' (duration: 124.204759ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:39.806025Z","caller":"traceutil/trace.go:171","msg":"trace[1155498093] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1089; }","duration":"123.330723ms","start":"2026-05-29T21:54:39.682678Z","end":"2026-05-29T21:54:39.806009Z","steps":["trace[1155498093] 'agreement among raft nodes before linearized reading' (duration: 123.141033ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:43.268961Z","caller":"traceutil/trace.go:171","msg":"trace[556255198] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"251.645613ms","start":"2026-05-29T21:54:43.017289Z","end":"2026-05-29T21:54:43.268935Z","steps":["trace[556255198] 'process raft request' (duration: 251.476664ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:46.170149Z","caller":"traceutil/trace.go:171","msg":"trace[1472687170] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1153; }","duration":"200.5391ms","start":"2026-05-29T21:54:45.969581Z","end":"2026-05-29T21:54:46.17012Z","steps":["trace[1472687170] 'read index received' (duration: 140.232136ms)","trace[1472687170] 'applied index is now lower than readState.Index' (duration: 60.305974ms)"],"step_count":2} {"level":"info","ts":"2026-05-29T21:54:46.170284Z","caller":"traceutil/trace.go:171","msg":"trace[328086447] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"229.720882ms","start":"2026-05-29T21:54:45.940522Z","end":"2026-05-29T21:54:46.170243Z","steps":["trace[328086447] 'process raft request' (duration: 169.339324ms)","trace[328086447] 'compare' (duration: 60.131555ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:46.170483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.351729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T21:54:46.170552Z","caller":"traceutil/trace.go:171","msg":"trace[1178610801] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1111; }","duration":"186.506027ms","start":"2026-05-29T21:54:45.984033Z","end":"2026-05-29T21:54:46.170539Z","steps":["trace[1178610801] 'agreement among raft nodes before linearized reading' (duration: 186.255024ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:46.170667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.066847ms","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-05-29T21:54:46.171594Z","caller":"traceutil/trace.go:171","msg":"trace[2029241286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1111; }","duration":"202.029668ms","start":"2026-05-29T21:54:45.969546Z","end":"2026-05-29T21:54:46.171575Z","steps":["trace[2029241286] 'agreement among raft nodes before linearized reading' (duration: 200.726989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:48.081224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.096494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T21:54:48.081409Z","caller":"traceutil/trace.go:171","msg":"trace[1052248028] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1115; }","duration":"159.330126ms","start":"2026-05-29T21:54:47.922067Z","end":"2026-05-29T21:54:48.081397Z","steps":["trace[1052248028] 'range keys from in-memory index tree' (duration: 158.959936ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:48.38998Z","caller":"traceutil/trace.go:171","msg":"trace[1370905989] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"210.653332ms","start":"2026-05-29T21:54:48.179304Z","end":"2026-05-29T21:54:48.389957Z","steps":["trace[1370905989] 'process raft request' (duration: 210.529365ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:48.390261Z","caller":"traceutil/trace.go:171","msg":"trace[1623667754] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1160; }","duration":"189.855064ms","start":"2026-05-29T21:54:48.200395Z","end":"2026-05-29T21:54:48.39025Z","steps":["trace[1623667754] 'read index received' (duration: 189.364558ms)","trace[1623667754] 'applied index is now lower than readState.Index' (duration: 489.056µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:48.390546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.739154ms","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-05-29T21:54:48.390597Z","caller":"traceutil/trace.go:171","msg":"trace[500067409] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1117; }","duration":"181.81413ms","start":"2026-05-29T21:54:48.208774Z","end":"2026-05-29T21:54:48.390588Z","steps":["trace[500067409] 'agreement among raft nodes before linearized reading' (duration: 181.717834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:48.390888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.080217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T21:54:48.390954Z","caller":"traceutil/trace.go:171","msg":"trace[1443297960] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1117; }","duration":"190.615447ms","start":"2026-05-29T21:54:48.20033Z","end":"2026-05-29T21:54:48.390946Z","steps":["trace[1443297960] 'agreement among raft nodes before linearized reading' (duration: 190.021554ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:48.516159Z","caller":"traceutil/trace.go:171","msg":"trace[1074594660] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"121.048488ms","start":"2026-05-29T21:54:48.395092Z","end":"2026-05-29T21:54:48.51614Z","steps":["trace[1074594660] 'process raft request' (duration: 120.922281ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:48.516382Z","caller":"traceutil/trace.go:171","msg":"trace[1193577908] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"121.303231ms","start":"2026-05-29T21:54:48.39506Z","end":"2026-05-29T21:54:48.516363Z","steps":["trace[1193577908] 'process raft request' (duration: 121.056108ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:50.142345Z","caller":"traceutil/trace.go:171","msg":"trace[824754537] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"265.616005ms","start":"2026-05-29T21:54:49.876703Z","end":"2026-05-29T21:54:50.142319Z","steps":["trace[824754537] 'process raft request' (duration: 265.467757ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:50.306049Z","caller":"traceutil/trace.go:171","msg":"trace[29676580] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"153.25637ms","start":"2026-05-29T21:54:50.15277Z","end":"2026-05-29T21:54:50.306027Z","steps":["trace[29676580] 'process raft request' (duration: 153.06675ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:50.903593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.809597ms","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-05-29T21:54:50.903658Z","caller":"traceutil/trace.go:171","msg":"trace[1774901055] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1124; }","duration":"383.901972ms","start":"2026-05-29T21:54:50.519742Z","end":"2026-05-29T21:54:50.903644Z","steps":["trace[1774901055] 'range keys from in-memory index tree' (duration: 383.585995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:50.903524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.206208ms","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-05-29T21:54:50.903693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:50.519733Z","time spent":"383.951214ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-29T21:54:50.903728Z","caller":"traceutil/trace.go:171","msg":"trace[247830171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1124; }","duration":"384.456002ms","start":"2026-05-29T21:54:50.519255Z","end":"2026-05-29T21:54:50.903711Z","steps":["trace[247830171] 'range keys from in-memory index tree' (duration: 384.038009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:50.90378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:50.51924Z","time spent":"384.527666ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-29T21:54:51.342559Z","caller":"traceutil/trace.go:171","msg":"trace[1053563876] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"435.372321ms","start":"2026-05-29T21:54:50.907165Z","end":"2026-05-29T21:54:51.342537Z","steps":["trace[1053563876] 'process raft request' (duration: 435.226973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:51.342707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:50.907149Z","time spent":"435.483967ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:54:51.354759Z","caller":"traceutil/trace.go:171","msg":"trace[384547581] linearizableReadLoop","detail":"{readStateIndex:1170; appliedIndex:1168; }","duration":"193.645949ms","start":"2026-05-29T21:54:51.161103Z","end":"2026-05-29T21:54:51.354749Z","steps":["trace[384547581] 'read index received' (duration: 181.289503ms)","trace[384547581] 'applied index is now lower than readState.Index' (duration: 12.355726ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:51.354832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.725834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:54:51.354855Z","caller":"traceutil/trace.go:171","msg":"trace[1896811434] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1127; }","duration":"193.784977ms","start":"2026-05-29T21:54:51.161062Z","end":"2026-05-29T21:54:51.354847Z","steps":["trace[1896811434] 'agreement among raft nodes before linearized reading' (duration: 193.733463ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:51.355351Z","caller":"traceutil/trace.go:171","msg":"trace[1642927692] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"447.503225ms","start":"2026-05-29T21:54:50.907828Z","end":"2026-05-29T21:54:51.355331Z","steps":["trace[1642927692] 'process raft request' (duration: 446.843389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:51.355418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:50.907814Z","time spent":"447.568209ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:54:51.672494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.869437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-29T21:54:51.672582Z","caller":"traceutil/trace.go:171","msg":"trace[1020674266] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1127; }","duration":"250.000055ms","start":"2026-05-29T21:54:51.422564Z","end":"2026-05-29T21:54:51.672564Z","steps":["trace[1020674266] 'count revisions from in-memory index tree' (duration: 249.780952ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:52.337602Z","caller":"traceutil/trace.go:171","msg":"trace[1999498874] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"187.756461ms","start":"2026-05-29T21:54:52.149822Z","end":"2026-05-29T21:54:52.337578Z","steps":["trace[1999498874] 'process raft request' (duration: 187.598632ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:52.744576Z","caller":"traceutil/trace.go:171","msg":"trace[199351430] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"554.814308ms","start":"2026-05-29T21:54:52.189733Z","end":"2026-05-29T21:54:52.744548Z","steps":["trace[199351430] 'process raft request' (duration: 541.55461ms)","trace[199351430] 'compare' (duration: 12.714217ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:52.744712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:52.189712Z","time spent":"554.929504ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-29T21:54:52.74494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.30403ms","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-05-29T21:54:52.744997Z","caller":"traceutil/trace.go:171","msg":"trace[388278788] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1129; }","duration":"434.404646ms","start":"2026-05-29T21:54:52.310579Z","end":"2026-05-29T21:54:52.744983Z","steps":["trace[388278788] 'agreement among raft nodes before linearized reading' (duration: 434.219626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:52.745025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:52.31056Z","time spent":"434.459429ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-29T21:54:52.745057Z","caller":"traceutil/trace.go:171","msg":"trace[1858539356] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1171; }","duration":"433.76167ms","start":"2026-05-29T21:54:52.310618Z","end":"2026-05-29T21:54:52.74438Z","steps":["trace[1858539356] 'read index received' (duration: 26.77854ms)","trace[1858539356] 'applied index is now lower than readState.Index' (duration: 406.98184ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:52.745261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.193756ms","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-05-29T21:54:52.745293Z","caller":"traceutil/trace.go:171","msg":"trace[1564317182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1129; }","duration":"282.290382ms","start":"2026-05-29T21:54:52.462993Z","end":"2026-05-29T21:54:52.745283Z","steps":["trace[1564317182] 'agreement among raft nodes before linearized reading' (duration: 282.140994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:53.662911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.542633ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-29T21:54:53.663114Z","caller":"traceutil/trace.go:171","msg":"trace[1229548023] linearizableReadLoop","detail":"{readStateIndex:1178; appliedIndex:1177; }","duration":"303.411645ms","start":"2026-05-29T21:54:53.359683Z","end":"2026-05-29T21:54:53.663095Z","steps":["trace[1229548023] 'read index received' (duration: 150.489851ms)","trace[1229548023] 'applied index is now lower than readState.Index' (duration: 152.919934ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:53.663323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.630219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-29T21:54:53.663358Z","caller":"traceutil/trace.go:171","msg":"trace[703279198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1134; }","duration":"303.687012ms","start":"2026-05-29T21:54:53.359661Z","end":"2026-05-29T21:54:53.663348Z","steps":["trace[703279198] 'agreement among raft nodes before linearized reading' (duration: 303.49427ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:54:53.66339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:53.359653Z","time spent":"303.729474ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-29T21:54:53.6635Z","caller":"traceutil/trace.go:171","msg":"trace[640941458] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"304.046782ms","start":"2026-05-29T21:54:53.359297Z","end":"2026-05-29T21:54:53.663344Z","steps":["trace[640941458] 'process raft request' (duration: 150.970558ms)","trace[640941458] 'compare' (duration: 152.271699ms)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:53.663666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:54:53.359286Z","time spent":"304.297385ms","remote":"127.0.0.1:56800","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-29T21:54:54.950093Z","caller":"traceutil/trace.go:171","msg":"trace[2125006257] linearizableReadLoop","detail":"{readStateIndex:1182; appliedIndex:1181; }","duration":"119.648273ms","start":"2026-05-29T21:54:54.830415Z","end":"2026-05-29T21:54:54.950064Z","steps":["trace[2125006257] 'read index received' (duration: 119.453652ms)","trace[2125006257] 'applied index is now lower than readState.Index' (duration: 193.961µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:54:54.95026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.824222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-29T21:54:54.950297Z","caller":"traceutil/trace.go:171","msg":"trace[1712505091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1137; }","duration":"119.884426ms","start":"2026-05-29T21:54:54.830403Z","end":"2026-05-29T21:54:54.950287Z","steps":["trace[1712505091] 'agreement among raft nodes before linearized reading' (duration: 119.753119ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:54:54.950396Z","caller":"traceutil/trace.go:171","msg":"trace[1648547796] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"120.422096ms","start":"2026-05-29T21:54:54.829966Z","end":"2026-05-29T21:54:54.950388Z","steps":["trace[1648547796] 'process raft request' (duration: 119.96215ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:11.351258Z","caller":"traceutil/trace.go:171","msg":"trace[830242131] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"122.137997ms","start":"2026-05-29T21:55:11.229096Z","end":"2026-05-29T21:55:11.351234Z","steps":["trace[830242131] 'process raft request' (duration: 122.0154ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:17.385866Z","caller":"traceutil/trace.go:171","msg":"trace[881096074] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"163.131704ms","start":"2026-05-29T21:55:17.222691Z","end":"2026-05-29T21:55:17.385823Z","steps":["trace[881096074] 'process raft request' (duration: 163.001456ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:31.793809Z","caller":"traceutil/trace.go:171","msg":"trace[2018087001] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"229.182405ms","start":"2026-05-29T21:55:31.564603Z","end":"2026-05-29T21:55:31.793786Z","steps":["trace[2018087001] 'process raft request' (duration: 228.844195ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:46.427537Z","caller":"traceutil/trace.go:171","msg":"trace[1211736676] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"129.785175ms","start":"2026-05-29T21:55:46.297722Z","end":"2026-05-29T21:55:46.427507Z","steps":["trace[1211736676] 'process raft request' (duration: 129.632136ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:54.560968Z","caller":"traceutil/trace.go:171","msg":"trace[2033721412] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"103.82806ms","start":"2026-05-29T21:55:54.457113Z","end":"2026-05-29T21:55:54.560941Z","steps":["trace[2033721412] 'process raft request' (duration: 103.584965ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:55:55.081538Z","caller":"traceutil/trace.go:171","msg":"trace[1757460061] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"284.32568ms","start":"2026-05-29T21:55:54.797195Z","end":"2026-05-29T21:55:55.081521Z","steps":["trace[1757460061] 'process raft request' (duration: 284.240255ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:55.665006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.669052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:55:55.665082Z","caller":"traceutil/trace.go:171","msg":"trace[1494556662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1373; }","duration":"503.77935ms","start":"2026-05-29T21:55:55.161283Z","end":"2026-05-29T21:55:55.665062Z","steps":["trace[1494556662] 'range keys from in-memory index tree' (duration: 503.554626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:55:55.66512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-29T21:55:55.161259Z","time spent":"503.852644ms","remote":"127.0.0.1:56562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-29T21:55:55.664994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.273528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-29T21:55:55.66519Z","caller":"traceutil/trace.go:171","msg":"trace[502071522] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1373; }","duration":"254.537274ms","start":"2026-05-29T21:55:55.410631Z","end":"2026-05-29T21:55:55.665169Z","steps":["trace[502071522] 'count revisions from in-memory index tree' (duration: 254.099177ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:02.364856Z","caller":"traceutil/trace.go:171","msg":"trace[751016459] linearizableReadLoop","detail":"{readStateIndex:1460; appliedIndex:1459; }","duration":"112.371887ms","start":"2026-05-29T21:56:02.252461Z","end":"2026-05-29T21:56:02.364833Z","steps":["trace[751016459] 'read index received' (duration: 111.553426ms)","trace[751016459] 'applied index is now lower than readState.Index' (duration: 817.441µs)"],"step_count":2} {"level":"warn","ts":"2026-05-29T21:56:02.365043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.590015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cilium\" ","response":"range_response_count:1 size:1654"} {"level":"info","ts":"2026-05-29T21:56:02.365098Z","caller":"traceutil/trace.go:171","msg":"trace[1804191865] range","detail":"{range_begin:/registry/clusterroles/cilium; range_end:; response_count:1; response_revision:1400; }","duration":"106.67917ms","start":"2026-05-29T21:56:02.258403Z","end":"2026-05-29T21:56:02.365082Z","steps":["trace[1804191865] 'agreement among raft nodes before linearized reading' (duration: 106.573974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-29T21:56:02.365074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.59091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" limit:500 ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-05-29T21:56:02.365158Z","caller":"traceutil/trace.go:171","msg":"trace[1988052973] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:1; response_revision:1400; }","duration":"112.709288ms","start":"2026-05-29T21:56:02.252429Z","end":"2026-05-29T21:56:02.365138Z","steps":["trace[1988052973] 'agreement among raft nodes before linearized reading' (duration: 112.484524ms)"],"step_count":1} {"level":"info","ts":"2026-05-29T21:56:02.365525Z","caller":"traceutil/trace.go:171","msg":"trace[507499406] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"118.095455ms","start":"2026-05-29T21:56:02.246769Z","end":"2026-05-29T21:56:02.364864Z","steps":["trace[507499406] 'process raft request' (duration: 117.297775ms)"],"step_count":1}