{"level":"warn","ts":"2026-05-27T09:46:19.364915Z","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-27T09:46:19.365013Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.121: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.19.213.121:2380","--initial-cluster=instance=https://199.19.213.121:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.121:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.121: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-27T09:46:19.365089Z","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-27T09:46:19.365106Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.121:2380"]} {"level":"info","ts":"2026-05-27T09:46:19.365131Z","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-27T09:46:19.36615Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"]} {"level":"info","ts":"2026-05-27T09:46:19.366244Z","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.19.213.121:2380"],"listen-peer-urls":["https://199.19.213.121:2380"],"advertise-client-urls":["https://199.19.213.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.121: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-27T09:46:19.380502Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"14.003121ms"} {"level":"info","ts":"2026-05-27T09:46:19.391925Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"31541ae44470b70","cluster-id":"8ae2d89c48d1447d"} {"level":"info","ts":"2026-05-27T09:46:19.391998Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T09:46:19.392018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became follower at term 0"} {"level":"info","ts":"2026-05-27T09:46:19.392035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 31541ae44470b70 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-27T09:46:19.392043Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became follower at term 1"} {"level":"info","ts":"2026-05-27T09:46:19.392093Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=(222155973350329200)"} {"level":"warn","ts":"2026-05-27T09:46:19.403315Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T09:46:19.407573Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T09:46:19.410324Z","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-27T09:46:19.412876Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"31541ae44470b70","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-27T09:46:19.413526Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"31541ae44470b70","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-27T09:46:19.415462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 switched to configuration voters=(222155973350329200)"} {"level":"info","ts":"2026-05-27T09:46:19.415668Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ae2d89c48d1447d","local-member-id":"31541ae44470b70","added-peer-id":"31541ae44470b70","added-peer-peer-urls":["https://199.19.213.121:2380"]} {"level":"info","ts":"2026-05-27T09:46:19.413751Z","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-27T09:46:19.421898Z","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-27T09:46:19.421926Z","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-27T09:46:19.422726Z","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-27T09:46:19.422985Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"31541ae44470b70","initial-advertise-peer-urls":["https://199.19.213.121:2380"],"listen-peer-urls":["https://199.19.213.121:2380"],"advertise-client-urls":["https://199.19.213.121:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.121:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-27T09:46:19.423018Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T09:46:19.423063Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.121:2380"} {"level":"info","ts":"2026-05-27T09:46:19.42308Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.121:2380"} {"level":"info","ts":"2026-05-27T09:46:20.292388Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T09:46:20.292456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T09:46:20.292472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 received MsgPreVoteResp from 31541ae44470b70 at term 1"} {"level":"info","ts":"2026-05-27T09:46:20.292483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became candidate at term 2"} {"level":"info","ts":"2026-05-27T09:46:20.292488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 received MsgVoteResp from 31541ae44470b70 at term 2"} {"level":"info","ts":"2026-05-27T09:46:20.292495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"31541ae44470b70 became leader at term 2"} {"level":"info","ts":"2026-05-27T09:46:20.292503Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 31541ae44470b70 elected leader 31541ae44470b70 at term 2"} {"level":"info","ts":"2026-05-27T09:46:20.293304Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"31541ae44470b70","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.121:2379]}","request-path":"/0/members/31541ae44470b70/attributes","cluster-id":"8ae2d89c48d1447d","publish-timeout":"7s"} {"level":"info","ts":"2026-05-27T09:46:20.293368Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:46:20.293456Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T09:46:20.293512Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T09:46:20.293759Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T09:46:20.293829Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T09:46:20.29422Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ae2d89c48d1447d","local-member-id":"31541ae44470b70","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:46:20.294334Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:46:20.294364Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T09:46:20.296105Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.121:2379"} {"level":"info","ts":"2026-05-27T09:46:20.296579Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T09:46:31.805051Z","caller":"traceutil/trace.go:171","msg":"trace[874208204] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"105.559442ms","start":"2026-05-27T09:46:31.699457Z","end":"2026-05-27T09:46:31.805016Z","steps":["trace[874208204] 'process raft request' (duration: 105.403186ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:33.806244Z","caller":"traceutil/trace.go:171","msg":"trace[534556472] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"100.306839ms","start":"2026-05-27T09:46:33.705916Z","end":"2026-05-27T09:46:33.806222Z","steps":["trace[534556472] 'process raft request' (duration: 100.191065ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:34.432528Z","caller":"traceutil/trace.go:171","msg":"trace[955273925] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"175.456088ms","start":"2026-05-27T09:46:34.25703Z","end":"2026-05-27T09:46:34.432487Z","steps":["trace[955273925] 'process raft request' (duration: 175.320952ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:35.31165Z","caller":"traceutil/trace.go:171","msg":"trace[1201112902] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"104.842331ms","start":"2026-05-27T09:46:35.206774Z","end":"2026-05-27T09:46:35.311616Z","steps":["trace[1201112902] 'process raft request' (duration: 104.608801ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:37.555065Z","caller":"traceutil/trace.go:171","msg":"trace[52854346] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"227.303735ms","start":"2026-05-27T09:46:37.327742Z","end":"2026-05-27T09:46:37.555046Z","steps":["trace[52854346] 'process raft request' (duration: 227.193471ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:43.981181Z","caller":"traceutil/trace.go:171","msg":"trace[1460303915] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:399; }","duration":"401.41725ms","start":"2026-05-27T09:46:43.579729Z","end":"2026-05-27T09:46:43.981146Z","steps":["trace[1460303915] 'read index received' (duration: 401.197532ms)","trace[1460303915] 'applied index is now lower than readState.Index' (duration: 217.788µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:46:43.981249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:43.191279Z","time spent":"789.961192ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-27T09:46:43.981499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.707849ms","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-27T09:46:43.981543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.668173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T09:46:43.981544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.346683ms","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-27T09:46:43.98161Z","caller":"traceutil/trace.go:171","msg":"trace[4589790] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:386; }","duration":"401.909567ms","start":"2026-05-27T09:46:43.579675Z","end":"2026-05-27T09:46:43.981585Z","steps":["trace[4589790] 'agreement among raft nodes before linearized reading' (duration: 401.661708ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:43.981644Z","caller":"traceutil/trace.go:171","msg":"trace[1523715017] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:386; }","duration":"398.488248ms","start":"2026-05-27T09:46:43.583131Z","end":"2026-05-27T09:46:43.98162Z","steps":["trace[1523715017] 'agreement among raft nodes before linearized reading' (duration: 398.283461ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:43.981638Z","caller":"traceutil/trace.go:171","msg":"trace[2004585230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:386; }","duration":"259.787437ms","start":"2026-05-27T09:46:43.721805Z","end":"2026-05-27T09:46:43.981592Z","steps":["trace[2004585230] 'agreement among raft nodes before linearized reading' (duration: 259.625892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:43.981669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:43.57961Z","time spent":"402.048521ms","remote":"127.0.0.1:55354","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-27T09:46:43.981707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:43.583122Z","time spent":"398.575431ms","remote":"127.0.0.1:55354","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-27T09:46:44.483402Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332904878015371,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T09:46:44.754436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.430311ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:46:44.754678Z","caller":"traceutil/trace.go:171","msg":"trace[242360543] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:400; }","duration":"771.705263ms","start":"2026-05-27T09:46:43.982946Z","end":"2026-05-27T09:46:44.754652Z","steps":["trace[242360543] 'read index received' (duration: 607.507805ms)","trace[242360543] 'applied index is now lower than readState.Index' (duration: 164.196458ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:46:44.754713Z","caller":"traceutil/trace.go:171","msg":"trace[390371368] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"771.824518ms","start":"2026-05-27T09:46:43.982818Z","end":"2026-05-27T09:46:44.754643Z","steps":["trace[390371368] 'process raft request' (duration: 607.6185ms)","trace[390371368] 'compare' (duration: 163.282076ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:46:44.754934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:43.982802Z","time spent":"772.013014ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:46:44.75502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.290289ms","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-27T09:46:44.755032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.069517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:46:44.75512Z","caller":"traceutil/trace.go:171","msg":"trace[1486508741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:389; }","duration":"772.194531ms","start":"2026-05-27T09:46:43.982902Z","end":"2026-05-27T09:46:44.755096Z","steps":["trace[1486508741] 'agreement among raft nodes before linearized reading' (duration: 772.036755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:44.755157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.119977ms","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-27T09:46:44.75517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:43.982889Z","time spent":"772.272724ms","remote":"127.0.0.1:55096","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-27T09:46:44.755192Z","caller":"traceutil/trace.go:171","msg":"trace[1168622431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:389; }","duration":"471.224091ms","start":"2026-05-27T09:46:44.28396Z","end":"2026-05-27T09:46:44.755184Z","steps":["trace[1168622431] 'agreement among raft nodes before linearized reading' (duration: 471.17299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:44.755227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:44.283939Z","time spent":"471.279983ms","remote":"127.0.0.1:55354","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-27T09:46:44.755065Z","caller":"traceutil/trace.go:171","msg":"trace[1093300146] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:389; }","duration":"677.570899ms","start":"2026-05-27T09:46:44.077478Z","end":"2026-05-27T09:46:44.755048Z","steps":["trace[1093300146] 'agreement among raft nodes before linearized reading' (duration: 677.508807ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:44.755447Z","caller":"traceutil/trace.go:171","msg":"trace[1250360555] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"770.216012ms","start":"2026-05-27T09:46:43.985223Z","end":"2026-05-27T09:46:44.755439Z","steps":["trace[1250360555] 'process raft request' (duration: 769.329051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:44.755482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:44.077438Z","time spent":"678.021324ms","remote":"127.0.0.1:55354","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-27T09:46:44.75551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:43.985211Z","time spent":"770.259403ms","remote":"127.0.0.1:55354","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-27T09:46:44.755416Z","caller":"traceutil/trace.go:171","msg":"trace[1838396638] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"768.038277ms","start":"2026-05-27T09:46:43.987366Z","end":"2026-05-27T09:46:44.755404Z","steps":["trace[1838396638] 'process raft request' (duration: 767.254129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:44.755681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:43.987341Z","time spent":"768.276905ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:46:45.064676Z","caller":"traceutil/trace.go:171","msg":"trace[1697777293] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:403; }","duration":"304.464342ms","start":"2026-05-27T09:46:44.760188Z","end":"2026-05-27T09:46:45.064652Z","steps":["trace[1697777293] 'read index received' (duration: 57.286791ms)","trace[1697777293] 'applied index is now lower than readState.Index' (duration: 247.176581ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:46:45.064918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.565933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:46:45.06497Z","caller":"traceutil/trace.go:171","msg":"trace[788946633] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:391; }","duration":"301.657506ms","start":"2026-05-27T09:46:44.763297Z","end":"2026-05-27T09:46:45.064955Z","steps":["trace[788946633] 'agreement among raft nodes before linearized reading' (duration: 301.562373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:45.065013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:44.763291Z","time spent":"301.714298ms","remote":"127.0.0.1:55090","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-27T09:46:45.064834Z","caller":"traceutil/trace.go:171","msg":"trace[1007027541] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"304.884247ms","start":"2026-05-27T09:46:44.75991Z","end":"2026-05-27T09:46:45.064794Z","steps":["trace[1007027541] 'process raft request' (duration: 236.221207ms)","trace[1007027541] 'compare' (duration: 68.414971ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:46:45.065227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:44.759896Z","time spent":"305.230138ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:46:45.06541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.222098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T09:46:45.065461Z","caller":"traceutil/trace.go:171","msg":"trace[1120340590] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:391; }","duration":"305.29223ms","start":"2026-05-27T09:46:44.760159Z","end":"2026-05-27T09:46:45.065451Z","steps":["trace[1120340590] 'agreement among raft nodes before linearized reading' (duration: 304.654818ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:45.06549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:44.760154Z","time spent":"305.328751ms","remote":"127.0.0.1:55104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-05-27T09:46:46.458102Z","caller":"traceutil/trace.go:171","msg":"trace[1049245699] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"179.231933ms","start":"2026-05-27T09:46:46.278853Z","end":"2026-05-27T09:46:46.458085Z","steps":["trace[1049245699] 'process raft request' (duration: 179.13093ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:46.891183Z","caller":"traceutil/trace.go:171","msg":"trace[767188550] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"125.76815ms","start":"2026-05-27T09:46:46.765375Z","end":"2026-05-27T09:46:46.891143Z","steps":["trace[767188550] 'process raft request' (duration: 125.536252ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:47.023299Z","caller":"traceutil/trace.go:171","msg":"trace[1636215112] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"127.814862ms","start":"2026-05-27T09:46:46.895457Z","end":"2026-05-27T09:46:47.023272Z","steps":["trace[1636215112] 'process raft request' (duration: 39.670617ms)","trace[1636215112] 'compare' (duration: 87.860256ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:46:48.097454Z","caller":"traceutil/trace.go:171","msg":"trace[1527794501] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"220.340364ms","start":"2026-05-27T09:46:47.87709Z","end":"2026-05-27T09:46:48.097431Z","steps":["trace[1527794501] 'process raft request' (duration: 220.194499ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:48.533307Z","caller":"traceutil/trace.go:171","msg":"trace[1564970556] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"428.535124ms","start":"2026-05-27T09:46:48.104749Z","end":"2026-05-27T09:46:48.533284Z","steps":["trace[1564970556] 'process raft request' (duration: 428.397769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:48.533457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:48.10472Z","time spent":"428.666067ms","remote":"127.0.0.1:55542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:46:48.812733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.46931ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-27T09:46:48.813105Z","caller":"traceutil/trace.go:171","msg":"trace[1184982403] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"275.487385ms","start":"2026-05-27T09:46:48.537581Z","end":"2026-05-27T09:46:48.813069Z","steps":["trace[1184982403] 'process raft request' (duration: 275.254087ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:48.813166Z","caller":"traceutil/trace.go:171","msg":"trace[148116205] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"273.354454ms","start":"2026-05-27T09:46:48.539783Z","end":"2026-05-27T09:46:48.813137Z","steps":["trace[148116205] 'process raft request' (duration: 273.250971ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:48.813216Z","caller":"traceutil/trace.go:171","msg":"trace[178518876] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"274.323287ms","start":"2026-05-27T09:46:48.538868Z","end":"2026-05-27T09:46:48.813191Z","steps":["trace[178518876] 'process raft request' (duration: 274.09748ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:49.040934Z","caller":"traceutil/trace.go:171","msg":"trace[1851218784] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:417; }","duration":"190.494774ms","start":"2026-05-27T09:46:48.850423Z","end":"2026-05-27T09:46:49.040917Z","steps":["trace[1851218784] 'read index received' (duration: 61.232184ms)","trace[1851218784] 'applied index is now lower than readState.Index' (duration: 129.26191ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:46:49.040992Z","caller":"traceutil/trace.go:171","msg":"trace[68345539] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"220.641739ms","start":"2026-05-27T09:46:48.820315Z","end":"2026-05-27T09:46:49.040957Z","steps":["trace[68345539] 'process raft request' (duration: 220.521165ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:49.04116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.700966ms","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-27T09:46:49.041177Z","caller":"traceutil/trace.go:171","msg":"trace[1424038175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:407; }","duration":"146.744228ms","start":"2026-05-27T09:46:48.894427Z","end":"2026-05-27T09:46:49.041171Z","steps":["trace[1424038175] 'agreement among raft nodes before linearized reading' (duration: 146.717857ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:49.041209Z","caller":"traceutil/trace.go:171","msg":"trace[403292804] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"219.798332ms","start":"2026-05-27T09:46:48.821385Z","end":"2026-05-27T09:46:49.041184Z","steps":["trace[403292804] 'process raft request' (duration: 219.488111ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:49.041251Z","caller":"traceutil/trace.go:171","msg":"trace[1974767337] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"221.573361ms","start":"2026-05-27T09:46:48.819639Z","end":"2026-05-27T09:46:49.041212Z","steps":["trace[1974767337] 'process raft request' (duration: 155.401346ms)","trace[1974767337] 'compare' (duration: 65.646917ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:46:49.041069Z","caller":"traceutil/trace.go:171","msg":"trace[1883208996] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"219.569214ms","start":"2026-05-27T09:46:48.821493Z","end":"2026-05-27T09:46:49.041062Z","steps":["trace[1883208996] 'process raft request' (duration: 219.400359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:49.04113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.69316ms","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-27T09:46:49.041565Z","caller":"traceutil/trace.go:171","msg":"trace[1783033745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:407; }","duration":"191.195477ms","start":"2026-05-27T09:46:48.850364Z","end":"2026-05-27T09:46:49.04156Z","steps":["trace[1783033745] 'agreement among raft nodes before linearized reading' (duration: 190.716211ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:49.343804Z","caller":"traceutil/trace.go:171","msg":"trace[2051102637] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"298.339454ms","start":"2026-05-27T09:46:49.045439Z","end":"2026-05-27T09:46:49.343778Z","steps":["trace[2051102637] 'process raft request' (duration: 237.971638ms)","trace[2051102637] 'compare' (duration: 60.135518ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:46:49.344085Z","caller":"traceutil/trace.go:171","msg":"trace[1164602432] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"297.88511ms","start":"2026-05-27T09:46:49.046189Z","end":"2026-05-27T09:46:49.344074Z","steps":["trace[1164602432] 'process raft request' (duration: 297.765386ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:49.344086Z","caller":"traceutil/trace.go:171","msg":"trace[320463579] linearizableReadLoop","detail":"{readStateIndex:424; appliedIndex:422; }","duration":"297.966642ms","start":"2026-05-27T09:46:49.046102Z","end":"2026-05-27T09:46:49.344069Z","steps":["trace[320463579] 'read index received' (duration: 34.769568ms)","trace[320463579] 'applied index is now lower than readState.Index' (duration: 263.196564ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:46:49.34423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.107426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-gqq7w\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-05-27T09:46:49.344204Z","caller":"traceutil/trace.go:171","msg":"trace[1075129857] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"298.063485ms","start":"2026-05-27T09:46:49.04612Z","end":"2026-05-27T09:46:49.344184Z","steps":["trace[1075129857] 'process raft request' (duration: 297.612631ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:49.344258Z","caller":"traceutil/trace.go:171","msg":"trace[373232171] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-gqq7w; range_end:; response_count:1; response_revision:412; }","duration":"298.177399ms","start":"2026-05-27T09:46:49.046075Z","end":"2026-05-27T09:46:49.344252Z","steps":["trace[373232171] 'agreement among raft nodes before linearized reading' (duration: 298.085596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:49.34447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.955822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-27T09:46:49.344516Z","caller":"traceutil/trace.go:171","msg":"trace[1829747054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"247.053845ms","start":"2026-05-27T09:46:49.097452Z","end":"2026-05-27T09:46:49.344506Z","steps":["trace[1829747054] 'agreement among raft nodes before linearized reading' (duration: 246.961402ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:49.344612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.188941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-05-27T09:46:49.344763Z","caller":"traceutil/trace.go:171","msg":"trace[1992086802] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:412; }","duration":"295.45369ms","start":"2026-05-27T09:46:49.04927Z","end":"2026-05-27T09:46:49.344724Z","steps":["trace[1992086802] 'agreement among raft nodes before linearized reading' (duration: 295.13715ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:49.344237Z","caller":"traceutil/trace.go:171","msg":"trace[1141557970] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"296.272506ms","start":"2026-05-27T09:46:49.047922Z","end":"2026-05-27T09:46:49.344195Z","steps":["trace[1141557970] 'process raft request' (duration: 296.115401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:49.816373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.03396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:46:49.816561Z","caller":"traceutil/trace.go:171","msg":"trace[183451359] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:427; }","duration":"463.915522ms","start":"2026-05-27T09:46:49.352626Z","end":"2026-05-27T09:46:49.816542Z","steps":["trace[183451359] 'read index received' (duration: 258.723046ms)","trace[183451359] 'applied index is now lower than readState.Index' (duration: 205.191595ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:46:49.816646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.023036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3855"} {"level":"info","ts":"2026-05-27T09:46:49.816672Z","caller":"traceutil/trace.go:171","msg":"trace[1299905070] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:414; }","duration":"464.075317ms","start":"2026-05-27T09:46:49.352587Z","end":"2026-05-27T09:46:49.816663Z","steps":["trace[1299905070] 'agreement among raft nodes before linearized reading' (duration: 464.003755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:49.816706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:49.352575Z","time spent":"464.124069ms","remote":"127.0.0.1:55542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3878,"request content":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" "} {"level":"info","ts":"2026-05-27T09:46:49.816707Z","caller":"traceutil/trace.go:171","msg":"trace[1918877937] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"469.999651ms","start":"2026-05-27T09:46:49.346667Z","end":"2026-05-27T09:46:49.816667Z","steps":["trace[1918877937] 'process raft request' (duration: 264.593929ms)","trace[1918877937] 'compare' (duration: 204.914926ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:46:49.816709Z","caller":"traceutil/trace.go:171","msg":"trace[290694984] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"468.041958ms","start":"2026-05-27T09:46:49.348605Z","end":"2026-05-27T09:46:49.816647Z","steps":["trace[290694984] 'process raft request' (duration: 467.881063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:49.816882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:49.348585Z","time spent":"468.208203ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:46:49.816877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:49.346648Z","time spent":"470.131105ms","remote":"127.0.0.1:55254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4013,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:46:49.955566Z","caller":"traceutil/trace.go:171","msg":"trace[1504923837] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"131.415951ms","start":"2026-05-27T09:46:49.824129Z","end":"2026-05-27T09:46:49.955545Z","steps":["trace[1504923837] 'process raft request' (duration: 131.352379ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:49.95565Z","caller":"traceutil/trace.go:171","msg":"trace[972121661] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"135.224085ms","start":"2026-05-27T09:46:49.820364Z","end":"2026-05-27T09:46:49.955588Z","steps":["trace[972121661] 'process raft request' (duration: 108.348755ms)","trace[972121661] 'compare' (duration: 26.640643ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:46:50.515594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.741125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:46:50.515775Z","caller":"traceutil/trace.go:171","msg":"trace[1396589435] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"554.165843ms","start":"2026-05-27T09:46:49.961589Z","end":"2026-05-27T09:46:50.515755Z","steps":["trace[1396589435] 'process raft request' (duration: 158.209881ms)","trace[1396589435] 'compare' (duration: 395.616761ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:46:50.515836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:49.961564Z","time spent":"554.250965ms","remote":"127.0.0.1:55254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3563,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:46:50.515884Z","caller":"traceutil/trace.go:171","msg":"trace[304463136] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"551.561057ms","start":"2026-05-27T09:46:49.964309Z","end":"2026-05-27T09:46:50.51587Z","steps":["trace[304463136] 'process raft request' (duration: 551.452824ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:50.515915Z","caller":"traceutil/trace.go:171","msg":"trace[1781948051] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"551.600029ms","start":"2026-05-27T09:46:49.964276Z","end":"2026-05-27T09:46:50.515876Z","steps":["trace[1781948051] 'process raft request' (duration: 551.401762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:50.516046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:49.964263Z","time spent":"551.718762ms","remote":"127.0.0.1:55518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:46:50.515951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:49.964289Z","time spent":"551.622949ms","remote":"127.0.0.1:55542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:46:50.830529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.801095ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:46:50.830712Z","caller":"traceutil/trace.go:171","msg":"trace[770075781] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:439; }","duration":"109.21092ms","start":"2026-05-27T09:46:50.721487Z","end":"2026-05-27T09:46:50.830698Z","steps":["trace[770075781] 'read index received' (duration: 62.452µs)","trace[770075781] 'applied index is now lower than readState.Index' (duration: 109.147528ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:46:50.830783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.291352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:46:50.830806Z","caller":"traceutil/trace.go:171","msg":"trace[642014537] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"109.347174ms","start":"2026-05-27T09:46:50.721446Z","end":"2026-05-27T09:46:50.830794Z","steps":["trace[642014537] 'agreement among raft nodes before linearized reading' (duration: 109.290182ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:50.830916Z","caller":"traceutil/trace.go:171","msg":"trace[700040513] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"306.231894ms","start":"2026-05-27T09:46:50.524627Z","end":"2026-05-27T09:46:50.830859Z","steps":["trace[700040513] 'process raft request' (duration: 306.019447ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:50.831034Z","caller":"traceutil/trace.go:171","msg":"trace[1374931109] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"302.497843ms","start":"2026-05-27T09:46:50.528527Z","end":"2026-05-27T09:46:50.831025Z","steps":["trace[1374931109] 'process raft request' (duration: 302.141662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:50.831082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:50.52852Z","time spent":"302.535314ms","remote":"127.0.0.1:55542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:46:50.831165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:50.524618Z","time spent":"306.371978ms","remote":"127.0.0.1:55518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4180,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:46:50.831247Z","caller":"traceutil/trace.go:171","msg":"trace[536419108] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"307.052321ms","start":"2026-05-27T09:46:50.524185Z","end":"2026-05-27T09:46:50.831238Z","steps":["trace[536419108] 'process raft request' (duration: 306.41947ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:50.831251Z","caller":"traceutil/trace.go:171","msg":"trace[2100796555] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"310.602576ms","start":"2026-05-27T09:46:50.520635Z","end":"2026-05-27T09:46:50.831237Z","steps":["trace[2100796555] 'process raft request' (duration: 156.045007ms)","trace[2100796555] 'compare' (duration: 153.67841ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:46:50.831287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:50.524175Z","time spent":"307.092822ms","remote":"127.0.0.1:55254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:46:50.831305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:50.520626Z","time spent":"310.654628ms","remote":"127.0.0.1:55150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:46:51.349973Z","caller":"traceutil/trace.go:171","msg":"trace[451793991] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"264.388136ms","start":"2026-05-27T09:46:51.085564Z","end":"2026-05-27T09:46:51.349952Z","steps":["trace[451793991] 'read index received' (duration: 230.210639ms)","trace[451793991] 'applied index is now lower than readState.Index' (duration: 34.176567ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:46:51.350177Z","caller":"traceutil/trace.go:171","msg":"trace[1633722100] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"504.893213ms","start":"2026-05-27T09:46:50.845276Z","end":"2026-05-27T09:46:51.350169Z","steps":["trace[1633722100] 'process raft request' (duration: 470.593082ms)","trace[1633722100] 'compare' (duration: 33.996792ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:46:51.350253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:50.84526Z","time spent":"504.937065ms","remote":"127.0.0.1:55542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:46:51.350363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.82018ms","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-27T09:46:51.350468Z","caller":"traceutil/trace.go:171","msg":"trace[882977360] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:429; }","duration":"264.964064ms","start":"2026-05-27T09:46:51.085487Z","end":"2026-05-27T09:46:51.350451Z","steps":["trace[882977360] 'agreement among raft nodes before linearized reading' (duration: 264.784119ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:53.373681Z","caller":"traceutil/trace.go:171","msg":"trace[1774645451] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"132.970405ms","start":"2026-05-27T09:46:53.240682Z","end":"2026-05-27T09:46:53.373653Z","steps":["trace[1774645451] 'process raft request' (duration: 98.449909ms)","trace[1774645451] 'compare' (duration: 34.407392ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:46:53.762767Z","caller":"traceutil/trace.go:171","msg":"trace[833923002] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:452; }","duration":"364.782004ms","start":"2026-05-27T09:46:53.397962Z","end":"2026-05-27T09:46:53.762744Z","steps":["trace[833923002] 'read index received' (duration: 48.812067ms)","trace[833923002] 'applied index is now lower than readState.Index' (duration: 315.968767ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:46:53.762916Z","caller":"traceutil/trace.go:171","msg":"trace[354170534] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"383.935087ms","start":"2026-05-27T09:46:53.378969Z","end":"2026-05-27T09:46:53.762904Z","steps":["trace[354170534] 'process raft request' (duration: 383.623647ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:53.762905Z","caller":"traceutil/trace.go:171","msg":"trace[1841433188] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"383.971208ms","start":"2026-05-27T09:46:53.378904Z","end":"2026-05-27T09:46:53.762875Z","steps":["trace[1841433188] 'process raft request' (duration: 355.700538ms)","trace[1841433188] 'compare' (duration: 27.726243ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:46:53.763035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:53.378962Z","time spent":"383.982718ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:46:53.763049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:53.378895Z","time spent":"384.077431ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:46:53.763214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.260098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-27T09:46:53.763254Z","caller":"traceutil/trace.go:171","msg":"trace[771895599] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:439; }","duration":"365.327071ms","start":"2026-05-27T09:46:53.397918Z","end":"2026-05-27T09:46:53.763245Z","steps":["trace[771895599] 'agreement among raft nodes before linearized reading' (duration: 365.256948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:53.763301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:46:53.397903Z","time spent":"365.390852ms","remote":"127.0.0.1:55232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-05-27T09:46:54.051292Z","caller":"traceutil/trace.go:171","msg":"trace[1494385116] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"216.77342ms","start":"2026-05-27T09:46:53.834497Z","end":"2026-05-27T09:46:54.05127Z","steps":["trace[1494385116] 'process raft request' (duration: 124.705575ms)","trace[1494385116] 'compare' (duration: 91.972952ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:46:56.066676Z","caller":"traceutil/trace.go:171","msg":"trace[262729345] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"120.33643ms","start":"2026-05-27T09:46:55.946305Z","end":"2026-05-27T09:46:56.066641Z","steps":["trace[262729345] 'process raft request' (duration: 119.988679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:46:59.699482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.280747ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-27T09:46:59.699583Z","caller":"traceutil/trace.go:171","msg":"trace[1548172221] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:465; }","duration":"220.062206ms","start":"2026-05-27T09:46:59.479504Z","end":"2026-05-27T09:46:59.699566Z","steps":["trace[1548172221] 'read index received' (duration: 55.625014ms)","trace[1548172221] 'applied index is now lower than readState.Index' (duration: 164.435682ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:46:59.699687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.1795ms","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-27T09:46:59.699736Z","caller":"traceutil/trace.go:171","msg":"trace[197722187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:449; }","duration":"220.267003ms","start":"2026-05-27T09:46:59.479455Z","end":"2026-05-27T09:46:59.699722Z","steps":["trace[197722187] 'agreement among raft nodes before linearized reading' (duration: 220.157479ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:46:59.893327Z","caller":"traceutil/trace.go:171","msg":"trace[1444540016] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"189.693653ms","start":"2026-05-27T09:46:59.70362Z","end":"2026-05-27T09:46:59.893314Z","steps":["trace[1444540016] 'process raft request' (duration: 189.544579ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:01.335445Z","caller":"traceutil/trace.go:171","msg":"trace[1372715450] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"234.4687ms","start":"2026-05-27T09:47:01.100953Z","end":"2026-05-27T09:47:01.335422Z","steps":["trace[1372715450] 'process raft request' (duration: 234.114769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:01.678406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.128365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:47:01.678637Z","caller":"traceutil/trace.go:171","msg":"trace[1896171343] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"305.058531ms","start":"2026-05-27T09:47:01.373561Z","end":"2026-05-27T09:47:01.67862Z","steps":["trace[1896171343] 'process raft request' (duration: 304.983128ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:01.678637Z","caller":"traceutil/trace.go:171","msg":"trace[685646966] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"305.084571ms","start":"2026-05-27T09:47:01.373524Z","end":"2026-05-27T09:47:01.678609Z","steps":["trace[685646966] 'process raft request' (duration: 179.672608ms)","trace[685646966] 'compare' (duration: 124.827195ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:47:01.678697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:01.373543Z","time spent":"305.130683ms","remote":"127.0.0.1:55276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":505,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:47:01.678762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:01.373506Z","time spent":"305.192505ms","remote":"127.0.0.1:55276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:47:01.869562Z","caller":"traceutil/trace.go:171","msg":"trace[1170053062] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:473; }","duration":"147.861569ms","start":"2026-05-27T09:47:01.721683Z","end":"2026-05-27T09:47:01.869545Z","steps":["trace[1170053062] 'read index received' (duration: 130.020367ms)","trace[1170053062] 'applied index is now lower than readState.Index' (duration: 17.840552ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:01.869619Z","caller":"traceutil/trace.go:171","msg":"trace[1236388834] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"185.115658ms","start":"2026-05-27T09:47:01.684435Z","end":"2026-05-27T09:47:01.869551Z","steps":["trace[1236388834] 'process raft request' (duration: 167.356058ms)","trace[1236388834] 'compare' (duration: 17.664847ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:47:01.869675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.979673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:47:01.869697Z","caller":"traceutil/trace.go:171","msg":"trace[1313148385] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:457; }","duration":"148.044505ms","start":"2026-05-27T09:47:01.721646Z","end":"2026-05-27T09:47:01.86969Z","steps":["trace[1313148385] 'agreement among raft nodes before linearized reading' (duration: 147.981273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:02.263221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.617151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:47:02.26333Z","caller":"traceutil/trace.go:171","msg":"trace[1020792608] linearizableReadLoop","detail":"{readStateIndex:476; appliedIndex:474; }","duration":"366.517376ms","start":"2026-05-27T09:47:01.896795Z","end":"2026-05-27T09:47:02.263312Z","steps":["trace[1020792608] 'read index received' (duration: 30.270304ms)","trace[1020792608] 'applied index is now lower than readState.Index' (duration: 336.245632ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:47:02.26312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.145788ms","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-27T09:47:02.263343Z","caller":"traceutil/trace.go:171","msg":"trace[1536884162] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"387.931011ms","start":"2026-05-27T09:47:01.875387Z","end":"2026-05-27T09:47:02.263318Z","steps":["trace[1536884162] 'process raft request' (duration: 230.151735ms)","trace[1536884162] 'compare' (duration: 157.402755ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:02.263415Z","caller":"traceutil/trace.go:171","msg":"trace[467241691] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:458; }","duration":"176.446926ms","start":"2026-05-27T09:47:02.086922Z","end":"2026-05-27T09:47:02.263368Z","steps":["trace[467241691] 'range keys from in-memory index tree' (duration: 176.133387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:02.26344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:01.875371Z","time spent":"388.022434ms","remote":"127.0.0.1:55426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2364,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:47:02.263544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.740602ms","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-27T09:47:02.263579Z","caller":"traceutil/trace.go:171","msg":"trace[946604072] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:459; }","duration":"366.816304ms","start":"2026-05-27T09:47:01.896754Z","end":"2026-05-27T09:47:02.263571Z","steps":["trace[946604072] 'agreement among raft nodes before linearized reading' (duration: 366.642679ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:02.2636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:01.896742Z","time spent":"366.853405ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T09:47:02.263688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.67081ms","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-27T09:47:02.263738Z","caller":"traceutil/trace.go:171","msg":"trace[1656271588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:459; }","duration":"340.753643ms","start":"2026-05-27T09:47:01.922969Z","end":"2026-05-27T09:47:02.263723Z","steps":["trace[1656271588] 'agreement among raft nodes before linearized reading' (duration: 340.6692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:02.263771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:01.922956Z","time spent":"340.807935ms","remote":"127.0.0.1:55354","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-27T09:47:02.263865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.946789ms","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-27T09:47:02.264067Z","caller":"traceutil/trace.go:171","msg":"trace[998838601] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:459; }","duration":"341.072243ms","start":"2026-05-27T09:47:01.922861Z","end":"2026-05-27T09:47:02.263933Z","steps":["trace[998838601] 'agreement among raft nodes before linearized reading' (duration: 340.743813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:02.264733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:01.922818Z","time spent":"341.897107ms","remote":"127.0.0.1:55354","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-27T09:47:02.456342Z","caller":"traceutil/trace.go:171","msg":"trace[835181927] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"188.725283ms","start":"2026-05-27T09:47:02.267589Z","end":"2026-05-27T09:47:02.456314Z","steps":["trace[835181927] 'process raft request' (duration: 187.992262ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:02.45642Z","caller":"traceutil/trace.go:171","msg":"trace[1219469538] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"187.554999ms","start":"2026-05-27T09:47:02.268842Z","end":"2026-05-27T09:47:02.456397Z","steps":["trace[1219469538] 'process raft request' (duration: 187.510878ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:02.456431Z","caller":"traceutil/trace.go:171","msg":"trace[643959188] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"188.530677ms","start":"2026-05-27T09:47:02.26786Z","end":"2026-05-27T09:47:02.456391Z","steps":["trace[643959188] 'process raft request' (duration: 188.384033ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:02.456616Z","caller":"traceutil/trace.go:171","msg":"trace[1940207267] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"188.311521ms","start":"2026-05-27T09:47:02.268296Z","end":"2026-05-27T09:47:02.456608Z","steps":["trace[1940207267] 'process raft request' (duration: 188.027513ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:02.456643Z","caller":"traceutil/trace.go:171","msg":"trace[1718204739] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"188.680272ms","start":"2026-05-27T09:47:02.267941Z","end":"2026-05-27T09:47:02.456621Z","steps":["trace[1718204739] 'process raft request' (duration: 188.349792ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:02.874349Z","caller":"traceutil/trace.go:171","msg":"trace[1458407549] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:487; }","duration":"151.517086ms","start":"2026-05-27T09:47:02.722809Z","end":"2026-05-27T09:47:02.874326Z","steps":["trace[1458407549] 'read index received' (duration: 150.997611ms)","trace[1458407549] 'applied index is now lower than readState.Index' (duration: 518.234µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:02.874701Z","caller":"traceutil/trace.go:171","msg":"trace[121096110] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"213.541142ms","start":"2026-05-27T09:47:02.66112Z","end":"2026-05-27T09:47:02.874661Z","steps":["trace[121096110] 'process raft request' (duration: 212.595834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:02.874741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.916629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:47:02.874798Z","caller":"traceutil/trace.go:171","msg":"trace[476674216] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"152.026132ms","start":"2026-05-27T09:47:02.722756Z","end":"2026-05-27T09:47:02.874782Z","steps":["trace[476674216] 'agreement among raft nodes before linearized reading' (duration: 151.679512ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:03.051592Z","caller":"traceutil/trace.go:171","msg":"trace[1660774951] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:488; }","duration":"175.19611ms","start":"2026-05-27T09:47:02.87637Z","end":"2026-05-27T09:47:03.051566Z","steps":["trace[1660774951] 'read index received' (duration: 172.40773ms)","trace[1660774951] 'applied index is now lower than readState.Index' (duration: 2.787ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:03.051701Z","caller":"traceutil/trace.go:171","msg":"trace[533966489] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"252.868308ms","start":"2026-05-27T09:47:02.798804Z","end":"2026-05-27T09:47:03.051672Z","steps":["trace[533966489] 'process raft request' (duration: 252.195179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:03.051914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.517309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:47:03.052057Z","caller":"traceutil/trace.go:171","msg":"trace[1713968402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:473; }","duration":"175.681814ms","start":"2026-05-27T09:47:02.876327Z","end":"2026-05-27T09:47:03.052009Z","steps":["trace[1713968402] 'agreement among raft nodes before linearized reading' (duration: 175.421446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:03.052214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.601889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:47:03.052307Z","caller":"traceutil/trace.go:171","msg":"trace[1342790821] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:473; }","duration":"169.748983ms","start":"2026-05-27T09:47:02.882541Z","end":"2026-05-27T09:47:03.05229Z","steps":["trace[1342790821] 'agreement among raft nodes before linearized reading' (duration: 169.519466ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:03.052843Z","caller":"traceutil/trace.go:171","msg":"trace[407828942] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"322.466724ms","start":"2026-05-27T09:47:02.728308Z","end":"2026-05-27T09:47:03.050775Z","steps":["trace[407828942] 'process raft request' (duration: 320.434286ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:03.053821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:02.728276Z","time spent":"325.450349ms","remote":"127.0.0.1:55542","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4177,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:47:03.380831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.241538ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:47:03.381583Z","caller":"traceutil/trace.go:171","msg":"trace[282379189] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"325.382162ms","start":"2026-05-27T09:47:03.056027Z","end":"2026-05-27T09:47:03.38141Z","steps":["trace[282379189] 'process raft request' (duration: 173.493895ms)","trace[282379189] 'compare' (duration: 151.120725ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:47:03.381716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:03.056003Z","time spent":"325.65591ms","remote":"127.0.0.1:55254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15015,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:47:03.386377Z","caller":"traceutil/trace.go:171","msg":"trace[1394003485] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:490; }","duration":"210.542968ms","start":"2026-05-27T09:47:03.175786Z","end":"2026-05-27T09:47:03.386329Z","steps":["trace[1394003485] 'read index received' (duration: 53.71625ms)","trace[1394003485] 'applied index is now lower than readState.Index' (duration: 156.825908ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:03.386545Z","caller":"traceutil/trace.go:171","msg":"trace[973611840] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"325.177325ms","start":"2026-05-27T09:47:03.061335Z","end":"2026-05-27T09:47:03.386513Z","steps":["trace[973611840] 'process raft request' (duration: 324.942129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:03.386696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.740344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.121\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-27T09:47:03.386765Z","caller":"traceutil/trace.go:171","msg":"trace[1618684663] range","detail":"{range_begin:/registry/masterleases/199.19.213.121; range_end:; response_count:1; response_revision:477; }","duration":"195.851147ms","start":"2026-05-27T09:47:03.190903Z","end":"2026-05-27T09:47:03.386754Z","steps":["trace[1618684663] 'agreement among raft nodes before linearized reading' (duration: 195.642131ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:03.386866Z","caller":"traceutil/trace.go:171","msg":"trace[1769929652] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"327.606846ms","start":"2026-05-27T09:47:03.059247Z","end":"2026-05-27T09:47:03.386854Z","steps":["trace[1769929652] 'process raft request' (duration: 326.964058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:03.386921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:03.059228Z","time spent":"327.670977ms","remote":"127.0.0.1:55254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3576,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:47:03.386706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:03.061326Z","time spent":"325.295589ms","remote":"127.0.0.1:55518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4320,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:47:03.387271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.481225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-05-27T09:47:03.38739Z","caller":"traceutil/trace.go:171","msg":"trace[823943497] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"327.372629ms","start":"2026-05-27T09:47:03.059106Z","end":"2026-05-27T09:47:03.386478Z","steps":["trace[823943497] 'process raft request' (duration: 326.049782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:03.387558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:03.059087Z","time spent":"328.417859ms","remote":"127.0.0.1:55150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:47:03.387364Z","caller":"traceutil/trace.go:171","msg":"trace[1952172533] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:477; }","duration":"211.61194ms","start":"2026-05-27T09:47:03.175719Z","end":"2026-05-27T09:47:03.387331Z","steps":["trace[1952172533] 'agreement among raft nodes before linearized reading' (duration: 210.94205ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:05.415635Z","caller":"traceutil/trace.go:171","msg":"trace[946490673] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"545.797265ms","start":"2026-05-27T09:47:04.869814Z","end":"2026-05-27T09:47:05.415611Z","steps":["trace[946490673] 'process raft request' (duration: 545.666321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:05.415777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:04.869795Z","time spent":"545.914009ms","remote":"127.0.0.1:55116","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-27T09:47:05.428489Z","caller":"traceutil/trace.go:171","msg":"trace[1581419234] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"555.064965ms","start":"2026-05-27T09:47:04.873411Z","end":"2026-05-27T09:47:05.428476Z","steps":["trace[1581419234] 'process raft request' (duration: 554.587512ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:05.428527Z","caller":"traceutil/trace.go:171","msg":"trace[414745969] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"555.007154ms","start":"2026-05-27T09:47:04.873511Z","end":"2026-05-27T09:47:05.428518Z","steps":["trace[414745969] 'process raft request' (duration: 554.605643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:05.428561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:04.873378Z","time spent":"555.145247ms","remote":"127.0.0.1:55116","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-27T09:47:05.428473Z","caller":"traceutil/trace.go:171","msg":"trace[1168107452] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"545.327058ms","start":"2026-05-27T09:47:04.883115Z","end":"2026-05-27T09:47:05.428442Z","steps":["trace[1168107452] 'process raft request' (duration: 545.04111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:05.428595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:04.873504Z","time spent":"555.061015ms","remote":"127.0.0.1:55116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:47:05.428583Z","caller":"traceutil/trace.go:171","msg":"trace[673729519] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"545.375989ms","start":"2026-05-27T09:47:04.883178Z","end":"2026-05-27T09:47:05.428554Z","steps":["trace[673729519] 'process raft request' (duration: 545.108802ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:05.428667Z","caller":"traceutil/trace.go:171","msg":"trace[2012580187] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"541.144029ms","start":"2026-05-27T09:47:04.887516Z","end":"2026-05-27T09:47:05.42866Z","steps":["trace[2012580187] 'process raft request' (duration: 540.83128ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:05.428691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:04.887503Z","time spent":"541.17643ms","remote":"127.0.0.1:55116","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-27T09:47:05.428704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:04.883175Z","time spent":"545.475912ms","remote":"127.0.0.1:55116","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-27T09:47:05.428698Z","caller":"traceutil/trace.go:171","msg":"trace[620592620] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"525.38648ms","start":"2026-05-27T09:47:04.903291Z","end":"2026-05-27T09:47:05.428677Z","steps":["trace[620592620] 'process raft request' (duration: 525.096722ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:05.428706Z","caller":"traceutil/trace.go:171","msg":"trace[1459855114] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"380.864784ms","start":"2026-05-27T09:47:05.047818Z","end":"2026-05-27T09:47:05.428683Z","steps":["trace[1459855114] 'process raft request' (duration: 380.816152ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:05.42875Z","caller":"traceutil/trace.go:171","msg":"trace[1473592668] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"544.672839ms","start":"2026-05-27T09:47:04.884069Z","end":"2026-05-27T09:47:05.428742Z","steps":["trace[1473592668] 'process raft request' (duration: 544.246537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:05.428788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:04.884063Z","time spent":"544.70902ms","remote":"127.0.0.1:55116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:47:05.428785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:04.903277Z","time spent":"525.464683ms","remote":"127.0.0.1:55116","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-27T09:47:05.428623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:04.883106Z","time spent":"545.505763ms","remote":"127.0.0.1:55116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:47:05.428835Z","caller":"traceutil/trace.go:171","msg":"trace[217232021] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"545.675427ms","start":"2026-05-27T09:47:04.883101Z","end":"2026-05-27T09:47:05.428777Z","steps":["trace[217232021] 'process raft request' (duration: 545.090451ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:05.428801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:05.047787Z","time spent":"380.970117ms","remote":"127.0.0.1:55116","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-27T09:47:05.42884Z","caller":"traceutil/trace.go:171","msg":"trace[1219766371] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"523.768956ms","start":"2026-05-27T09:47:04.905063Z","end":"2026-05-27T09:47:05.428832Z","steps":["trace[1219766371] 'process raft request' (duration: 523.470797ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:05.428865Z","caller":"traceutil/trace.go:171","msg":"trace[36248013] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"496.15007ms","start":"2026-05-27T09:47:04.932708Z","end":"2026-05-27T09:47:05.428858Z","steps":["trace[36248013] 'process raft request' (duration: 495.872643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:05.428873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:04.883079Z","time spent":"545.77748ms","remote":"127.0.0.1:55116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:47:05.428889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:04.905055Z","time spent":"523.822927ms","remote":"127.0.0.1:55116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:47:05.428895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:04.932682Z","time spent":"496.198821ms","remote":"127.0.0.1:55116","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-27T09:47:06.162067Z","caller":"traceutil/trace.go:171","msg":"trace[337974640] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"654.39123ms","start":"2026-05-27T09:47:05.507647Z","end":"2026-05-27T09:47:06.162038Z","steps":["trace[337974640] 'process raft request' (duration: 641.83506ms)","trace[337974640] 'compare' (duration: 12.392855ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:47:06.162223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:05.507629Z","time spent":"654.514764ms","remote":"127.0.0.1:55116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:47:06.162682Z","caller":"traceutil/trace.go:171","msg":"trace[11838052] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"654.08156ms","start":"2026-05-27T09:47:05.508588Z","end":"2026-05-27T09:47:06.16267Z","steps":["trace[11838052] 'process raft request' (duration: 653.882695ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:06.162744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:05.508543Z","time spent":"654.169453ms","remote":"127.0.0.1:55116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:47:06.162788Z","caller":"traceutil/trace.go:171","msg":"trace[2121891083] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:542; }","duration":"419.199532ms","start":"2026-05-27T09:47:05.743577Z","end":"2026-05-27T09:47:06.162777Z","steps":["trace[2121891083] 'read index received' (duration: 405.953223ms)","trace[2121891083] 'applied index is now lower than readState.Index' (duration: 13.245649ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:06.1628Z","caller":"traceutil/trace.go:171","msg":"trace[907948053] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"650.5019ms","start":"2026-05-27T09:47:05.512274Z","end":"2026-05-27T09:47:06.162776Z","steps":["trace[907948053] 'process raft request' (duration: 650.273834ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:06.162886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.314705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:47:06.162877Z","caller":"traceutil/trace.go:171","msg":"trace[1709871577] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"647.880436ms","start":"2026-05-27T09:47:05.514972Z","end":"2026-05-27T09:47:06.162853Z","steps":["trace[1709871577] 'process raft request' (duration: 647.604589ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:06.162936Z","caller":"traceutil/trace.go:171","msg":"trace[1288873352] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"603.68941ms","start":"2026-05-27T09:47:05.55924Z","end":"2026-05-27T09:47:06.162929Z","steps":["trace[1288873352] 'process raft request' (duration: 603.393732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:06.162915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:05.512257Z","time spent":"650.617293ms","remote":"127.0.0.1:55116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:47:06.162979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:05.559219Z","time spent":"603.736372ms","remote":"127.0.0.1:55116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:47:06.163025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:05.514945Z","time spent":"647.99542ms","remote":"127.0.0.1:55116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:47:06.162911Z","caller":"traceutil/trace.go:171","msg":"trace[1924728849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"419.339916ms","start":"2026-05-27T09:47:05.743563Z","end":"2026-05-27T09:47:06.162903Z","steps":["trace[1924728849] 'agreement among raft nodes before linearized reading' (duration: 419.246323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:06.163127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:05.743508Z","time spent":"419.610173ms","remote":"127.0.0.1:55090","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-27T09:47:07.036132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332904878015645,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T09:47:07.220821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.017025ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:47:07.220964Z","caller":"traceutil/trace.go:171","msg":"trace[1742910819] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"786.70832ms","start":"2026-05-27T09:47:06.434221Z","end":"2026-05-27T09:47:07.22093Z","steps":["trace[1742910819] 'process raft request' (duration: 637.498849ms)","trace[1742910819] 'compare' (duration: 148.863781ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:47:07.221025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:06.4342Z","time spent":"786.798332ms","remote":"127.0.0.1:55116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T09:47:07.222204Z","caller":"traceutil/trace.go:171","msg":"trace[1227014514] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:569; }","duration":"686.793499ms","start":"2026-05-27T09:47:06.535385Z","end":"2026-05-27T09:47:07.222179Z","steps":["trace[1227014514] 'read index received' (duration: 536.367355ms)","trace[1227014514] 'applied index is now lower than readState.Index' (duration: 150.424924ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:07.222513Z","caller":"traceutil/trace.go:171","msg":"trace[782070018] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"787.037559ms","start":"2026-05-27T09:47:06.435463Z","end":"2026-05-27T09:47:07.222501Z","steps":["trace[782070018] 'process raft request' (duration: 786.432991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:07.222616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:06.435433Z","time spent":"787.1185ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:47:07.222998Z","caller":"traceutil/trace.go:171","msg":"trace[790074568] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"782.069659ms","start":"2026-05-27T09:47:06.440912Z","end":"2026-05-27T09:47:07.222981Z","steps":["trace[790074568] 'process raft request' (duration: 781.143762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:07.223117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:06.440899Z","time spent":"782.152382ms","remote":"127.0.0.1:55116","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151212,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:47:07.223372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.981912ms","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-27T09:47:07.223426Z","caller":"traceutil/trace.go:171","msg":"trace[1861310133] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:550; }","duration":"688.054374ms","start":"2026-05-27T09:47:06.535341Z","end":"2026-05-27T09:47:07.223396Z","steps":["trace[1861310133] 'agreement among raft nodes before linearized reading' (duration: 687.934671ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:07.223458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:06.535323Z","time spent":"688.128466ms","remote":"127.0.0.1:55354","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-27T09:47:07.223599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.252857ms","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-27T09:47:07.223652Z","caller":"traceutil/trace.go:171","msg":"trace[2002140567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:550; }","duration":"679.323499ms","start":"2026-05-27T09:47:06.544314Z","end":"2026-05-27T09:47:07.223637Z","steps":["trace[2002140567] 'agreement among raft nodes before linearized reading' (duration: 679.139044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:07.223698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:06.544307Z","time spent":"679.383431ms","remote":"127.0.0.1:55354","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-27T09:47:07.223836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.21998ms","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-27T09:47:07.223864Z","caller":"traceutil/trace.go:171","msg":"trace[1315240615] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:550; }","duration":"682.263291ms","start":"2026-05-27T09:47:06.541591Z","end":"2026-05-27T09:47:07.223854Z","steps":["trace[1315240615] 'agreement among raft nodes before linearized reading' (duration: 681.86396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:07.223896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:06.541585Z","time spent":"682.304553ms","remote":"127.0.0.1:55354","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-27T09:47:10.181766Z","caller":"traceutil/trace.go:171","msg":"trace[553524931] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"161.288131ms","start":"2026-05-27T09:47:10.020438Z","end":"2026-05-27T09:47:10.181726Z","steps":["trace[553524931] 'process raft request' (duration: 156.291546ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:11.883109Z","caller":"traceutil/trace.go:171","msg":"trace[509629905] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"161.427646ms","start":"2026-05-27T09:47:11.721654Z","end":"2026-05-27T09:47:11.883082Z","steps":["trace[509629905] 'read index received' (duration: 160.43552ms)","trace[509629905] 'applied index is now lower than readState.Index' (duration: 991.276µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:11.883188Z","caller":"traceutil/trace.go:171","msg":"trace[149599581] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"197.896002ms","start":"2026-05-27T09:47:11.685255Z","end":"2026-05-27T09:47:11.883151Z","steps":["trace[149599581] 'process raft request' (duration: 197.199683ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:11.883231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.56121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:47:11.883289Z","caller":"traceutil/trace.go:171","msg":"trace[1909491983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:594; }","duration":"161.668533ms","start":"2026-05-27T09:47:11.721608Z","end":"2026-05-27T09:47:11.883277Z","steps":["trace[1909491983] 'agreement among raft nodes before linearized reading' (duration: 161.57418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:12.196568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.193606ms","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-27T09:47:12.196726Z","caller":"traceutil/trace.go:171","msg":"trace[1094229170] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:594; }","duration":"109.367101ms","start":"2026-05-27T09:47:12.087339Z","end":"2026-05-27T09:47:12.196706Z","steps":["trace[1094229170] 'range keys from in-memory index tree' (duration: 109.177356ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:12.196743Z","caller":"traceutil/trace.go:171","msg":"trace[931006512] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"252.958883ms","start":"2026-05-27T09:47:11.943756Z","end":"2026-05-27T09:47:12.196715Z","steps":["trace[931006512] 'process raft request' (duration: 213.626789ms)","trace[931006512] 'compare' (duration: 39.034916ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:47:13.223349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332904878015754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T09:47:13.234046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"886.44546ms","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-27T09:47:13.23464Z","caller":"traceutil/trace.go:171","msg":"trace[1749462865] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:596; }","duration":"886.579313ms","start":"2026-05-27T09:47:12.347527Z","end":"2026-05-27T09:47:13.234106Z","steps":["trace[1749462865] 'range keys from in-memory index tree' (duration: 886.187662ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:13.234768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:12.347508Z","time spent":"887.23949ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T09:47:13.240285Z","caller":"traceutil/trace.go:171","msg":"trace[1330170825] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"534.674319ms","start":"2026-05-27T09:47:12.705578Z","end":"2026-05-27T09:47:13.240253Z","steps":["trace[1330170825] 'process raft request' (duration: 533.857397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:13.240507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:12.705552Z","time spent":"534.840543ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:47:13.240957Z","caller":"traceutil/trace.go:171","msg":"trace[770034186] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"326.94029ms","start":"2026-05-27T09:47:12.914008Z","end":"2026-05-27T09:47:13.240948Z","steps":["trace[770034186] 'process raft request' (duration: 326.781656ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:13.241008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:12.913982Z","time spent":"326.999682ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:47:13.24097Z","caller":"traceutil/trace.go:171","msg":"trace[1454024147] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:619; }","duration":"518.667214ms","start":"2026-05-27T09:47:12.722269Z","end":"2026-05-27T09:47:13.240936Z","steps":["trace[1454024147] 'read index received' (duration: 516.859636ms)","trace[1454024147] 'applied index is now lower than readState.Index' (duration: 1.805977ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:47:13.24123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.951872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:47:13.241319Z","caller":"traceutil/trace.go:171","msg":"trace[1167523428] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:598; }","duration":"519.042794ms","start":"2026-05-27T09:47:12.722254Z","end":"2026-05-27T09:47:13.241297Z","steps":["trace[1167523428] 'agreement among raft nodes before linearized reading' (duration: 518.914601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:13.241354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:12.72223Z","time spent":"519.118275ms","remote":"127.0.0.1:55090","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-27T09:47:13.931987Z","caller":"traceutil/trace.go:171","msg":"trace[714966890] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"209.344536ms","start":"2026-05-27T09:47:13.722615Z","end":"2026-05-27T09:47:13.93196Z","steps":["trace[714966890] 'read index received' (duration: 207.922558ms)","trace[714966890] 'applied index is now lower than readState.Index' (duration: 1.421118ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:13.932081Z","caller":"traceutil/trace.go:171","msg":"trace[1325479433] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"211.995146ms","start":"2026-05-27T09:47:13.720065Z","end":"2026-05-27T09:47:13.93206Z","steps":["trace[1325479433] 'process raft request' (duration: 210.688931ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:13.932202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.553292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:47:13.932291Z","caller":"traceutil/trace.go:171","msg":"trace[644856935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"209.692675ms","start":"2026-05-27T09:47:13.722576Z","end":"2026-05-27T09:47:13.932269Z","steps":["trace[644856935] 'agreement among raft nodes before linearized reading' (duration: 209.518231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:14.807528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332904878015780,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T09:47:15.009393Z","caller":"traceutil/trace.go:171","msg":"trace[1248147927] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"702.568913ms","start":"2026-05-27T09:47:14.306791Z","end":"2026-05-27T09:47:15.00936Z","steps":["trace[1248147927] 'read index received' (duration: 614.629621ms)","trace[1248147927] 'applied index is now lower than readState.Index' (duration: 87.938172ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:15.009664Z","caller":"traceutil/trace.go:171","msg":"trace[897268125] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"1.00658868s","start":"2026-05-27T09:47:14.00306Z","end":"2026-05-27T09:47:15.009648Z","steps":["trace[897268125] 'process raft request' (duration: 918.433152ms)","trace[897268125] 'compare' (duration: 87.726737ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:47:15.009809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:14.003037Z","time spent":"1.006672753s","remote":"127.0.0.1:55432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T09:47:15.009836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.590487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:47:15.010246Z","caller":"traceutil/trace.go:171","msg":"trace[1515223353] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:608; }","duration":"286.06619ms","start":"2026-05-27T09:47:14.724145Z","end":"2026-05-27T09:47:15.010211Z","steps":["trace[1515223353] 'agreement among raft nodes before linearized reading' (duration: 285.485045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:15.010747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.936278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lkkwd\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-27T09:47:15.010971Z","caller":"traceutil/trace.go:171","msg":"trace[515162094] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lkkwd; range_end:; response_count:1; response_revision:608; }","duration":"704.195425ms","start":"2026-05-27T09:47:14.306733Z","end":"2026-05-27T09:47:15.010929Z","steps":["trace[515162094] 'agreement among raft nodes before linearized reading' (duration: 703.089086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:15.011084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:14.306699Z","time spent":"704.369609ms","remote":"127.0.0.1:55254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-lkkwd\" "} {"level":"warn","ts":"2026-05-27T09:47:16.641764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033404566s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:47:16.641877Z","caller":"traceutil/trace.go:171","msg":"trace[1552652065] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:632; }","duration":"1.389339781s","start":"2026-05-27T09:47:15.252518Z","end":"2026-05-27T09:47:16.641858Z","steps":["trace[1552652065] 'read index received' (duration: 355.7463ms)","trace[1552652065] 'applied index is now lower than readState.Index' (duration: 1.033591881s)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:16.641961Z","caller":"traceutil/trace.go:171","msg":"trace[850357612] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"1.616356322s","start":"2026-05-27T09:47:15.02559Z","end":"2026-05-27T09:47:16.641946Z","steps":["trace[850357612] 'process raft request' (duration: 582.660559ms)","trace[850357612] 'compare' (duration: 1.033261002s)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:47:16.642036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:15.025562Z","time spent":"1.616432834s","remote":"127.0.0.1:55254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:47:16.642158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.389646699s","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-27T09:47:16.642182Z","caller":"traceutil/trace.go:171","msg":"trace[1160534964] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"1.389713621s","start":"2026-05-27T09:47:15.25246Z","end":"2026-05-27T09:47:16.642173Z","steps":["trace[1160534964] 'agreement among raft nodes before linearized reading' (duration: 1.389605558s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:16.642198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:15.252437Z","time spent":"1.389757831s","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T09:47:17.142459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332904878015788,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T09:47:17.643199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332904878015788,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T09:47:18.143353Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332904878015788,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T09:47:18.64385Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332904878015788,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T09:47:18.72205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000237675s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-27T09:47:18.722203Z","caller":"traceutil/trace.go:171","msg":"trace[93416754] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000477861s","start":"2026-05-27T09:47:16.721698Z","end":"2026-05-27T09:47:18.722176Z","steps":["trace[93416754] 'agreement among raft nodes before linearized reading' (duration: 2.000265025s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:18.722269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:16.72168Z","time spent":"2.000576773s","remote":"127.0.0.1:55090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/27 09:47:18 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-27T09:47:19.144756Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332904878015788,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T09:47:19.32083Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.711245119s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-27T09:47:20.261003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"939.505179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T09:47:20.261137Z","caller":"traceutil/trace.go:171","msg":"trace[1949740985] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"4.828353732s","start":"2026-05-27T09:47:15.432758Z","end":"2026-05-27T09:47:20.261112Z","steps":["trace[1949740985] 'process raft request' (duration: 3.888645608s)","trace[1949740985] 'compare' (duration: 939.100099ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:47:20.261228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:15.432733Z","time spent":"4.828449405s","remote":"127.0.0.1:55172","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-27T09:47:20.356911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999717685s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-27T09:47:20.357Z","caller":"traceutil/trace.go:171","msg":"trace[514686922] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999847858s","start":"2026-05-27T09:47:15.357121Z","end":"2026-05-27T09:47:20.356969Z","steps":["trace[514686922] 'agreement among raft nodes before linearized reading' (duration: 4.999733686s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:20.357059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:15.357106Z","time spent":"4.999940811s","remote":"127.0.0.1:55354","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/27 09:47:20 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-27T09:47:20.425531Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.104449236s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T09:47:20.480768Z","caller":"traceutil/trace.go:171","msg":"trace[1338420142] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"3.833895208s","start":"2026-05-27T09:47:16.646849Z","end":"2026-05-27T09:47:20.480744Z","steps":["trace[1338420142] 'process raft request' (duration: 3.778893583s)","trace[1338420142] 'compare' (duration: 54.832181ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:47:20.48091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:16.64682Z","time spent":"3.83401992s","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:47:20.48098Z","caller":"traceutil/trace.go:171","msg":"trace[2030176906] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"3.151655334s","start":"2026-05-27T09:47:17.329282Z","end":"2026-05-27T09:47:20.480937Z","steps":["trace[2030176906] 'process raft request' (duration: 3.151419888s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:20.481137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:17.32925Z","time spent":"3.151808978s","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:47:20.503134Z","caller":"traceutil/trace.go:171","msg":"trace[676167490] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:633; }","duration":"3.86117999s","start":"2026-05-27T09:47:16.641909Z","end":"2026-05-27T09:47:20.503089Z","steps":["trace[676167490] 'read index received' (duration: 2.679176358s)","trace[676167490] 'applied index is now lower than readState.Index' (duration: 1.181995942s)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:20.503239Z","caller":"traceutil/trace.go:171","msg":"trace[1191502907] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"185.474036ms","start":"2026-05-27T09:47:20.317741Z","end":"2026-05-27T09:47:20.503215Z","steps":["trace[1191502907] 'process raft request' (duration: 185.127158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:20.503416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.234762322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T09:47:20.503509Z","caller":"traceutil/trace.go:171","msg":"trace[32681851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:613; }","duration":"5.234925187s","start":"2026-05-27T09:47:15.268566Z","end":"2026-05-27T09:47:20.503492Z","steps":["trace[32681851] 'agreement among raft nodes before linearized reading' (duration: 5.234532237s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:20.503571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.780895203s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:47:20.503622Z","caller":"traceutil/trace.go:171","msg":"trace[515738404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:613; }","duration":"1.780982955s","start":"2026-05-27T09:47:18.722625Z","end":"2026-05-27T09:47:20.503608Z","steps":["trace[515738404] 'agreement among raft nodes before linearized reading' (duration: 1.780923523s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:20.503659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:18.722596Z","time spent":"1.781055837s","remote":"127.0.0.1:55096","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-27T09:47:20.503565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:15.268537Z","time spent":"5.23501583s","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-27T09:47:20.503509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.235067341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-27T09:47:20.504002Z","caller":"traceutil/trace.go:171","msg":"trace[1743022524] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:613; }","duration":"5.235625885s","start":"2026-05-27T09:47:15.268362Z","end":"2026-05-27T09:47:20.503988Z","steps":["trace[1743022524] 'agreement among raft nodes before linearized reading' (duration: 5.234941927s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:20.504046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:15.268348Z","time spent":"5.235687887s","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-27T09:47:22.682938Z","caller":"traceutil/trace.go:171","msg":"trace[1611468660] linearizableReadLoop","detail":"{readStateIndex:681; appliedIndex:680; }","duration":"169.141675ms","start":"2026-05-27T09:47:22.513774Z","end":"2026-05-27T09:47:22.682915Z","steps":["trace[1611468660] 'read index received' (duration: 168.976261ms)","trace[1611468660] 'applied index is now lower than readState.Index' (duration: 163.964µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:22.682999Z","caller":"traceutil/trace.go:171","msg":"trace[719935203] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"194.921605ms","start":"2026-05-27T09:47:22.488029Z","end":"2026-05-27T09:47:22.682951Z","steps":["trace[719935203] 'process raft request' (duration: 194.591207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:22.683211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.422133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-27T09:47:22.683348Z","caller":"traceutil/trace.go:171","msg":"trace[1971975441] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"169.607207ms","start":"2026-05-27T09:47:22.513716Z","end":"2026-05-27T09:47:22.683323Z","steps":["trace[1971975441] 'agreement among raft nodes before linearized reading' (duration: 169.335861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:22.685599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.083892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T09:47:22.685664Z","caller":"traceutil/trace.go:171","msg":"trace[2138258420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:657; }","duration":"164.188003ms","start":"2026-05-27T09:47:22.521461Z","end":"2026-05-27T09:47:22.685649Z","steps":["trace[2138258420] 'agreement among raft nodes before linearized reading' (duration: 163.979178ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:25.799093Z","caller":"traceutil/trace.go:171","msg":"trace[1649050882] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"147.720318ms","start":"2026-05-27T09:47:25.651339Z","end":"2026-05-27T09:47:25.79906Z","steps":["trace[1649050882] 'process raft request' (duration: 147.456592ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:33.912465Z","caller":"traceutil/trace.go:171","msg":"trace[415863126] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:793; }","duration":"188.226478ms","start":"2026-05-27T09:47:33.724218Z","end":"2026-05-27T09:47:33.912445Z","steps":["trace[415863126] 'read index received' (duration: 188.076555ms)","trace[415863126] 'applied index is now lower than readState.Index' (duration: 149.273µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:33.912516Z","caller":"traceutil/trace.go:171","msg":"trace[1611169287] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"446.285998ms","start":"2026-05-27T09:47:33.466196Z","end":"2026-05-27T09:47:33.912482Z","steps":["trace[1611169287] 'process raft request' (duration: 446.136844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:33.912584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.363601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:47:33.912605Z","caller":"traceutil/trace.go:171","msg":"trace[18056617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:764; }","duration":"188.395361ms","start":"2026-05-27T09:47:33.724204Z","end":"2026-05-27T09:47:33.912599Z","steps":["trace[18056617] 'agreement among raft nodes before linearized reading' (duration: 188.32899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:33.912786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:33.466171Z","time spent":"446.39012ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:47:36.929686Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332904878016196,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T09:47:37.430689Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332904878016196,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T09:47:37.931872Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332904878016196,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T09:47:38.433057Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332904878016196,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T09:47:38.558193Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.135038593s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T09:47:38.559265Z","caller":"traceutil/trace.go:171","msg":"trace[1922285513] linearizableReadLoop","detail":"{readStateIndex:809; appliedIndex:808; }","duration":"2.130197526s","start":"2026-05-27T09:47:36.429044Z","end":"2026-05-27T09:47:38.559241Z","steps":["trace[1922285513] 'read index received' (duration: 2.129383159s)","trace[1922285513] 'applied index is now lower than readState.Index' (duration: 813.407µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:47:38.559437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.13035849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T09:47:38.559524Z","caller":"traceutil/trace.go:171","msg":"trace[1487761715] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:779; }","duration":"2.130498053s","start":"2026-05-27T09:47:36.429002Z","end":"2026-05-27T09:47:38.559501Z","steps":["trace[1487761715] 'agreement among raft nodes before linearized reading' (duration: 2.130339559s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:38.559588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:36.428982Z","time spent":"2.130586995s","remote":"127.0.0.1:55472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-05-27T09:47:38.559385Z","caller":"traceutil/trace.go:171","msg":"trace[2139991423] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"2.136223759s","start":"2026-05-27T09:47:36.423072Z","end":"2026-05-27T09:47:38.559296Z","steps":["trace[2139991423] 'process raft request' (duration: 2.135510074s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:38.560215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:36.42306Z","time spent":"2.136773861s","remote":"127.0.0.1:55254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:47:38.564441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.125832189s","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-27T09:47:38.564478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.818704669s","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-27T09:47:38.564522Z","caller":"traceutil/trace.go:171","msg":"trace[1556508784] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:779; }","duration":"1.81875114s","start":"2026-05-27T09:47:36.745759Z","end":"2026-05-27T09:47:38.56451Z","steps":["trace[1556508784] 'agreement among raft nodes before linearized reading' (duration: 1.818616967s)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:38.564526Z","caller":"traceutil/trace.go:171","msg":"trace[96879756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:779; }","duration":"2.125944081s","start":"2026-05-27T09:47:36.438561Z","end":"2026-05-27T09:47:38.564505Z","steps":["trace[96879756] 'agreement among raft nodes before linearized reading' (duration: 2.125726756s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:38.564442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.814728501s","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-27T09:47:38.564573Z","caller":"traceutil/trace.go:171","msg":"trace[1427702621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:779; }","duration":"1.814897435s","start":"2026-05-27T09:47:36.749668Z","end":"2026-05-27T09:47:38.564565Z","steps":["trace[1427702621] 'agreement among raft nodes before linearized reading' (duration: 1.814645669s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:38.564573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.638806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-27T09:47:38.564523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.828598967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-27T09:47:38.564545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:36.745722Z","time spent":"1.818817991s","remote":"127.0.0.1:55354","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-27T09:47:38.564621Z","caller":"traceutil/trace.go:171","msg":"trace[271065398] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:779; }","duration":"466.710778ms","start":"2026-05-27T09:47:38.097896Z","end":"2026-05-27T09:47:38.564607Z","steps":["trace[271065398] 'agreement among raft nodes before linearized reading' (duration: 466.587965ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:38.564802Z","caller":"traceutil/trace.go:171","msg":"trace[328141217] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:779; }","duration":"1.828828672s","start":"2026-05-27T09:47:36.735887Z","end":"2026-05-27T09:47:38.564716Z","steps":["trace[328141217] 'agreement among raft nodes before linearized reading' (duration: 1.828475474s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:38.564881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:38.097883Z","time spent":"466.986093ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-27T09:47:38.564584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:36.438549Z","time spent":"2.126022602s","remote":"127.0.0.1:55354","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-27T09:47:38.564657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.842711497s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:47:38.56507Z","caller":"traceutil/trace.go:171","msg":"trace[88248579] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:779; }","duration":"1.843160287s","start":"2026-05-27T09:47:36.721894Z","end":"2026-05-27T09:47:38.565054Z","steps":["trace[88248579] 'agreement among raft nodes before linearized reading' (duration: 1.842724348s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:38.564669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:36.749659Z","time spent":"1.814937975s","remote":"127.0.0.1:55354","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-27T09:47:38.565125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:36.721873Z","time spent":"1.843238569s","remote":"127.0.0.1:55090","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-27T09:47:38.56491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:47:36.735877Z","time spent":"1.829021546s","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T09:47:49.724023Z","caller":"traceutil/trace.go:171","msg":"trace[1496341625] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"144.79705ms","start":"2026-05-27T09:47:49.579208Z","end":"2026-05-27T09:47:49.724005Z","steps":["trace[1496341625] 'process raft request' (duration: 144.672348ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:50.09997Z","caller":"traceutil/trace.go:171","msg":"trace[1528954403] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"140.451431ms","start":"2026-05-27T09:47:49.959485Z","end":"2026-05-27T09:47:50.099937Z","steps":["trace[1528954403] 'process raft request' (duration: 61.971852ms)","trace[1528954403] 'compare' (duration: 78.241964ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:50.188858Z","caller":"traceutil/trace.go:171","msg":"trace[1801712174] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"227.501541ms","start":"2026-05-27T09:47:49.961324Z","end":"2026-05-27T09:47:50.188826Z","steps":["trace[1801712174] 'process raft request' (duration: 227.45719ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:50.188868Z","caller":"traceutil/trace.go:171","msg":"trace[2011305108] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"229.030422ms","start":"2026-05-27T09:47:49.959818Z","end":"2026-05-27T09:47:50.188848Z","steps":["trace[2011305108] 'process raft request' (duration: 228.867059ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:50.351192Z","caller":"traceutil/trace.go:171","msg":"trace[1683459017] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"149.15062ms","start":"2026-05-27T09:47:50.202021Z","end":"2026-05-27T09:47:50.351171Z","steps":["trace[1683459017] 'process raft request' (duration: 148.743442ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:50.35111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.418686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:47:50.351449Z","caller":"traceutil/trace.go:171","msg":"trace[20767036] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:904; }","duration":"151.840024ms","start":"2026-05-27T09:47:50.199599Z","end":"2026-05-27T09:47:50.351439Z","steps":["trace[20767036] 'count revisions from in-memory index tree' (duration: 151.340535ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:50.554756Z","caller":"traceutil/trace.go:171","msg":"trace[483308120] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"126.374792ms","start":"2026-05-27T09:47:50.428263Z","end":"2026-05-27T09:47:50.554637Z","steps":["trace[483308120] 'process raft request' (duration: 44.227069ms)","trace[483308120] 'compare' (duration: 82.01407ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:50.767266Z","caller":"traceutil/trace.go:171","msg":"trace[1330025004] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:941; }","duration":"138.051507ms","start":"2026-05-27T09:47:50.629185Z","end":"2026-05-27T09:47:50.767237Z","steps":["trace[1330025004] 'read index received' (duration: 130.405653ms)","trace[1330025004] 'applied index is now lower than readState.Index' (duration: 7.644954ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:47:50.767356Z","caller":"traceutil/trace.go:171","msg":"trace[826746305] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"206.78818ms","start":"2026-05-27T09:47:50.560537Z","end":"2026-05-27T09:47:50.767325Z","steps":["trace[826746305] 'process raft request' (duration: 199.006743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:50.767563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.273592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-27T09:47:50.767617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.459195ms","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-27T09:47:50.767652Z","caller":"traceutil/trace.go:171","msg":"trace[60948846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:909; }","duration":"138.441685ms","start":"2026-05-27T09:47:50.629192Z","end":"2026-05-27T09:47:50.767634Z","steps":["trace[60948846] 'agreement among raft nodes before linearized reading' (duration: 138.19992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:50.76775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.883863ms","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-27T09:47:50.767863Z","caller":"traceutil/trace.go:171","msg":"trace[1721198638] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:909; }","duration":"128.010145ms","start":"2026-05-27T09:47:50.63983Z","end":"2026-05-27T09:47:50.76784Z","steps":["trace[1721198638] 'agreement among raft nodes before linearized reading' (duration: 127.668348ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:50.767953Z","caller":"traceutil/trace.go:171","msg":"trace[1585735131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:909; }","duration":"138.861934ms","start":"2026-05-27T09:47:50.629068Z","end":"2026-05-27T09:47:50.76793Z","steps":["trace[1585735131] 'agreement among raft nodes before linearized reading' (duration: 138.299903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:50.767973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.73954ms","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-27T09:47:50.768028Z","caller":"traceutil/trace.go:171","msg":"trace[1746395858] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:909; }","duration":"132.816361ms","start":"2026-05-27T09:47:50.635197Z","end":"2026-05-27T09:47:50.768013Z","steps":["trace[1746395858] 'agreement among raft nodes before linearized reading' (duration: 132.640507ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:47:50.768349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.804831ms","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-27T09:47:50.768595Z","caller":"traceutil/trace.go:171","msg":"trace[738642149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:909; }","duration":"128.959784ms","start":"2026-05-27T09:47:50.639477Z","end":"2026-05-27T09:47:50.768437Z","steps":["trace[738642149] 'agreement among raft nodes before linearized reading' (duration: 128.301781ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:47:53.87376Z","caller":"traceutil/trace.go:171","msg":"trace[1526850843] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"139.750321ms","start":"2026-05-27T09:47:53.733986Z","end":"2026-05-27T09:47:53.873736Z","steps":["trace[1526850843] 'process raft request' (duration: 139.613938ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:48:00.93352Z","caller":"traceutil/trace.go:171","msg":"trace[1912398989] linearizableReadLoop","detail":"{readStateIndex:1017; appliedIndex:1015; }","duration":"100.64348ms","start":"2026-05-27T09:48:00.832849Z","end":"2026-05-27T09:48:00.933493Z","steps":["trace[1912398989] 'read index received' (duration: 65.35656ms)","trace[1912398989] 'applied index is now lower than readState.Index' (duration: 35.28535ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:48:00.933646Z","caller":"traceutil/trace.go:171","msg":"trace[1488856133] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"101.769602ms","start":"2026-05-27T09:48:00.831866Z","end":"2026-05-27T09:48:00.933636Z","steps":["trace[1488856133] 'process raft request' (duration: 101.395495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:48:00.933773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.904315ms","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-27T09:48:00.933846Z","caller":"traceutil/trace.go:171","msg":"trace[678670591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:983; }","duration":"101.013587ms","start":"2026-05-27T09:48:00.832814Z","end":"2026-05-27T09:48:00.933827Z","steps":["trace[678670591] 'agreement among raft nodes before linearized reading' (duration: 100.833783ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:48:01.091317Z","caller":"traceutil/trace.go:171","msg":"trace[2000704680] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"154.286048ms","start":"2026-05-27T09:48:00.937006Z","end":"2026-05-27T09:48:01.091292Z","steps":["trace[2000704680] 'process raft request' (duration: 148.893686ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:48:01.123985Z","caller":"traceutil/trace.go:171","msg":"trace[316270059] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"186.829123ms","start":"2026-05-27T09:48:00.937129Z","end":"2026-05-27T09:48:01.123959Z","steps":["trace[316270059] 'process raft request' (duration: 186.694131ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:48:03.193192Z","caller":"traceutil/trace.go:171","msg":"trace[1090816141] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"251.822457ms","start":"2026-05-27T09:48:02.941343Z","end":"2026-05-27T09:48:03.193165Z","steps":["trace[1090816141] 'process raft request' (duration: 172.13732ms)","trace[1090816141] 'compare' (duration: 79.508924ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:48:04.146153Z","caller":"traceutil/trace.go:171","msg":"trace[802481178] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"236.790567ms","start":"2026-05-27T09:48:03.909345Z","end":"2026-05-27T09:48:04.146136Z","steps":["trace[802481178] 'process raft request' (duration: 236.687874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:48:05.45617Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":824332904878016604,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T09:48:05.483183Z","caller":"traceutil/trace.go:171","msg":"trace[1010413156] linearizableReadLoop","detail":"{readStateIndex:1032; appliedIndex:1031; }","duration":"527.502377ms","start":"2026-05-27T09:48:04.955665Z","end":"2026-05-27T09:48:05.483167Z","steps":["trace[1010413156] 'read index received' (duration: 527.364255ms)","trace[1010413156] 'applied index is now lower than readState.Index' (duration: 137.552µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:48:05.483349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.674921ms","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-27T09:48:05.483372Z","caller":"traceutil/trace.go:171","msg":"trace[152216245] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:996; }","duration":"527.739883ms","start":"2026-05-27T09:48:04.955626Z","end":"2026-05-27T09:48:05.483366Z","steps":["trace[152216245] 'agreement among raft nodes before linearized reading' (duration: 527.617801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:48:05.483392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:48:04.955613Z","time spent":"527.775303ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-27T09:48:05.483336Z","caller":"traceutil/trace.go:171","msg":"trace[691451767] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"568.137143ms","start":"2026-05-27T09:48:04.915128Z","end":"2026-05-27T09:48:05.483265Z","steps":["trace[691451767] 'process raft request' (duration: 567.943729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:48:05.483684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.732477ms","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-27T09:48:05.483701Z","caller":"traceutil/trace.go:171","msg":"trace[959471399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:996; }","duration":"285.778308ms","start":"2026-05-27T09:48:05.197919Z","end":"2026-05-27T09:48:05.483698Z","steps":["trace[959471399] 'agreement among raft nodes before linearized reading' (duration: 285.735997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:48:05.483762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.344417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-27T09:48:05.483748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:48:04.915109Z","time spent":"568.54164ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T09:48:05.483806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.465519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-27T09:48:05.483819Z","caller":"traceutil/trace.go:171","msg":"trace[66848892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:996; }","duration":"202.51372ms","start":"2026-05-27T09:48:05.281303Z","end":"2026-05-27T09:48:05.483816Z","steps":["trace[66848892] 'agreement among raft nodes before linearized reading' (duration: 202.479549ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:48:05.483774Z","caller":"traceutil/trace.go:171","msg":"trace[1713111005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:996; }","duration":"202.403178ms","start":"2026-05-27T09:48:05.281368Z","end":"2026-05-27T09:48:05.483771Z","steps":["trace[1713111005] 'agreement among raft nodes before linearized reading' (duration: 202.368878ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:48:05.609635Z","caller":"traceutil/trace.go:171","msg":"trace[1491233492] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"120.999374ms","start":"2026-05-27T09:48:05.488597Z","end":"2026-05-27T09:48:05.609596Z","steps":["trace[1491233492] 'process raft request' (duration: 22.721279ms)","trace[1491233492] 'compare' (duration: 97.885878ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:48:05.609667Z","caller":"traceutil/trace.go:171","msg":"trace[2100167850] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"120.997345ms","start":"2026-05-27T09:48:05.488645Z","end":"2026-05-27T09:48:05.609642Z","steps":["trace[2100167850] 'process raft request' (duration: 120.853152ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:48:05.609665Z","caller":"traceutil/trace.go:171","msg":"trace[728680312] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"119.314534ms","start":"2026-05-27T09:48:05.490327Z","end":"2026-05-27T09:48:05.609642Z","steps":["trace[728680312] 'process raft request' (duration: 119.266893ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:48:06.457036Z","caller":"traceutil/trace.go:171","msg":"trace[1437832555] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"305.306329ms","start":"2026-05-27T09:48:06.151706Z","end":"2026-05-27T09:48:06.457012Z","steps":["trace[1437832555] 'process raft request' (duration: 305.155646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:48:06.457185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:48:06.151682Z","time spent":"305.427001ms","remote":"127.0.0.1:55354","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T09:48:13.643016Z","caller":"traceutil/trace.go:171","msg":"trace[980932938] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"102.57132ms","start":"2026-05-27T09:48:13.540422Z","end":"2026-05-27T09:48:13.642994Z","steps":["trace[980932938] 'process raft request' (duration: 99.22465ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:48:19.045264Z","caller":"traceutil/trace.go:171","msg":"trace[1040955669] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"457.434513ms","start":"2026-05-27T09:48:18.587806Z","end":"2026-05-27T09:48:19.04524Z","steps":["trace[1040955669] 'process raft request' (duration: 457.28423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:48:19.045425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:48:18.587788Z","time spent":"457.545335ms","remote":"127.0.0.1:55354","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-27T09:48:19.153038Z","caller":"traceutil/trace.go:171","msg":"trace[1563171839] linearizableReadLoop","detail":"{readStateIndex:1083; appliedIndex:1081; }","duration":"431.279911ms","start":"2026-05-27T09:48:18.721733Z","end":"2026-05-27T09:48:19.153013Z","steps":["trace[1563171839] 'read index received' (duration: 323.39096ms)","trace[1563171839] 'applied index is now lower than readState.Index' (duration: 107.887521ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:48:19.15316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.412394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T09:48:19.153193Z","caller":"traceutil/trace.go:171","msg":"trace[132086799] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"431.496065ms","start":"2026-05-27T09:48:18.721687Z","end":"2026-05-27T09:48:19.153183Z","steps":["trace[132086799] 'agreement among raft nodes before linearized reading' (duration: 431.416554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:48:19.153235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:48:18.721668Z","time spent":"431.558916ms","remote":"127.0.0.1:55096","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-27T09:48:42.004007Z","caller":"traceutil/trace.go:171","msg":"trace[2120856845] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"124.974215ms","start":"2026-05-27T09:48:41.879Z","end":"2026-05-27T09:48:42.003974Z","steps":["trace[2120856845] 'process raft request' (duration: 63.630706ms)","trace[2120856845] 'compare' (duration: 61.163716ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:48:42.219334Z","caller":"traceutil/trace.go:171","msg":"trace[976852332] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1162; }","duration":"135.535295ms","start":"2026-05-27T09:48:42.083774Z","end":"2026-05-27T09:48:42.219309Z","steps":["trace[976852332] 'read index received' (duration: 89.695142ms)","trace[976852332] 'applied index is now lower than readState.Index' (duration: 45.838673ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:48:42.219473Z","caller":"traceutil/trace.go:171","msg":"trace[28885233] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"162.373567ms","start":"2026-05-27T09:48:42.057071Z","end":"2026-05-27T09:48:42.219445Z","steps":["trace[28885233] 'process raft request' (duration: 116.381112ms)","trace[28885233] 'compare' (duration: 45.644288ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:48:42.219643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.811859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-27T09:48:42.220855Z","caller":"traceutil/trace.go:171","msg":"trace[1764963691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1120; }","duration":"165.057959ms","start":"2026-05-27T09:48:42.055782Z","end":"2026-05-27T09:48:42.22084Z","steps":["trace[1764963691] 'agreement among raft nodes before linearized reading' (duration: 163.750909ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:48:42.22042Z","caller":"traceutil/trace.go:171","msg":"trace[361291549] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"132.200642ms","start":"2026-05-27T09:48:42.08816Z","end":"2026-05-27T09:48:42.220361Z","steps":["trace[361291549] 'process raft request' (duration: 131.931568ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:48:50.175348Z","caller":"traceutil/trace.go:171","msg":"trace[1819615818] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"135.045452ms","start":"2026-05-27T09:48:50.040274Z","end":"2026-05-27T09:48:50.17532Z","steps":["trace[1819615818] 'process raft request' (duration: 134.826688ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:49:08.627331Z","caller":"traceutil/trace.go:171","msg":"trace[1628016449] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"136.968378ms","start":"2026-05-27T09:49:08.49032Z","end":"2026-05-27T09:49:08.627288Z","steps":["trace[1628016449] 'process raft request' (duration: 135.864022ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:49:12.735872Z","caller":"traceutil/trace.go:171","msg":"trace[1122111967] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"187.983106ms","start":"2026-05-27T09:49:12.547868Z","end":"2026-05-27T09:49:12.735851Z","steps":["trace[1122111967] 'process raft request' (duration: 187.118513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:49:26.942321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.375711ms","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-27T09:49:26.942502Z","caller":"traceutil/trace.go:171","msg":"trace[44026519] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1298; }","duration":"111.598904ms","start":"2026-05-27T09:49:26.830876Z","end":"2026-05-27T09:49:26.942475Z","steps":["trace[44026519] 'range keys from in-memory index tree' (duration: 111.177509ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:49:27.118685Z","caller":"traceutil/trace.go:171","msg":"trace[534143291] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"172.210691ms","start":"2026-05-27T09:49:26.946441Z","end":"2026-05-27T09:49:27.118652Z","steps":["trace[534143291] 'process raft request' (duration: 172.019788ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:49:34.796276Z","caller":"traceutil/trace.go:171","msg":"trace[33831553] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1384; }","duration":"159.118819ms","start":"2026-05-27T09:49:34.637133Z","end":"2026-05-27T09:49:34.796252Z","steps":["trace[33831553] 'read index received' (duration: 151.146008ms)","trace[33831553] 'applied index is now lower than readState.Index' (duration: 7.971621ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T09:49:34.796331Z","caller":"traceutil/trace.go:171","msg":"trace[1769027950] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"188.736777ms","start":"2026-05-27T09:49:34.607548Z","end":"2026-05-27T09:49:34.796285Z","steps":["trace[1769027950] 'process raft request' (duration: 180.714846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T09:49:34.796594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.445223ms","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-27T09:49:34.797055Z","caller":"traceutil/trace.go:171","msg":"trace[2047749286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1330; }","duration":"159.94814ms","start":"2026-05-27T09:49:34.637052Z","end":"2026-05-27T09:49:34.797Z","steps":["trace[2047749286] 'agreement among raft nodes before linearized reading' (duration: 159.368492ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:49:35.325825Z","caller":"traceutil/trace.go:171","msg":"trace[1470668700] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"169.041211ms","start":"2026-05-27T09:49:35.156756Z","end":"2026-05-27T09:49:35.325797Z","steps":["trace[1470668700] 'process raft request' (duration: 168.865439ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:49:36.092957Z","caller":"traceutil/trace.go:171","msg":"trace[585907840] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"642.42572ms","start":"2026-05-27T09:49:35.450497Z","end":"2026-05-27T09:49:36.092923Z","steps":["trace[585907840] 'process raft request' (duration: 590.92172ms)","trace[585907840] 'compare' (duration: 51.347578ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T09:49:36.093137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T09:49:35.45047Z","time spent":"642.576413ms","remote":"127.0.0.1:55354","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-27T09:49:36.695233Z","caller":"traceutil/trace.go:171","msg":"trace[792905232] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1336; }","duration":"118.541943ms","start":"2026-05-27T09:49:36.57666Z","end":"2026-05-27T09:49:36.695202Z","steps":["trace[792905232] 'process raft request' (duration: 107.976687ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T09:49:39.102944Z","caller":"traceutil/trace.go:171","msg":"trace[658318275] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"152.658963ms","start":"2026-05-27T09:49:38.950265Z","end":"2026-05-27T09:49:39.102924Z","steps":["trace[658318275] 'process raft request' (duration: 129.773139ms)","trace[658318275] 'compare' (duration: 22.732992ms)"],"step_count":2}