{"level":"warn","ts":"2026-03-25T07:34:06.466981Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T07:34:06.467059Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-25T07:34:06.467123Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T07:34:06.467134Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-03-25T07:34:06.467427Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-25T07:34:06.470237Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-03-25T07:34:06.470415Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-25T07:34:06.496366Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"25.635005ms"} {"level":"info","ts":"2026-03-25T07:34:06.522614Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-03-25T07:34:06.522752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T07:34:06.522827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-03-25T07:34:06.522859Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T07:34:06.522889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-03-25T07:34:06.52294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-03-25T07:34:06.549431Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T07:34:06.555928Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T07:34:06.563082Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-25T07:34:06.57306Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T07:34:06.573779Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T07:34:06.573802Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-25T07:34:06.573954Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-25T07:34:06.573993Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-25T07:34:06.576874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-03-25T07:34:06.5773Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-03-25T07:34:06.578677Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-25T07:34:06.578849Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-03-25T07:34:06.57896Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-03-25T07:34:06.579486Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T07:34:06.579527Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T07:34:07.027653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T07:34:07.027695Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T07:34:07.027717Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-03-25T07:34:07.027728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-03-25T07:34:07.027734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-03-25T07:34:07.027743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-03-25T07:34:07.027751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-03-25T07:34:07.029393Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T07:34:07.02942Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T07:34:07.0297Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T07:34:07.029772Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T07:34:07.031928Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-25T07:34:07.032103Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T07:34:07.03224Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T07:34:07.032283Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T07:34:07.033481Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-03-25T07:34:07.034645Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T07:34:07.034662Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-03-25T07:34:25.351319Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009632916,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:34:25.83356Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.064681643s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T07:34:25.834189Z","caller":"traceutil/trace.go:171","msg":"trace[1032067643] linearizableReadLoop","detail":"{readStateIndex:412; appliedIndex:411; }","duration":"982.914639ms","start":"2026-03-25T07:34:24.851237Z","end":"2026-03-25T07:34:25.834152Z","steps":["trace[1032067643] 'read index received' (duration: 982.636306ms)","trace[1032067643] 'applied index is now lower than readState.Index' (duration: 276.523µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:25.83441Z","caller":"traceutil/trace.go:171","msg":"trace[292972209] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"1.065571623s","start":"2026-03-25T07:34:24.768816Z","end":"2026-03-25T07:34:25.834387Z","steps":["trace[292972209] 'process raft request' (duration: 1.065071638s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:25.834608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"983.302623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-25T07:34:25.834717Z","caller":"traceutil/trace.go:171","msg":"trace[1372027306] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:399; }","duration":"983.495326ms","start":"2026-03-25T07:34:24.851198Z","end":"2026-03-25T07:34:25.834693Z","steps":["trace[1372027306] 'agreement among raft nodes before linearized reading' (duration: 983.275733ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:25.834762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:24.851181Z","time spent":"983.571476ms","remote":"127.0.0.1:55216","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-03-25T07:34:25.835208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:24.768804Z","time spent":"1.065650254s","remote":"127.0.0.1:55216","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-03-25T07:34:27.395417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.452946324s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-25T07:34:27.39578Z","caller":"traceutil/trace.go:171","msg":"trace[1425110148] linearizableReadLoop","detail":"{readStateIndex:413; appliedIndex:412; }","duration":"1.561425787s","start":"2026-03-25T07:34:25.834299Z","end":"2026-03-25T07:34:27.395724Z","steps":["trace[1425110148] 'read index received' (duration: 108.295612ms)","trace[1425110148] 'applied index is now lower than readState.Index' (duration: 1.453124125s)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:27.395895Z","caller":"traceutil/trace.go:171","msg":"trace[1447763991] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"1.557359152s","start":"2026-03-25T07:34:25.838508Z","end":"2026-03-25T07:34:27.395867Z","steps":["trace[1447763991] 'process raft request' (duration: 1.557169979s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:27.39589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.68134244s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:34:27.396007Z","caller":"traceutil/trace.go:171","msg":"trace[729679583] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:399; }","duration":"1.681558593s","start":"2026-03-25T07:34:25.714415Z","end":"2026-03-25T07:34:27.395973Z","steps":["trace[729679583] 'agreement among raft nodes before linearized reading' (duration: 1.68134127s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:27.395984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.122212892s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:34:27.396067Z","caller":"traceutil/trace.go:171","msg":"trace[695347994] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:400; }","duration":"2.122316123s","start":"2026-03-25T07:34:25.27374Z","end":"2026-03-25T07:34:27.396056Z","steps":["trace[695347994] 'agreement among raft nodes before linearized reading' (duration: 2.122164231s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:27.396067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:25.714388Z","time spent":"1.681667324s","remote":"127.0.0.1:54974","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-03-25T07:34:27.39606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:25.838476Z","time spent":"1.557484153s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:34:27.396082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.355364935s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-25T07:34:27.396175Z","caller":"traceutil/trace.go:171","msg":"trace[366070368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:400; }","duration":"1.355508096s","start":"2026-03-25T07:34:26.040642Z","end":"2026-03-25T07:34:27.39615Z","steps":["trace[366070368] 'agreement among raft nodes before linearized reading' (duration: 1.355334344s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:27.39623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:26.040619Z","time spent":"1.355599097s","remote":"127.0.0.1:55216","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-03-25T07:34:27.39629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.359573458s","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-03-25T07:34:27.396328Z","caller":"traceutil/trace.go:171","msg":"trace[1394305064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:400; }","duration":"2.35964562s","start":"2026-03-25T07:34:25.03667Z","end":"2026-03-25T07:34:27.396316Z","steps":["trace[1394305064] 'agreement among raft nodes before linearized reading' (duration: 2.359576489s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:27.39635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:25.036639Z","time spent":"2.35970577s","remote":"127.0.0.1:55216","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-03-25T07:34:32.091264Z","caller":"traceutil/trace.go:171","msg":"trace[873402952] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"232.554241ms","start":"2026-03-25T07:34:31.858681Z","end":"2026-03-25T07:34:32.091236Z","steps":["trace[873402952] 'process raft request' (duration: 232.374639ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:35.937832Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009632976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:34:36.438517Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009632976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:34:36.939107Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009632976,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:34:37.14739Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.714293928s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T07:34:37.148057Z","caller":"traceutil/trace.go:171","msg":"trace[604923127] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"1.715075527s","start":"2026-03-25T07:34:35.432959Z","end":"2026-03-25T07:34:37.148035Z","steps":["trace[604923127] 'process raft request' (duration: 1.714619072s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:37.148216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:35.432934Z","time spent":"1.715189778s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:34:37.15972Z","caller":"traceutil/trace.go:171","msg":"trace[308292346] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:434; }","duration":"1.722561639s","start":"2026-03-25T07:34:35.437136Z","end":"2026-03-25T07:34:37.159698Z","steps":["trace[308292346] 'read index received' (duration: 1.710560108s)","trace[308292346] 'applied index is now lower than readState.Index' (duration: 12.000351ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:37.159873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.722723591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-25T07:34:37.159888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.618701996s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-25T07:34:37.159911Z","caller":"traceutil/trace.go:171","msg":"trace[523641070] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:420; }","duration":"1.722802412s","start":"2026-03-25T07:34:35.437097Z","end":"2026-03-25T07:34:37.1599Z","steps":["trace[523641070] 'agreement among raft nodes before linearized reading' (duration: 1.722713431s)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:37.159927Z","caller":"traceutil/trace.go:171","msg":"trace[503286627] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:420; }","duration":"1.618829037s","start":"2026-03-25T07:34:35.541086Z","end":"2026-03-25T07:34:37.159915Z","steps":["trace[503286627] 'agreement among raft nodes before linearized reading' (duration: 1.618756246s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:37.159933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054425227s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-25T07:34:37.159947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:35.437085Z","time spent":"1.722853572s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-25T07:34:37.159955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:35.541068Z","time spent":"1.618879909s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-25T07:34:37.159975Z","caller":"traceutil/trace.go:171","msg":"trace[1231728328] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:420; }","duration":"1.054507318s","start":"2026-03-25T07:34:36.105457Z","end":"2026-03-25T07:34:37.159964Z","steps":["trace[1231728328] 'agreement among raft nodes before linearized reading' (duration: 1.054431147s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:37.160001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:36.105404Z","time spent":"1.054590529s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T07:34:37.160056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.445870054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:34:37.160125Z","caller":"traceutil/trace.go:171","msg":"trace[1684207933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"1.445972474s","start":"2026-03-25T07:34:35.714143Z","end":"2026-03-25T07:34:37.160116Z","steps":["trace[1684207933] 'agreement among raft nodes before linearized reading' (duration: 1.445894254s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:37.160207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:35.71412Z","time spent":"1.446077776s","remote":"127.0.0.1:54974","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-03-25T07:34:37.426252Z","caller":"traceutil/trace.go:171","msg":"trace[921329731] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"166.968539ms","start":"2026-03-25T07:34:37.259258Z","end":"2026-03-25T07:34:37.426227Z","steps":["trace[921329731] 'process raft request' (duration: 166.594645ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:37.795577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.062571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:34:37.795899Z","caller":"traceutil/trace.go:171","msg":"trace[1043637771] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"339.978265ms","start":"2026-03-25T07:34:37.45587Z","end":"2026-03-25T07:34:37.795848Z","steps":["trace[1043637771] 'process raft request' (duration: 161.54892ms)","trace[1043637771] 'compare' (duration: 177.750578ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:37.795996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:37.455863Z","time spent":"340.095397ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:34:38.137238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.058563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:34:38.13734Z","caller":"traceutil/trace.go:171","msg":"trace[591263202] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"336.377743ms","start":"2026-03-25T07:34:37.800944Z","end":"2026-03-25T07:34:38.137322Z","steps":["trace[591263202] 'process raft request' (duration: 216.163069ms)","trace[591263202] 'compare' (duration: 119.857821ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:38.137392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:37.800924Z","time spent":"336.447924ms","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6516,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:34:38.981336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.516447ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:34:38.981506Z","caller":"traceutil/trace.go:171","msg":"trace[1280497369] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:444; }","duration":"309.066339ms","start":"2026-03-25T07:34:38.672422Z","end":"2026-03-25T07:34:38.981488Z","steps":["trace[1280497369] 'read index received' (duration: 41.69µs)","trace[1280497369] 'applied index is now lower than readState.Index' (duration: 309.022799ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:38.981626Z","caller":"traceutil/trace.go:171","msg":"trace[310127727] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"839.956785ms","start":"2026-03-25T07:34:38.141657Z","end":"2026-03-25T07:34:38.981614Z","steps":["trace[310127727] 'process raft request' (duration: 292.108275ms)","trace[310127727] 'compare' (duration: 547.404465ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:38.981698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:38.141644Z","time spent":"840.034296ms","remote":"127.0.0.1:55294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1639,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:34:38.981919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.491854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:34:38.981977Z","caller":"traceutil/trace.go:171","msg":"trace[2021035896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"309.591785ms","start":"2026-03-25T07:34:38.672375Z","end":"2026-03-25T07:34:38.981967Z","steps":["trace[2021035896] 'agreement among raft nodes before linearized reading' (duration: 309.495084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:38.982084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:38.672354Z","time spent":"309.722276ms","remote":"127.0.0.1:54974","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-03-25T07:34:38.982132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.147816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:34:38.982227Z","caller":"traceutil/trace.go:171","msg":"trace[398691571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:429; }","duration":"261.295988ms","start":"2026-03-25T07:34:38.720908Z","end":"2026-03-25T07:34:38.982204Z","steps":["trace[398691571] 'agreement among raft nodes before linearized reading' (duration: 261.096215ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:39.414937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.193634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:34:39.415027Z","caller":"traceutil/trace.go:171","msg":"trace[299610428] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"154.512055ms","start":"2026-03-25T07:34:39.260499Z","end":"2026-03-25T07:34:39.415011Z","steps":["trace[299610428] 'process raft request' (duration: 21.391244ms)","trace[299610428] 'compare' (duration: 132.861858ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:39.442645Z","caller":"traceutil/trace.go:171","msg":"trace[1565218434] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"179.780211ms","start":"2026-03-25T07:34:39.262842Z","end":"2026-03-25T07:34:39.442623Z","steps":["trace[1565218434] 'process raft request' (duration: 179.68013ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:39.896691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.068916ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:34:39.896845Z","caller":"traceutil/trace.go:171","msg":"trace[1089338619] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"475.210236ms","start":"2026-03-25T07:34:39.421557Z","end":"2026-03-25T07:34:39.896767Z","steps":["trace[1089338619] 'process raft request' (duration: 294.008548ms)","trace[1089338619] 'compare' (duration: 180.717863ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:39.896922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:39.421538Z","time spent":"475.354517ms","remote":"127.0.0.1:55418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4177,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T07:34:39.952942Z","caller":"traceutil/trace.go:171","msg":"trace[2115926353] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:458; }","duration":"231.87097ms","start":"2026-03-25T07:34:39.721053Z","end":"2026-03-25T07:34:39.952924Z","steps":["trace[2115926353] 'read index received' (duration: 231.708459ms)","trace[2115926353] 'applied index is now lower than readState.Index' (duration: 162.051µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:39.952942Z","caller":"traceutil/trace.go:171","msg":"trace[995165849] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"502.878836ms","start":"2026-03-25T07:34:39.450013Z","end":"2026-03-25T07:34:39.952892Z","steps":["trace[995165849] 'process raft request' (duration: 502.611793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:39.953029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.962641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:34:39.95306Z","caller":"traceutil/trace.go:171","msg":"trace[771069906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:444; }","duration":"232.040572ms","start":"2026-03-25T07:34:39.72101Z","end":"2026-03-25T07:34:39.953051Z","steps":["trace[771069906] 'agreement among raft nodes before linearized reading' (duration: 231.984181ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:39.953155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:39.449957Z","time spent":"503.092659ms","remote":"127.0.0.1:55158","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-03-25T07:34:39.95318Z","caller":"traceutil/trace.go:171","msg":"trace[1004969700] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"420.129914ms","start":"2026-03-25T07:34:39.532993Z","end":"2026-03-25T07:34:39.953123Z","steps":["trace[1004969700] 'process raft request' (duration: 419.904922ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:39.953212Z","caller":"traceutil/trace.go:171","msg":"trace[926119709] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"463.705109ms","start":"2026-03-25T07:34:39.489444Z","end":"2026-03-25T07:34:39.953149Z","steps":["trace[926119709] 'process raft request' (duration: 463.296814ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:39.953333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:39.532962Z","time spent":"420.281206ms","remote":"127.0.0.1:55216","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-03-25T07:34:39.953343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:39.4894Z","time spent":"463.886151ms","remote":"127.0.0.1:55080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:34:40.42912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.004406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:34:40.429562Z","caller":"traceutil/trace.go:171","msg":"trace[900365846] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"528.73264ms","start":"2026-03-25T07:34:39.900748Z","end":"2026-03-25T07:34:40.42948Z","steps":["trace[900365846] 'process raft request' (duration: 528.515158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:40.430053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:39.900729Z","time spent":"528.951692ms","remote":"127.0.0.1:55158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3576,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T07:34:40.430291Z","caller":"traceutil/trace.go:171","msg":"trace[1823949918] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"528.330904ms","start":"2026-03-25T07:34:39.901905Z","end":"2026-03-25T07:34:40.430236Z","steps":["trace[1823949918] 'process raft request' (duration: 527.454255ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:40.430389Z","caller":"traceutil/trace.go:171","msg":"trace[671113102] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"529.67814ms","start":"2026-03-25T07:34:39.900695Z","end":"2026-03-25T07:34:40.430373Z","steps":["trace[671113102] 'process raft request' (duration: 296.315619ms)","trace[671113102] 'compare' (duration: 231.595722ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:40.430384Z","caller":"traceutil/trace.go:171","msg":"trace[1682530146] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"461.628507ms","start":"2026-03-25T07:34:39.968726Z","end":"2026-03-25T07:34:40.430355Z","steps":["trace[1682530146] 'process raft request' (duration: 461.556556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:40.430471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:39.900678Z","time spent":"529.742781ms","remote":"127.0.0.1:55064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:34:40.430547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:39.968706Z","time spent":"461.755569ms","remote":"127.0.0.1:55412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":14448,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:34:40.430575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:39.901894Z","time spent":"528.542387ms","remote":"127.0.0.1:55394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4320,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:34:40.430805Z","caller":"traceutil/trace.go:171","msg":"trace[894627269] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:460; }","duration":"474.63241ms","start":"2026-03-25T07:34:39.956159Z","end":"2026-03-25T07:34:40.430792Z","steps":["trace[894627269] 'read index received' (duration: 240.759893ms)","trace[894627269] 'applied index is now lower than readState.Index' (duration: 233.871377ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:40.430841Z","caller":"traceutil/trace.go:171","msg":"trace[600904333] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"472.112242ms","start":"2026-03-25T07:34:39.958711Z","end":"2026-03-25T07:34:40.430823Z","steps":["trace[600904333] 'process raft request' (duration: 471.512625ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:40.430943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:39.958697Z","time spent":"472.191283ms","remote":"127.0.0.1:55064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T07:34:40.431027Z","caller":"traceutil/trace.go:171","msg":"trace[1725876999] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"472.336054ms","start":"2026-03-25T07:34:39.958672Z","end":"2026-03-25T07:34:40.431008Z","steps":["trace[1725876999] 'process raft request' (duration: 471.012529ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:40.431122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:39.958656Z","time spent":"472.411215ms","remote":"127.0.0.1:55158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15061,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:34:40.431157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.968754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:34:40.431232Z","caller":"traceutil/trace.go:171","msg":"trace[1946258613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:450; }","duration":"475.105555ms","start":"2026-03-25T07:34:39.956107Z","end":"2026-03-25T07:34:40.431212Z","steps":["trace[1946258613] 'agreement among raft nodes before linearized reading' (duration: 474.908292ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:40.431282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:39.956086Z","time spent":"475.188175ms","remote":"127.0.0.1:54990","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-03-25T07:34:40.431364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.930197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-03-25T07:34:40.431398Z","caller":"traceutil/trace.go:171","msg":"trace[43364961] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:450; }","duration":"188.013717ms","start":"2026-03-25T07:34:40.243376Z","end":"2026-03-25T07:34:40.43139Z","steps":["trace[43364961] 'agreement among raft nodes before linearized reading' (duration: 187.947787ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:40.431507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.168782ms","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:329960"} {"level":"info","ts":"2026-03-25T07:34:40.431563Z","caller":"traceutil/trace.go:171","msg":"trace[670972274] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:450; }","duration":"316.222333ms","start":"2026-03-25T07:34:40.115321Z","end":"2026-03-25T07:34:40.431543Z","steps":["trace[670972274] 'agreement among raft nodes before linearized reading' (duration: 315.706528ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:40.431641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:40.115234Z","time spent":"316.396855ms","remote":"127.0.0.1:55080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329983,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-03-25T07:34:40.712196Z","caller":"traceutil/trace.go:171","msg":"trace[2038235521] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"229.950284ms","start":"2026-03-25T07:34:40.482162Z","end":"2026-03-25T07:34:40.712113Z","steps":["trace[2038235521] 'process raft request' (duration: 191.203032ms)","trace[2038235521] 'compare' (duration: 37.992434ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:40.71332Z","caller":"traceutil/trace.go:171","msg":"trace[1591638592] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"181.547496ms","start":"2026-03-25T07:34:40.531642Z","end":"2026-03-25T07:34:40.71319Z","steps":["trace[1591638592] 'process raft request' (duration: 180.98482ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:40.714134Z","caller":"traceutil/trace.go:171","msg":"trace[1775452057] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"216.73167ms","start":"2026-03-25T07:34:40.497368Z","end":"2026-03-25T07:34:40.7141Z","steps":["trace[1775452057] 'process raft request' (duration: 214.122802ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:40.715067Z","caller":"traceutil/trace.go:171","msg":"trace[1072283528] linearizableReadLoop","detail":"{readStateIndex:474; appliedIndex:471; }","duration":"182.417186ms","start":"2026-03-25T07:34:40.532567Z","end":"2026-03-25T07:34:40.714985Z","steps":["trace[1072283528] 'read index received' (duration: 140.850533ms)","trace[1072283528] 'applied index is now lower than readState.Index' (duration: 41.563733ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:40.718048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.297447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-869df985b8\" ","response":"range_response_count:1 size:4309"} {"level":"info","ts":"2026-03-25T07:34:40.718654Z","caller":"traceutil/trace.go:171","msg":"trace[247831692] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-869df985b8; range_end:; response_count:1; response_revision:458; }","duration":"186.026245ms","start":"2026-03-25T07:34:40.53253Z","end":"2026-03-25T07:34:40.718556Z","steps":["trace[247831692] 'agreement among raft nodes before linearized reading' (duration: 183.402637ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:40.720356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.016917ms","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:329960"} {"level":"info","ts":"2026-03-25T07:34:40.720403Z","caller":"traceutil/trace.go:171","msg":"trace[159760740] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:458; }","duration":"165.068528ms","start":"2026-03-25T07:34:40.555321Z","end":"2026-03-25T07:34:40.72039Z","steps":["trace[159760740] 'agreement among raft nodes before linearized reading' (duration: 161.368548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:40.720395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.623303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-03-25T07:34:40.720624Z","caller":"traceutil/trace.go:171","msg":"trace[1493610822] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:458; }","duration":"143.911567ms","start":"2026-03-25T07:34:40.576701Z","end":"2026-03-25T07:34:40.720612Z","steps":["trace[1493610822] 'agreement among raft nodes before linearized reading' (duration: 141.40682ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:40.720817Z","caller":"traceutil/trace.go:171","msg":"trace[1554118579] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"183.903312ms","start":"2026-03-25T07:34:40.53481Z","end":"2026-03-25T07:34:40.718713Z","steps":["trace[1554118579] 'process raft request' (duration: 177.961698ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:43.536749Z","caller":"traceutil/trace.go:171","msg":"trace[398822568] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"274.860564ms","start":"2026-03-25T07:34:43.26187Z","end":"2026-03-25T07:34:43.53673Z","steps":["trace[398822568] 'read index received' (duration: 274.681362ms)","trace[398822568] 'applied index is now lower than readState.Index' (duration: 176.382µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:43.536801Z","caller":"traceutil/trace.go:171","msg":"trace[1040798170] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"815.278597ms","start":"2026-03-25T07:34:42.721492Z","end":"2026-03-25T07:34:43.536771Z","steps":["trace[1040798170] 'process raft request' (duration: 815.118736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:43.536921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.047716ms","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-03-25T07:34:43.53693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:42.721464Z","time spent":"815.391408ms","remote":"127.0.0.1:55216","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-03-25T07:34:43.536951Z","caller":"traceutil/trace.go:171","msg":"trace[946949767] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:468; }","duration":"275.125536ms","start":"2026-03-25T07:34:43.261816Z","end":"2026-03-25T07:34:43.536942Z","steps":["trace[946949767] 'agreement among raft nodes before linearized reading' (duration: 275.012825ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:43.537005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.051156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-25T07:34:43.537042Z","caller":"traceutil/trace.go:171","msg":"trace[1243848653] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"252.112698ms","start":"2026-03-25T07:34:43.284918Z","end":"2026-03-25T07:34:43.53703Z","steps":["trace[1243848653] 'agreement among raft nodes before linearized reading' (duration: 252.032237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:43.537038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.754655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-25T07:34:43.537077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.174929ms","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-03-25T07:34:43.537102Z","caller":"traceutil/trace.go:171","msg":"trace[114448811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"252.22159ms","start":"2026-03-25T07:34:43.284872Z","end":"2026-03-25T07:34:43.537094Z","steps":["trace[114448811] 'agreement among raft nodes before linearized reading' (duration: 252.159458ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:43.537101Z","caller":"traceutil/trace.go:171","msg":"trace[1217685834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:468; }","duration":"253.820367ms","start":"2026-03-25T07:34:43.283263Z","end":"2026-03-25T07:34:43.537083Z","steps":["trace[1217685834] 'agreement among raft nodes before linearized reading' (duration: 253.604173ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:44.218359Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633073,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-25T07:34:44.236885Z","caller":"traceutil/trace.go:171","msg":"trace[71622751] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"695.349777ms","start":"2026-03-25T07:34:43.541512Z","end":"2026-03-25T07:34:44.236862Z","steps":["trace[71622751] 'process raft request' (duration: 695.226636ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:44.23714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:43.541489Z","time spent":"695.533049ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:34:44.262438Z","caller":"traceutil/trace.go:171","msg":"trace[1858815523] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"720.65322ms","start":"2026-03-25T07:34:43.541759Z","end":"2026-03-25T07:34:44.262412Z","steps":["trace[1858815523] 'process raft request' (duration: 720.408558ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:44.262586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:43.541735Z","time spent":"720.775432ms","remote":"127.0.0.1:55216","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-03-25T07:34:44.263037Z","caller":"traceutil/trace.go:171","msg":"trace[1287625614] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"721.095816ms","start":"2026-03-25T07:34:43.541906Z","end":"2026-03-25T07:34:44.263002Z","steps":["trace[1287625614] 'process raft request' (duration: 720.453939ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:44.263229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:43.541897Z","time spent":"721.255278ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:34:44.264214Z","caller":"traceutil/trace.go:171","msg":"trace[1903548737] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"721.751253ms","start":"2026-03-25T07:34:43.542453Z","end":"2026-03-25T07:34:44.264204Z","steps":["trace[1903548737] 'process raft request' (duration: 720.296088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:44.2643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:43.542445Z","time spent":"721.824024ms","remote":"127.0.0.1:55216","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-03-25T07:34:44.264696Z","caller":"traceutil/trace.go:171","msg":"trace[1281022685] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:485; }","duration":"546.857331ms","start":"2026-03-25T07:34:43.717803Z","end":"2026-03-25T07:34:44.26466Z","steps":["trace[1281022685] 'read index received' (duration: 518.99898ms)","trace[1281022685] 'applied index is now lower than readState.Index' (duration: 27.84088ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:44.264947Z","caller":"traceutil/trace.go:171","msg":"trace[1230653176] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"720.089024ms","start":"2026-03-25T07:34:43.544842Z","end":"2026-03-25T07:34:44.264931Z","steps":["trace[1230653176] 'process raft request' (duration: 718.980703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:44.265245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:43.544832Z","time spent":"720.167015ms","remote":"127.0.0.1:55146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4565,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:34:44.265501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.037812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:34:44.26563Z","caller":"traceutil/trace.go:171","msg":"trace[254378988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:473; }","duration":"547.830421ms","start":"2026-03-25T07:34:43.717766Z","end":"2026-03-25T07:34:44.265597Z","steps":["trace[254378988] 'agreement among raft nodes before linearized reading' (duration: 546.984572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:44.265688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:43.717748Z","time spent":"547.924842ms","remote":"127.0.0.1:54974","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-03-25T07:34:46.422961Z","caller":"traceutil/trace.go:171","msg":"trace[681660031] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"124.860524ms","start":"2026-03-25T07:34:46.298083Z","end":"2026-03-25T07:34:46.422944Z","steps":["trace[681660031] 'process raft request' (duration: 124.740672ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:46.447224Z","caller":"traceutil/trace.go:171","msg":"trace[1184713207] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"148.668519ms","start":"2026-03-25T07:34:46.29854Z","end":"2026-03-25T07:34:46.447208Z","steps":["trace[1184713207] 'process raft request' (duration: 148.646008ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:46.44732Z","caller":"traceutil/trace.go:171","msg":"trace[1340991739] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"148.887491ms","start":"2026-03-25T07:34:46.29839Z","end":"2026-03-25T07:34:46.447277Z","steps":["trace[1340991739] 'process raft request' (duration: 148.71291ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:48.358747Z","caller":"traceutil/trace.go:171","msg":"trace[379772626] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"434.75277ms","start":"2026-03-25T07:34:47.923938Z","end":"2026-03-25T07:34:48.358691Z","steps":["trace[379772626] 'process raft request' (duration: 434.477017ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:48.359844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:47.923909Z","time spent":"435.151175ms","remote":"127.0.0.1:55096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":389,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:34:48.927658Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633105,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-25T07:34:49.012019Z","caller":"traceutil/trace.go:171","msg":"trace[497718585] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:497; }","duration":"584.949337ms","start":"2026-03-25T07:34:48.427048Z","end":"2026-03-25T07:34:49.011997Z","steps":["trace[497718585] 'read index received' (duration: 581.368809ms)","trace[497718585] 'applied index is now lower than readState.Index' (duration: 3.579688ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:49.012028Z","caller":"traceutil/trace.go:171","msg":"trace[675492622] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"648.606879ms","start":"2026-03-25T07:34:48.363396Z","end":"2026-03-25T07:34:49.012003Z","steps":["trace[675492622] 'process raft request' (duration: 645.119242ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:49.01216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:48.363376Z","time spent":"648.712581ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:34:49.012187Z","caller":"traceutil/trace.go:171","msg":"trace[1235769959] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"648.729931ms","start":"2026-03-25T07:34:48.363446Z","end":"2026-03-25T07:34:49.012176Z","steps":["trace[1235769959] 'process raft request' (duration: 648.463248ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:49.012268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:48.363426Z","time spent":"648.790642ms","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1745,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T07:34:49.01249Z","caller":"traceutil/trace.go:171","msg":"trace[1714296364] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"645.644227ms","start":"2026-03-25T07:34:48.366833Z","end":"2026-03-25T07:34:49.012477Z","steps":["trace[1714296364] 'process raft request' (duration: 645.126352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:49.012571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:48.366822Z","time spent":"645.699628ms","remote":"127.0.0.1:55080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12761,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:34:49.012649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.603394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-25T07:34:49.012689Z","caller":"traceutil/trace.go:171","msg":"trace[1180586693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:482; }","duration":"585.680585ms","start":"2026-03-25T07:34:48.426996Z","end":"2026-03-25T07:34:49.012676Z","steps":["trace[1180586693] 'agreement among raft nodes before linearized reading' (duration: 585.563334ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:49.012725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:48.426974Z","time spent":"585.744086ms","remote":"127.0.0.1:55216","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-03-25T07:34:49.012727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.273814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-25T07:34:49.012765Z","caller":"traceutil/trace.go:171","msg":"trace[1031710577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:482; }","duration":"561.336954ms","start":"2026-03-25T07:34:48.451417Z","end":"2026-03-25T07:34:49.012754Z","steps":["trace[1031710577] 'agreement among raft nodes before linearized reading' (duration: 561.207793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:49.012791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:48.451406Z","time spent":"561.379564ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T07:34:49.012797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.693578ms","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-03-25T07:34:49.012864Z","caller":"traceutil/trace.go:171","msg":"trace[716574665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:482; }","duration":"561.787499ms","start":"2026-03-25T07:34:48.451057Z","end":"2026-03-25T07:34:49.012845Z","steps":["trace[716574665] 'agreement among raft nodes before linearized reading' (duration: 561.569827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:49.012913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:48.451007Z","time spent":"561.89096ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-25T07:34:49.352324Z","caller":"traceutil/trace.go:171","msg":"trace[1861556102] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"320.7601ms","start":"2026-03-25T07:34:49.031533Z","end":"2026-03-25T07:34:49.352294Z","steps":["trace[1861556102] 'process raft request' (duration: 320.513087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:49.352462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:49.031502Z","time spent":"320.888831ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:34:49.352465Z","caller":"traceutil/trace.go:171","msg":"trace[148826410] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"320.388135ms","start":"2026-03-25T07:34:49.032056Z","end":"2026-03-25T07:34:49.352444Z","steps":["trace[148826410] 'process raft request' (duration: 320.177633ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:49.352475Z","caller":"traceutil/trace.go:171","msg":"trace[260233337] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"320.673608ms","start":"2026-03-25T07:34:49.031667Z","end":"2026-03-25T07:34:49.352341Z","steps":["trace[260233337] 'process raft request' (duration: 320.526547ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:49.352671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:49.032048Z","time spent":"320.479546ms","remote":"127.0.0.1:55172","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:34:49.352751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:49.031635Z","time spent":"320.988872ms","remote":"127.0.0.1:55216","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-03-25T07:34:49.730322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.99383ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:34:49.730538Z","caller":"traceutil/trace.go:171","msg":"trace[664828503] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"373.533464ms","start":"2026-03-25T07:34:49.356974Z","end":"2026-03-25T07:34:49.730507Z","steps":["trace[664828503] 'process raft request' (duration: 156.261411ms)","trace[664828503] 'compare' (duration: 215.928699ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:49.730819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:49.356946Z","time spent":"373.649935ms","remote":"127.0.0.1:55082","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1449,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T07:34:50.020041Z","caller":"traceutil/trace.go:171","msg":"trace[1784254925] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"285.448203ms","start":"2026-03-25T07:34:49.734566Z","end":"2026-03-25T07:34:50.020014Z","steps":["trace[1784254925] 'process raft request' (duration: 283.801135ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:50.34933Z","caller":"traceutil/trace.go:171","msg":"trace[675836943] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"324.11097ms","start":"2026-03-25T07:34:50.025183Z","end":"2026-03-25T07:34:50.349294Z","steps":["trace[675836943] 'process raft request' (duration: 282.80021ms)","trace[675836943] 'compare' (duration: 41.093148ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:50.349517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:50.025161Z","time spent":"324.272631ms","remote":"127.0.0.1:55294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1091,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:34:50.71556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.926339ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:34:50.715794Z","caller":"traceutil/trace.go:171","msg":"trace[1390467255] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"661.846365ms","start":"2026-03-25T07:34:50.053934Z","end":"2026-03-25T07:34:50.71578Z","steps":["trace[1390467255] 'process raft request' (duration: 498.631733ms)","trace[1390467255] 'compare' (duration: 162.685457ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:50.715866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:50.053913Z","time spent":"661.918546ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:34:50.715945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.556055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-03-25T07:34:50.715987Z","caller":"traceutil/trace.go:171","msg":"trace[1279009169] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:490; }","duration":"472.625946ms","start":"2026-03-25T07:34:50.24335Z","end":"2026-03-25T07:34:50.715976Z","steps":["trace[1279009169] 'agreement among raft nodes before linearized reading' (duration: 472.503815ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:50.715752Z","caller":"traceutil/trace.go:171","msg":"trace[133578835] linearizableReadLoop","detail":"{readStateIndex:508; appliedIndex:506; }","duration":"472.338263ms","start":"2026-03-25T07:34:50.243391Z","end":"2026-03-25T07:34:50.715729Z","steps":["trace[133578835] 'read index received' (duration: 64.606241ms)","trace[133578835] 'applied index is now lower than readState.Index' (duration: 407.730692ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:50.71602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:50.243333Z","time spent":"472.678997ms","remote":"127.0.0.1:55014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"info","ts":"2026-03-25T07:34:50.737311Z","caller":"traceutil/trace.go:171","msg":"trace[870185577] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"383.659626ms","start":"2026-03-25T07:34:50.353589Z","end":"2026-03-25T07:34:50.737248Z","steps":["trace[870185577] 'process raft request' (duration: 383.414053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:50.737637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:50.353563Z","time spent":"383.931788ms","remote":"127.0.0.1:55310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1066,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:34:50.994011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.044832ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-25T07:34:50.994098Z","caller":"traceutil/trace.go:171","msg":"trace[795514452] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:509; }","duration":"255.476806ms","start":"2026-03-25T07:34:50.738609Z","end":"2026-03-25T07:34:50.994086Z","steps":["trace[795514452] 'read index received' (duration: 89.474605ms)","trace[795514452] 'applied index is now lower than readState.Index' (duration: 166.001411ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:50.994146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.541247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:34:50.994163Z","caller":"traceutil/trace.go:171","msg":"trace[285541772] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:491; }","duration":"255.602428ms","start":"2026-03-25T07:34:50.738555Z","end":"2026-03-25T07:34:50.994157Z","steps":["trace[285541772] 'agreement among raft nodes before linearized reading' (duration: 255.561518ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:50.996831Z","caller":"traceutil/trace.go:171","msg":"trace[1421526511] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"235.550034ms","start":"2026-03-25T07:34:50.761257Z","end":"2026-03-25T07:34:50.996807Z","steps":["trace[1421526511] 'process raft request' (duration: 235.338872ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:51.188572Z","caller":"traceutil/trace.go:171","msg":"trace[753717858] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"186.098864ms","start":"2026-03-25T07:34:51.002416Z","end":"2026-03-25T07:34:51.188515Z","steps":["trace[753717858] 'process raft request' (duration: 169.304155ms)","trace[753717858] 'compare' (duration: 16.468415ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:51.188752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.832648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:420"} {"level":"info","ts":"2026-03-25T07:34:51.188787Z","caller":"traceutil/trace.go:171","msg":"trace[1658650510] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:494; }","duration":"182.919959ms","start":"2026-03-25T07:34:51.00586Z","end":"2026-03-25T07:34:51.18878Z","steps":["trace[1658650510] 'agreement among raft nodes before linearized reading' (duration: 182.776667ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:51.188502Z","caller":"traceutil/trace.go:171","msg":"trace[1275403390] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:512; }","duration":"182.579565ms","start":"2026-03-25T07:34:51.005898Z","end":"2026-03-25T07:34:51.188478Z","steps":["trace[1275403390] 'read index received' (duration: 165.841007ms)","trace[1275403390] 'applied index is now lower than readState.Index' (duration: 16.736798ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:51.413274Z","caller":"traceutil/trace.go:171","msg":"trace[455201814] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"406.469971ms","start":"2026-03-25T07:34:51.00676Z","end":"2026-03-25T07:34:51.41323Z","steps":["trace[455201814] 'process raft request' (duration: 332.405203ms)","trace[455201814] 'compare' (duration: 73.790936ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:51.413498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:51.006745Z","time spent":"406.624403ms","remote":"127.0.0.1:55080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:34:51.413751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.623607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-25T07:34:51.413812Z","caller":"traceutil/trace.go:171","msg":"trace[550288722] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:513; }","duration":"224.120258ms","start":"2026-03-25T07:34:51.189068Z","end":"2026-03-25T07:34:51.413188Z","steps":["trace[550288722] 'read index received' (duration: 150.013879ms)","trace[550288722] 'applied index is now lower than readState.Index' (duration: 74.105259ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:51.413834Z","caller":"traceutil/trace.go:171","msg":"trace[841601253] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:495; }","duration":"306.819339ms","start":"2026-03-25T07:34:51.106995Z","end":"2026-03-25T07:34:51.413815Z","steps":["trace[841601253] 'agreement among raft nodes before linearized reading' (duration: 306.465926ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:51.413875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:51.106964Z","time spent":"306.90389ms","remote":"127.0.0.1:55216","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-03-25T07:34:51.413953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.22367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-25T07:34:51.413979Z","caller":"traceutil/trace.go:171","msg":"trace[414324847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:495; }","duration":"392.3017ms","start":"2026-03-25T07:34:51.02167Z","end":"2026-03-25T07:34:51.413972Z","steps":["trace[414324847] 'agreement among raft nodes before linearized reading' (duration: 392.184779ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:51.414003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:51.02163Z","time spent":"392.368211ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-25T07:34:51.915249Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633135,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-25T07:34:52.102431Z","caller":"traceutil/trace.go:171","msg":"trace[1169038429] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"907.121355ms","start":"2026-03-25T07:34:51.195288Z","end":"2026-03-25T07:34:52.10241Z","steps":["trace[1169038429] 'process raft request' (duration: 907.060594ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:52.102531Z","caller":"traceutil/trace.go:171","msg":"trace[1605472752] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"907.404188ms","start":"2026-03-25T07:34:51.19507Z","end":"2026-03-25T07:34:52.102474Z","steps":["trace[1605472752] 'process raft request' (duration: 907.123556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:52.102657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:51.195263Z","time spent":"907.265007ms","remote":"127.0.0.1:55394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3371,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:34:52.102762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:51.195033Z","time spent":"907.61236ms","remote":"127.0.0.1:55064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":760,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T07:34:52.506427Z","caller":"traceutil/trace.go:171","msg":"trace[1911274932] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"1.087103284s","start":"2026-03-25T07:34:51.419291Z","end":"2026-03-25T07:34:52.506394Z","steps":["trace[1911274932] 'process raft request' (duration: 1.017994289s)","trace[1911274932] 'compare' (duration: 68.850492ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:52.506592Z","caller":"traceutil/trace.go:171","msg":"trace[888394825] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:514; }","duration":"1.09230022s","start":"2026-03-25T07:34:51.414263Z","end":"2026-03-25T07:34:52.506564Z","steps":["trace[888394825] 'read index received' (duration: 687.9343ms)","trace[888394825] 'applied index is now lower than readState.Index' (duration: 404.36384ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:52.506684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310519515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T07:34:52.506645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:51.419262Z","time spent":"1.087272385s","remote":"127.0.0.1:55216","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-03-25T07:34:52.506772Z","caller":"traceutil/trace.go:171","msg":"trace[2060119364] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:499; }","duration":"1.310645705s","start":"2026-03-25T07:34:51.196094Z","end":"2026-03-25T07:34:52.506739Z","steps":["trace[2060119364] 'agreement among raft nodes before linearized reading' (duration: 1.310527924s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:52.506793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14865895s","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-03-25T07:34:52.506794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148440148s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-25T07:34:52.506844Z","caller":"traceutil/trace.go:171","msg":"trace[428015071] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:499; }","duration":"1.148767501s","start":"2026-03-25T07:34:51.358063Z","end":"2026-03-25T07:34:52.50683Z","steps":["trace[428015071] 'agreement among raft nodes before linearized reading' (duration: 1.148571339s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:52.506847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:51.196079Z","time spent":"1.310749496s","remote":"127.0.0.1:55114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" "} {"level":"info","ts":"2026-03-25T07:34:52.506871Z","caller":"traceutil/trace.go:171","msg":"trace[1911384186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:499; }","duration":"1.148553649s","start":"2026-03-25T07:34:51.358299Z","end":"2026-03-25T07:34:52.506853Z","steps":["trace[1911384186] 'agreement among raft nodes before linearized reading' (duration: 1.148274816s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:52.506883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:51.358028Z","time spent":"1.148846292s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T07:34:52.506921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:51.35825Z","time spent":"1.14865842s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-25T07:34:52.506923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.089852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:3386"} {"level":"info","ts":"2026-03-25T07:34:52.506963Z","caller":"traceutil/trace.go:171","msg":"trace[1267183684] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:499; }","duration":"396.177945ms","start":"2026-03-25T07:34:52.110772Z","end":"2026-03-25T07:34:52.50695Z","steps":["trace[1267183684] 'agreement among raft nodes before linearized reading' (duration: 396.086643ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:52.506934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037829969s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-03-25T07:34:52.506998Z","caller":"traceutil/trace.go:171","msg":"trace[1025758578] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"1.087541579s","start":"2026-03-25T07:34:51.419442Z","end":"2026-03-25T07:34:52.506984Z","steps":["trace[1025758578] 'process raft request' (duration: 1.086897711s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:52.507831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:52.11074Z","time spent":"396.245395ms","remote":"127.0.0.1:55394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":3409,"request content":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" "} {"level":"info","ts":"2026-03-25T07:34:52.507859Z","caller":"traceutil/trace.go:171","msg":"trace[970427275] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:499; }","duration":"1.037971381s","start":"2026-03-25T07:34:51.469026Z","end":"2026-03-25T07:34:52.506998Z","steps":["trace[970427275] 'agreement among raft nodes before linearized reading' (duration: 1.037727138s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:52.507967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:51.468998Z","time spent":"1.038924991s","remote":"127.0.0.1:55080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12778,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-03-25T07:34:52.507968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:51.419434Z","time spent":"1.088442839s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:34:52.506828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.316154644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-03-25T07:34:52.509399Z","caller":"traceutil/trace.go:171","msg":"trace[1502043323] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:499; }","duration":"1.318754292s","start":"2026-03-25T07:34:51.190623Z","end":"2026-03-25T07:34:52.509377Z","steps":["trace[1502043323] 'agreement among raft nodes before linearized reading' (duration: 1.316077723s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:52.50948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:51.190609Z","time spent":"1.318837452s","remote":"127.0.0.1:55014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-03-25T07:34:52.815782Z","caller":"traceutil/trace.go:171","msg":"trace[1763220356] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:518; }","duration":"303.230056ms","start":"2026-03-25T07:34:52.512522Z","end":"2026-03-25T07:34:52.815752Z","steps":["trace[1763220356] 'read index received' (duration: 295.513933ms)","trace[1763220356] 'applied index is now lower than readState.Index' (duration: 7.714143ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:52.815996Z","caller":"traceutil/trace.go:171","msg":"trace[571792660] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"304.160855ms","start":"2026-03-25T07:34:52.511728Z","end":"2026-03-25T07:34:52.815889Z","steps":["trace[571792660] 'process raft request' (duration: 296.20337ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:52.816252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.70496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-25T07:34:52.81644Z","caller":"traceutil/trace.go:171","msg":"trace[2070713945] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:500; }","duration":"303.941873ms","start":"2026-03-25T07:34:52.512482Z","end":"2026-03-25T07:34:52.816423Z","steps":["trace[2070713945] 'agreement among raft nodes before linearized reading' (duration: 303.409788ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:52.816425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:52.511691Z","time spent":"304.62282ms","remote":"127.0.0.1:55158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2589,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:34:52.816491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:52.512465Z","time spent":"304.016274ms","remote":"127.0.0.1:55224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-03-25T07:34:53.177223Z","caller":"traceutil/trace.go:171","msg":"trace[1489852990] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"660.709274ms","start":"2026-03-25T07:34:52.516471Z","end":"2026-03-25T07:34:53.17718Z","steps":["trace[1489852990] 'process raft request' (duration: 657.701632ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:53.177468Z","caller":"traceutil/trace.go:171","msg":"trace[14518289] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"660.753014ms","start":"2026-03-25T07:34:52.516687Z","end":"2026-03-25T07:34:53.17744Z","steps":["trace[14518289] 'process raft request' (duration: 660.403211ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:53.177589Z","caller":"traceutil/trace.go:171","msg":"trace[287335824] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"660.118428ms","start":"2026-03-25T07:34:52.517438Z","end":"2026-03-25T07:34:53.177557Z","steps":["trace[287335824] 'process raft request' (duration: 659.933816ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:53.177593Z","caller":"traceutil/trace.go:171","msg":"trace[1630687818] linearizableReadLoop","detail":"{readStateIndex:522; appliedIndex:519; }","duration":"361.661523ms","start":"2026-03-25T07:34:52.815858Z","end":"2026-03-25T07:34:53.177519Z","steps":["trace[1630687818] 'read index received' (duration: 358.243307ms)","trace[1630687818] 'applied index is now lower than readState.Index' (duration: 3.416756ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:53.177696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:52.51743Z","time spent":"660.209789ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:34:53.177681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:52.516679Z","time spent":"660.864426ms","remote":"127.0.0.1:55394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3681,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:34:53.177895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.161925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"warn","ts":"2026-03-25T07:34:53.177472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:52.516453Z","time spent":"660.886986ms","remote":"127.0.0.1:55216","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-03-25T07:34:53.177947Z","caller":"traceutil/trace.go:171","msg":"trace[21468801] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:503; }","duration":"608.241476ms","start":"2026-03-25T07:34:52.569688Z","end":"2026-03-25T07:34:53.177929Z","steps":["trace[21468801] 'agreement among raft nodes before linearized reading' (duration: 607.994294ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:53.17799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:52.569644Z","time spent":"608.336697ms","remote":"127.0.0.1:55080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12778,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-03-25T07:34:53.178792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.461516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:34:53.178882Z","caller":"traceutil/trace.go:171","msg":"trace[776626811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:503; }","duration":"464.585358ms","start":"2026-03-25T07:34:52.714273Z","end":"2026-03-25T07:34:53.178858Z","steps":["trace[776626811] 'agreement among raft nodes before linearized reading' (duration: 463.563437ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:53.179192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:52.714254Z","time spent":"464.926361ms","remote":"127.0.0.1:54974","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-03-25T07:34:53.416723Z","caller":"traceutil/trace.go:171","msg":"trace[699972959] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"593.818958ms","start":"2026-03-25T07:34:52.82288Z","end":"2026-03-25T07:34:53.416699Z","steps":["trace[699972959] 'process raft request' (duration: 532.337965ms)","trace[699972959] 'compare' (duration: 61.238801ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:53.416754Z","caller":"traceutil/trace.go:171","msg":"trace[661141649] linearizableReadLoop","detail":"{readStateIndex:526; appliedIndex:522; }","duration":"236.39301ms","start":"2026-03-25T07:34:53.180351Z","end":"2026-03-25T07:34:53.416744Z","steps":["trace[661141649] 'read index received' (duration: 174.827736ms)","trace[661141649] 'applied index is now lower than readState.Index' (duration: 61.564494ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:53.416777Z","caller":"traceutil/trace.go:171","msg":"trace[829232713] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"593.686887ms","start":"2026-03-25T07:34:52.823058Z","end":"2026-03-25T07:34:53.416745Z","steps":["trace[829232713] 'process raft request' (duration: 593.546575ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:53.416837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:52.822821Z","time spent":"593.9649ms","remote":"127.0.0.1:55064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":781,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:34:53.416857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.498541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:34:53.416882Z","caller":"traceutil/trace.go:171","msg":"trace[971358104] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:507; }","duration":"236.575281ms","start":"2026-03-25T07:34:53.1803Z","end":"2026-03-25T07:34:53.416875Z","steps":["trace[971358104] 'agreement among raft nodes before linearized reading' (duration: 236.48738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:53.416935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:52.823036Z","time spent":"593.809659ms","remote":"127.0.0.1:55418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3204,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:34:53.417139Z","caller":"traceutil/trace.go:171","msg":"trace[609561478] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"593.581385ms","start":"2026-03-25T07:34:52.823538Z","end":"2026-03-25T07:34:53.41712Z","steps":["trace[609561478] 'process raft request' (duration: 593.161911ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:53.417187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:52.823524Z","time spent":"593.645836ms","remote":"127.0.0.1:55064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T07:34:53.417255Z","caller":"traceutil/trace.go:171","msg":"trace[223311950] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"594.142472ms","start":"2026-03-25T07:34:52.823104Z","end":"2026-03-25T07:34:53.417246Z","steps":["trace[223311950] 'process raft request' (duration: 593.565076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:53.417315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:52.823081Z","time spent":"594.203052ms","remote":"127.0.0.1:55158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:34:53.809943Z","caller":"traceutil/trace.go:171","msg":"trace[282303131] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"290.12821ms","start":"2026-03-25T07:34:53.519794Z","end":"2026-03-25T07:34:53.809922Z","steps":["trace[282303131] 'process raft request' (duration: 290.006079ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:54.375296Z","caller":"traceutil/trace.go:171","msg":"trace[1343703041] linearizableReadLoop","detail":"{readStateIndex:529; appliedIndex:527; }","duration":"660.305922ms","start":"2026-03-25T07:34:53.714969Z","end":"2026-03-25T07:34:54.375275Z","steps":["trace[1343703041] 'read index received' (duration: 94.826267ms)","trace[1343703041] 'applied index is now lower than readState.Index' (duration: 565.478765ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:54.375407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.415213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:34:54.375429Z","caller":"traceutil/trace.go:171","msg":"trace[1437867947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"660.494084ms","start":"2026-03-25T07:34:53.714928Z","end":"2026-03-25T07:34:54.375422Z","steps":["trace[1437867947] 'agreement among raft nodes before linearized reading' (duration: 660.416853ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:54.375451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:53.714907Z","time spent":"660.538215ms","remote":"127.0.0.1:54974","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-03-25T07:34:54.375568Z","caller":"traceutil/trace.go:171","msg":"trace[283498056] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"725.720038ms","start":"2026-03-25T07:34:53.649835Z","end":"2026-03-25T07:34:54.375555Z","steps":["trace[283498056] 'process raft request' (duration: 661.832171ms)","trace[283498056] 'compare' (duration: 63.497892ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:54.375655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:53.649816Z","time spent":"725.78958ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:34:55.004479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.885951ms","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-03-25T07:34:55.004561Z","caller":"traceutil/trace.go:171","msg":"trace[1730140238] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"487.028092ms","start":"2026-03-25T07:34:54.517514Z","end":"2026-03-25T07:34:55.004542Z","steps":["trace[1730140238] 'range keys from in-memory index tree' (duration: 486.708908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:55.004609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:54.517497Z","time spent":"487.102372ms","remote":"127.0.0.1:55216","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-03-25T07:34:55.004764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.251163ms","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-03-25T07:34:55.004826Z","caller":"traceutil/trace.go:171","msg":"trace[404550231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"486.352054ms","start":"2026-03-25T07:34:54.518459Z","end":"2026-03-25T07:34:55.004811Z","steps":["trace[404550231] 'range keys from in-memory index tree' (duration: 486.118611ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:55.004854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:54.518444Z","time spent":"486.404344ms","remote":"127.0.0.1:55216","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-03-25T07:34:55.383908Z","caller":"traceutil/trace.go:171","msg":"trace[1039045524] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"375.124407ms","start":"2026-03-25T07:34:55.008756Z","end":"2026-03-25T07:34:55.383881Z","steps":["trace[1039045524] 'process raft request' (duration: 374.966516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:55.384072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:55.008738Z","time spent":"375.24792ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:34:55.405216Z","caller":"traceutil/trace.go:171","msg":"trace[494797171] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:529; }","duration":"222.16902ms","start":"2026-03-25T07:34:55.183029Z","end":"2026-03-25T07:34:55.405198Z","steps":["trace[494797171] 'read index received' (duration: 200.654402ms)","trace[494797171] 'applied index is now lower than readState.Index' (duration: 21.513918ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:34:55.405292Z","caller":"traceutil/trace.go:171","msg":"trace[1539817859] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"394.743286ms","start":"2026-03-25T07:34:55.010521Z","end":"2026-03-25T07:34:55.405264Z","steps":["trace[1539817859] 'process raft request' (duration: 394.581125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:55.405384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.336772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-25T07:34:55.405405Z","caller":"traceutil/trace.go:171","msg":"trace[720688944] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:512; }","duration":"222.416803ms","start":"2026-03-25T07:34:55.182981Z","end":"2026-03-25T07:34:55.405398Z","steps":["trace[720688944] 'agreement among raft nodes before linearized reading' (duration: 222.303501ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:34:55.405453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.14632ms","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-03-25T07:34:55.405467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:55.01049Z","time spent":"394.865007ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:34:55.405513Z","caller":"traceutil/trace.go:171","msg":"trace[2037150173] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"222.220411ms","start":"2026-03-25T07:34:55.183277Z","end":"2026-03-25T07:34:55.405497Z","steps":["trace[2037150173] 'agreement among raft nodes before linearized reading' (duration: 222.070179ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:55.523339Z","caller":"traceutil/trace.go:171","msg":"trace[340651141] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"112.060775ms","start":"2026-03-25T07:34:55.411248Z","end":"2026-03-25T07:34:55.523309Z","steps":["trace[340651141] 'process raft request' (duration: 111.978254ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:34:55.523502Z","caller":"traceutil/trace.go:171","msg":"trace[97807344] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"113.229078ms","start":"2026-03-25T07:34:55.410251Z","end":"2026-03-25T07:34:55.52348Z","steps":["trace[97807344] 'process raft request' (duration: 21.934502ms)","trace[97807344] 'compare' (duration: 90.846041ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:34:59.929581Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633177,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:35:00.429985Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633177,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:35:00.743681Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.341033742s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T07:35:00.743964Z","caller":"traceutil/trace.go:171","msg":"trace[1156272496] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"1.314483722s","start":"2026-03-25T07:34:59.429464Z","end":"2026-03-25T07:35:00.743947Z","steps":["trace[1156272496] 'read index received' (duration: 1.31436847s)","trace[1156272496] 'applied index is now lower than readState.Index' (duration: 114.612µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:35:00.744056Z","caller":"traceutil/trace.go:171","msg":"trace[1480778307] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.341499587s","start":"2026-03-25T07:34:59.40255Z","end":"2026-03-25T07:35:00.744049Z","steps":["trace[1480778307] 'process raft request' (duration: 1.341300965s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:00.744145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:59.40253Z","time spent":"1.341549127s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:35:00.74428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192214298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-25T07:35:00.744319Z","caller":"traceutil/trace.go:171","msg":"trace[47686425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"1.192295698s","start":"2026-03-25T07:34:59.552009Z","end":"2026-03-25T07:35:00.744305Z","steps":["trace[47686425] 'agreement among raft nodes before linearized reading' (duration: 1.192159337s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:00.74441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:59.551993Z","time spent":"1.19235332s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T07:35:00.744552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029503087s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:35:00.744572Z","caller":"traceutil/trace.go:171","msg":"trace[1180535089] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"1.029551257s","start":"2026-03-25T07:34:59.715013Z","end":"2026-03-25T07:35:00.744564Z","steps":["trace[1180535089] 'agreement among raft nodes before linearized reading' (duration: 1.029521987s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:00.744589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:59.714996Z","time spent":"1.029589838s","remote":"127.0.0.1:54974","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-03-25T07:35:00.744593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.430361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-03-25T07:35:00.744629Z","caller":"traceutil/trace.go:171","msg":"trace[2004771612] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:519; }","duration":"500.495871ms","start":"2026-03-25T07:35:00.244122Z","end":"2026-03-25T07:35:00.744618Z","steps":["trace[2004771612] 'agreement among raft nodes before linearized reading' (duration: 500.34377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:00.744675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:00.244102Z","time spent":"500.563833ms","remote":"127.0.0.1:55014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"warn","ts":"2026-03-25T07:35:00.744729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.16973084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-25T07:35:00.744752Z","caller":"traceutil/trace.go:171","msg":"trace[1765130716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:519; }","duration":"1.169784671s","start":"2026-03-25T07:34:59.574961Z","end":"2026-03-25T07:35:00.744746Z","steps":["trace[1765130716] 'agreement among raft nodes before linearized reading' (duration: 1.16972074s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:00.744769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:59.574943Z","time spent":"1.169822242s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-25T07:35:00.744857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.315396982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-25T07:35:00.744886Z","caller":"traceutil/trace.go:171","msg":"trace[510640104] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:519; }","duration":"1.315458632s","start":"2026-03-25T07:34:59.429418Z","end":"2026-03-25T07:35:00.744877Z","steps":["trace[510640104] 'agreement among raft nodes before linearized reading' (duration: 1.315371621s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:00.744911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:34:59.4294Z","time spent":"1.315505852s","remote":"127.0.0.1:55216","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-03-25T07:35:01.185557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.087258ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-03-25T07:35:01.185631Z","caller":"traceutil/trace.go:171","msg":"trace[1579611264] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"439.696859ms","start":"2026-03-25T07:35:00.74592Z","end":"2026-03-25T07:35:01.185617Z","steps":["trace[1579611264] 'read index received' (duration: 319.51958ms)","trace[1579611264] 'applied index is now lower than readState.Index' (duration: 120.176629ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:01.185683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.75408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:35:01.18572Z","caller":"traceutil/trace.go:171","msg":"trace[1538104031] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:519; }","duration":"439.797111ms","start":"2026-03-25T07:35:00.745916Z","end":"2026-03-25T07:35:01.185713Z","steps":["trace[1538104031] 'agreement among raft nodes before linearized reading' (duration: 439.73378ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:01.185677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:00.745852Z","time spent":"439.815621ms","remote":"127.0.0.1:55014","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-25T07:35:01.185746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:00.745879Z","time spent":"439.859811ms","remote":"127.0.0.1:54990","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-03-25T07:35:01.252535Z","caller":"traceutil/trace.go:171","msg":"trace[784846456] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"504.762461ms","start":"2026-03-25T07:35:00.747711Z","end":"2026-03-25T07:35:01.252474Z","steps":["trace[784846456] 'process raft request' (duration: 504.296026ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:01.252733Z","caller":"traceutil/trace.go:171","msg":"trace[19368754] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"204.155197ms","start":"2026-03-25T07:35:01.048554Z","end":"2026-03-25T07:35:01.25271Z","steps":["trace[19368754] 'process raft request' (duration: 204.070476ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:01.252776Z","caller":"traceutil/trace.go:171","msg":"trace[1045902504] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"503.161364ms","start":"2026-03-25T07:35:00.74957Z","end":"2026-03-25T07:35:01.252731Z","steps":["trace[1045902504] 'process raft request' (duration: 502.87045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:01.252835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:00.747701Z","time spent":"504.993503ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:35:01.252567Z","caller":"traceutil/trace.go:171","msg":"trace[1028392345] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"503.829591ms","start":"2026-03-25T07:35:00.748673Z","end":"2026-03-25T07:35:01.252503Z","steps":["trace[1028392345] 'process raft request' (duration: 503.607059ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:01.253053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:00.749558Z","time spent":"503.350327ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:35:01.253269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:00.748649Z","time spent":"504.423418ms","remote":"127.0.0.1:55216","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-03-25T07:35:01.449502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.465763ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:35:01.449639Z","caller":"traceutil/trace.go:171","msg":"trace[25894105] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:540; }","duration":"255.523736ms","start":"2026-03-25T07:35:01.194075Z","end":"2026-03-25T07:35:01.449599Z","steps":["trace[25894105] 'read index received' (duration: 58.034987ms)","trace[25894105] 'applied index is now lower than readState.Index' (duration: 197.487629ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:01.449859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.790629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:35:01.449886Z","caller":"traceutil/trace.go:171","msg":"trace[429528742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"255.86748ms","start":"2026-03-25T07:35:01.194011Z","end":"2026-03-25T07:35:01.449878Z","steps":["trace[429528742] 'agreement among raft nodes before linearized reading' (duration: 255.654548ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:01.450206Z","caller":"traceutil/trace.go:171","msg":"trace[42029148] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"262.428009ms","start":"2026-03-25T07:35:01.187267Z","end":"2026-03-25T07:35:01.449695Z","steps":["trace[42029148] 'process raft request' (duration: 138.672523ms)","trace[42029148] 'compare' (duration: 123.342422ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:05.434839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198407028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5sn9z\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-03-25T07:35:05.434914Z","caller":"traceutil/trace.go:171","msg":"trace[1715525961] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5sn9z; range_end:; response_count:1; response_revision:531; }","duration":"1.19851866s","start":"2026-03-25T07:35:04.236381Z","end":"2026-03-25T07:35:05.4349Z","steps":["trace[1715525961] 'range keys from in-memory index tree' (duration: 1.198278618s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:05.434944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:04.236361Z","time spent":"1.19857619s","remote":"127.0.0.1:55158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-5sn9z\" "} {"level":"warn","ts":"2026-03-25T07:35:05.434841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.060782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:35:05.435086Z","caller":"traceutil/trace.go:171","msg":"trace[1701118710] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"721.126572ms","start":"2026-03-25T07:35:04.71395Z","end":"2026-03-25T07:35:05.435077Z","steps":["trace[1701118710] 'read index received' (duration: 499.711187ms)","trace[1701118710] 'applied index is now lower than readState.Index' (duration: 221.414645ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:35:05.435191Z","caller":"traceutil/trace.go:171","msg":"trace[50940586] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"869.840424ms","start":"2026-03-25T07:35:04.56534Z","end":"2026-03-25T07:35:05.435181Z","steps":["trace[50940586] 'process raft request' (duration: 648.387997ms)","trace[50940586] 'compare' (duration: 220.348335ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:05.435248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:04.565317Z","time spent":"869.894224ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:35:05.435378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.444226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:35:05.435407Z","caller":"traceutil/trace.go:171","msg":"trace[577356142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"721.517157ms","start":"2026-03-25T07:35:04.713883Z","end":"2026-03-25T07:35:05.4354Z","steps":["trace[577356142] 'agreement among raft nodes before linearized reading' (duration: 721.468027ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:05.435427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:04.713821Z","time spent":"721.599567ms","remote":"127.0.0.1:54974","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-03-25T07:35:05.435571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.190779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-25T07:35:05.435601Z","caller":"traceutil/trace.go:171","msg":"trace[139115245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:532; }","duration":"662.254919ms","start":"2026-03-25T07:35:04.773339Z","end":"2026-03-25T07:35:05.435594Z","steps":["trace[139115245] 'agreement among raft nodes before linearized reading' (duration: 662.183788ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:05.435638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:04.773321Z","time spent":"662.310929ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-25T07:35:05.4357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.338823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-25T07:35:05.435732Z","caller":"traceutil/trace.go:171","msg":"trace[960706922] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"119.427283ms","start":"2026-03-25T07:35:05.316295Z","end":"2026-03-25T07:35:05.435723Z","steps":["trace[960706922] 'agreement among raft nodes before linearized reading' (duration: 119.342112ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:05.435744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.973826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-25T07:35:05.435763Z","caller":"traceutil/trace.go:171","msg":"trace[1757915911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:532; }","duration":"160.042217ms","start":"2026-03-25T07:35:05.275715Z","end":"2026-03-25T07:35:05.435757Z","steps":["trace[1757915911] 'agreement among raft nodes before linearized reading' (duration: 159.987076ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:05.436037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.494234ms","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-03-25T07:35:05.436126Z","caller":"traceutil/trace.go:171","msg":"trace[1309401550] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"119.617945ms","start":"2026-03-25T07:35:05.316485Z","end":"2026-03-25T07:35:05.436103Z","steps":["trace[1309401550] 'agreement among raft nodes before linearized reading' (duration: 119.316212ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:06.215373Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633212,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:35:06.716312Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633212,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:35:07.012413Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.571766011s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T07:35:07.088979Z","caller":"traceutil/trace.go:171","msg":"trace[503430635] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.648466547s","start":"2026-03-25T07:35:05.440483Z","end":"2026-03-25T07:35:07.088949Z","steps":["trace[503430635] 'process raft request' (duration: 1.572173016s)","trace[503430635] 'compare' (duration: 76.12652ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:07.089138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:05.44045Z","time spent":"1.648611478s","remote":"127.0.0.1:55216","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-03-25T07:35:07.252958Z","caller":"traceutil/trace.go:171","msg":"trace[788242420] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.811269976s","start":"2026-03-25T07:35:05.441667Z","end":"2026-03-25T07:35:07.252937Z","steps":["trace[788242420] 'process raft request' (duration: 1.811057874s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:07.253089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:05.441659Z","time spent":"1.811367627s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:35:07.253111Z","caller":"traceutil/trace.go:171","msg":"trace[1706598555] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.80104759s","start":"2026-03-25T07:35:05.452021Z","end":"2026-03-25T07:35:07.253069Z","steps":["trace[1706598555] 'process raft request' (duration: 1.800775557s)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:07.253111Z","caller":"traceutil/trace.go:171","msg":"trace[630208183] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.811368117s","start":"2026-03-25T07:35:05.441693Z","end":"2026-03-25T07:35:07.253061Z","steps":["trace[630208183] 'process raft request' (duration: 1.811072664s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:07.253246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:05.45198Z","time spent":"1.801198101s","remote":"127.0.0.1:55158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:35:07.253374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:05.441651Z","time spent":"1.81158877s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:35:07.253173Z","caller":"traceutil/trace.go:171","msg":"trace[2083898049] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.811561438s","start":"2026-03-25T07:35:05.441592Z","end":"2026-03-25T07:35:07.253154Z","steps":["trace[2083898049] 'process raft request' (duration: 1.791784193s)","trace[2083898049] 'compare' (duration: 19.25165ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:07.253528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:05.441569Z","time spent":"1.811927134s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:35:07.715004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00023548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-25T07:35:07.715087Z","caller":"traceutil/trace.go:171","msg":"trace[662057198] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000371861s","start":"2026-03-25T07:35:05.714702Z","end":"2026-03-25T07:35:07.715074Z","steps":["trace[662057198] 'agreement among raft nodes before linearized reading' (duration: 2.00026931s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:07.71512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:05.714682Z","time spent":"2.000430242s","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/25 07:35:07 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-25T07:35:07.835328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.473027ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-25T07:35:07.835442Z","caller":"traceutil/trace.go:171","msg":"trace[1249926401] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:553; }","duration":"2.12067076s","start":"2026-03-25T07:35:05.71475Z","end":"2026-03-25T07:35:07.835421Z","steps":["trace[1249926401] 'read index received' (duration: 1.29790697s)","trace[1249926401] 'applied index is now lower than readState.Index' (duration: 822.76201ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:07.836087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.756165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:35:07.83617Z","caller":"traceutil/trace.go:171","msg":"trace[746754582] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"120.877686ms","start":"2026-03-25T07:35:07.715273Z","end":"2026-03-25T07:35:07.836151Z","steps":["trace[746754582] 'agreement among raft nodes before linearized reading' (duration: 120.741604ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:08.344006Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633220,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:35:08.902688Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633220,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:35:09.4039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633220,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:35:09.843813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000657679s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-25T07:35:09.843924Z","caller":"traceutil/trace.go:171","msg":"trace[142016006] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00084113s","start":"2026-03-25T07:35:07.843058Z","end":"2026-03-25T07:35:09.843899Z","steps":["trace[142016006] 'agreement among raft nodes before linearized reading' (duration: 2.000699129s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:09.844007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:07.843034Z","time spent":"2.000956932s","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/25 07:35:09 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-25T07:35:09.904382Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633220,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:35:09.91072Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.070216269s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T07:35:09.911441Z","caller":"traceutil/trace.go:171","msg":"trace[942057110] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"2.068290629s","start":"2026-03-25T07:35:07.843118Z","end":"2026-03-25T07:35:09.911408Z","steps":["trace[942057110] 'read index received' (duration: 2.067835854s)","trace[942057110] 'applied index is now lower than readState.Index' (duration: 453.635µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:09.912067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.92326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-25T07:35:09.912139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.130462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-25T07:35:09.912227Z","caller":"traceutil/trace.go:171","msg":"trace[1758064078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:538; }","duration":"654.262573ms","start":"2026-03-25T07:35:09.257937Z","end":"2026-03-25T07:35:09.9122Z","steps":["trace[1758064078] 'agreement among raft nodes before linearized reading' (duration: 653.864519ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:09.912245Z","caller":"traceutil/trace.go:171","msg":"trace[1076964663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"654.093531ms","start":"2026-03-25T07:35:09.258031Z","end":"2026-03-25T07:35:09.912125Z","steps":["trace[1076964663] 'agreement among raft nodes before linearized reading' (duration: 653.744347ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:09.912303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:09.257928Z","time spent":"654.358554ms","remote":"127.0.0.1:55216","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-03-25T07:35:09.912356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:09.258Z","time spent":"654.340604ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-25T07:35:09.912398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.545836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-25T07:35:09.91252Z","caller":"traceutil/trace.go:171","msg":"trace[269797374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"654.778798ms","start":"2026-03-25T07:35:09.257714Z","end":"2026-03-25T07:35:09.912493Z","steps":["trace[269797374] 'agreement among raft nodes before linearized reading' (duration: 654.393244ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:09.912576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:09.257686Z","time spent":"654.878649ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-25T07:35:09.91285Z","caller":"traceutil/trace.go:171","msg":"trace[1335749661] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"2.071280231s","start":"2026-03-25T07:35:07.84042Z","end":"2026-03-25T07:35:09.9117Z","steps":["trace[1335749661] 'process raft request' (duration: 2.070710224s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:09.912968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.091168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-25T07:35:09.913039Z","caller":"traceutil/trace.go:171","msg":"trace[1588886881] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:538; }","duration":"819.340141ms","start":"2026-03-25T07:35:09.093687Z","end":"2026-03-25T07:35:09.913027Z","steps":["trace[1588886881] 'agreement among raft nodes before linearized reading' (duration: 818.063498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:09.913073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:09.093668Z","time spent":"819.391922ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T07:35:09.912989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:07.84039Z","time spent":"2.072508373s","remote":"127.0.0.1:55064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:35:10.423961Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:35:10.924603Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:35:11.425661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:35:11.923252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999951733s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-25T07:35:11.923388Z","caller":"traceutil/trace.go:171","msg":"trace[329521594] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000136404s","start":"2026-03-25T07:35:09.923228Z","end":"2026-03-25T07:35:11.923365Z","steps":["trace[329521594] 'agreement among raft nodes before linearized reading' (duration: 1.999974473s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:11.92348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:09.923218Z","time spent":"2.000246295s","remote":"127.0.0.1:54974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/25 07:35:11 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-25T07:35:11.925805Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:35:12.426161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:35:12.927025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:35:13.428197Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14933264717009633223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:35:13.70526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.534774414s","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-03-25T07:35:13.705375Z","caller":"traceutil/trace.go:171","msg":"trace[1992895343] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:538; }","duration":"2.534905065s","start":"2026-03-25T07:35:11.170453Z","end":"2026-03-25T07:35:13.705358Z","steps":["trace[1992895343] 'range keys from in-memory index tree' (duration: 2.534759933s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:13.7157Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.79607559s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T07:35:13.716496Z","caller":"traceutil/trace.go:171","msg":"trace[169400562] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"3.79701042s","start":"2026-03-25T07:35:09.919461Z","end":"2026-03-25T07:35:13.716472Z","steps":["trace[169400562] 'process raft request' (duration: 3.796835219s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:13.716643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:09.919434Z","time spent":"3.797135011s","remote":"127.0.0.1:55064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:35:13.924227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00031623s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-25T07:35:13.92436Z","caller":"traceutil/trace.go:171","msg":"trace[1198948952] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000482231s","start":"2026-03-25T07:35:11.92382Z","end":"2026-03-25T07:35:13.924303Z","steps":["trace[1198948952] 'agreement among raft nodes before linearized reading' (duration: 2.000355829s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:13.92442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:11.923795Z","time spent":"2.000616352s","remote":"127.0.0.1:54990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/25 07:35:13 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-03-25T07:35:13.949417Z","caller":"traceutil/trace.go:171","msg":"trace[1403405783] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"4.029553974s","start":"2026-03-25T07:35:09.919842Z","end":"2026-03-25T07:35:13.949396Z","steps":["trace[1403405783] 'process raft request' (duration: 4.024238649s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:13.949569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:09.919824Z","time spent":"4.029668125s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:35:13.949941Z","caller":"traceutil/trace.go:171","msg":"trace[1129231114] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"4.029976648s","start":"2026-03-25T07:35:09.919928Z","end":"2026-03-25T07:35:13.949904Z","steps":["trace[1129231114] 'process raft request' (duration: 4.029430773s)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:13.950085Z","caller":"traceutil/trace.go:171","msg":"trace[501496040] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"4.029703164s","start":"2026-03-25T07:35:09.920374Z","end":"2026-03-25T07:35:13.950077Z","steps":["trace[501496040] 'process raft request' (duration: 4.029580603s)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:13.950052Z","caller":"traceutil/trace.go:171","msg":"trace[960979310] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"4.029788055s","start":"2026-03-25T07:35:09.920229Z","end":"2026-03-25T07:35:13.950017Z","steps":["trace[960979310] 'process raft request' (duration: 4.029575013s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:13.95011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:09.919567Z","time spent":"4.030457414s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:35:13.950132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:09.920346Z","time spent":"4.029758645s","remote":"127.0.0.1:55216","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-03-25T07:35:13.950261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:09.920218Z","time spent":"4.029931027s","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:35:13.950433Z","caller":"traceutil/trace.go:171","msg":"trace[284414612] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"2.415657796s","start":"2026-03-25T07:35:11.534739Z","end":"2026-03-25T07:35:13.950397Z","steps":["trace[284414612] 'process raft request' (duration: 2.415303303s)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:13.950469Z","caller":"traceutil/trace.go:171","msg":"trace[8404827] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:560; }","duration":"4.027174399s","start":"2026-03-25T07:35:09.923264Z","end":"2026-03-25T07:35:13.950439Z","steps":["trace[8404827] 'read index received' (duration: 3.792958078s)","trace[8404827] 'applied index is now lower than readState.Index' (duration: 234.215021ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:13.950577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:11.534714Z","time spent":"2.415784517s","remote":"127.0.0.1:55216","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-03-25T07:35:13.950685Z","caller":"traceutil/trace.go:171","msg":"trace[1402552132] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"639.820887ms","start":"2026-03-25T07:35:13.310839Z","end":"2026-03-25T07:35:13.95066Z","steps":["trace[1402552132] 'process raft request' (duration: 639.480073ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:13.950772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.935844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T07:35:13.9508Z","caller":"traceutil/trace.go:171","msg":"trace[979121755] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:545; }","duration":"321.001994ms","start":"2026-03-25T07:35:13.62979Z","end":"2026-03-25T07:35:13.950792Z","steps":["trace[979121755] 'agreement among raft nodes before linearized reading' (duration: 320.944364ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:13.950822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:13.629771Z","time spent":"321.046494ms","remote":"127.0.0.1:55224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":2,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-03-25T07:35:13.950818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.705766704s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.3\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-03-25T07:35:13.95085Z","caller":"traceutil/trace.go:171","msg":"trace[1463003876] range","detail":"{range_begin:/registry/masterleases/199.204.45.3; range_end:; response_count:1; response_revision:545; }","duration":"3.705846626s","start":"2026-03-25T07:35:10.244994Z","end":"2026-03-25T07:35:13.950841Z","steps":["trace[1463003876] 'agreement among raft nodes before linearized reading' (duration: 3.705737925s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:13.950834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:13.310823Z","time spent":"639.909048ms","remote":"127.0.0.1:55146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4743,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:35:13.950873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:10.244963Z","time spent":"3.705904467s","remote":"127.0.0.1:55014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.3\" "} {"level":"warn","ts":"2026-03-25T07:35:13.950992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.624728176s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-25T07:35:13.951001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.511343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18a0055324964fed\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-03-25T07:35:13.951017Z","caller":"traceutil/trace.go:171","msg":"trace[1070221384] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:545; }","duration":"1.624796967s","start":"2026-03-25T07:35:12.326213Z","end":"2026-03-25T07:35:13.95101Z","steps":["trace[1070221384] 'agreement among raft nodes before linearized reading' (duration: 1.624753977s)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:13.951025Z","caller":"traceutil/trace.go:171","msg":"trace[2118939417] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18a0055324964fed; range_end:; response_count:1; response_revision:545; }","duration":"230.560523ms","start":"2026-03-25T07:35:13.720455Z","end":"2026-03-25T07:35:13.951015Z","steps":["trace[2118939417] 'agreement among raft nodes before linearized reading' (duration: 230.462522ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:13.951039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:12.326193Z","time spent":"1.624838688s","remote":"127.0.0.1:55314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":30,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"warn","ts":"2026-03-25T07:35:13.951158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.503877908s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T07:35:13.951176Z","caller":"traceutil/trace.go:171","msg":"trace[1265332164] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:545; }","duration":"3.503933118s","start":"2026-03-25T07:35:10.447237Z","end":"2026-03-25T07:35:13.95117Z","steps":["trace[1265332164] 'agreement among raft nodes before linearized reading' (duration: 3.503889987s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:13.951194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:10.447214Z","time spent":"3.503975008s","remote":"127.0.0.1:55268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":13,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-03-25T07:35:13.951335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.544753611s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T07:35:13.951367Z","caller":"traceutil/trace.go:171","msg":"trace[1038572956] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:545; }","duration":"3.544817102s","start":"2026-03-25T07:35:10.406542Z","end":"2026-03-25T07:35:13.951359Z","steps":["trace[1038572956] 'agreement among raft nodes before linearized reading' (duration: 3.544748681s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:13.95139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:10.406526Z","time spent":"3.544856672s","remote":"127.0.0.1:55144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":2,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"info","ts":"2026-03-25T07:35:14.12908Z","caller":"traceutil/trace.go:171","msg":"trace[1768768917] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"172.309113ms","start":"2026-03-25T07:35:13.956668Z","end":"2026-03-25T07:35:14.128977Z","steps":["trace[1768768917] 'read index received' (duration: 153.400959ms)","trace[1768768917] 'applied index is now lower than readState.Index' (duration: 18.905044ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:14.129375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.632676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:35:14.129557Z","caller":"traceutil/trace.go:171","msg":"trace[1045284764] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"172.945259ms","start":"2026-03-25T07:35:13.956594Z","end":"2026-03-25T07:35:14.129539Z","steps":["trace[1045284764] 'agreement among raft nodes before linearized reading' (duration: 172.646326ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:14.184223Z","caller":"traceutil/trace.go:171","msg":"trace[1146348314] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"216.261695ms","start":"2026-03-25T07:35:13.967929Z","end":"2026-03-25T07:35:14.184191Z","steps":["trace[1146348314] 'process raft request' (duration: 214.201734ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:14.304379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.533436ms","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":"warn","ts":"2026-03-25T07:35:14.304405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.684021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7285"} {"level":"info","ts":"2026-03-25T07:35:14.304482Z","caller":"traceutil/trace.go:171","msg":"trace[471051845] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:548; }","duration":"106.678447ms","start":"2026-03-25T07:35:14.197779Z","end":"2026-03-25T07:35:14.304458Z","steps":["trace[471051845] 'agreement among raft nodes before linearized reading' (duration: 30.494133ms)","trace[471051845] 'count revisions from in-memory index tree' (duration: 75.986852ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:35:14.30456Z","caller":"traceutil/trace.go:171","msg":"trace[531041335] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:548; }","duration":"115.894302ms","start":"2026-03-25T07:35:14.188625Z","end":"2026-03-25T07:35:14.304519Z","steps":["trace[531041335] 'agreement among raft nodes before linearized reading' (duration: 39.709768ms)","trace[531041335] 'range keys from in-memory index tree' (duration: 75.907552ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:35:15.479913Z","caller":"traceutil/trace.go:171","msg":"trace[1823460221] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"207.688542ms","start":"2026-03-25T07:35:15.272201Z","end":"2026-03-25T07:35:15.479889Z","steps":["trace[1823460221] 'process raft request' (duration: 207.559161ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:15.615256Z","caller":"traceutil/trace.go:171","msg":"trace[1775129872] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"332.128391ms","start":"2026-03-25T07:35:15.283099Z","end":"2026-03-25T07:35:15.615227Z","steps":["trace[1775129872] 'process raft request' (duration: 331.873548ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:15.615443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:15.283081Z","time spent":"332.260182ms","remote":"127.0.0.1:55158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17224,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:35:15.871374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.725228ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:35:15.871627Z","caller":"traceutil/trace.go:171","msg":"trace[1630359972] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"157.443906ms","start":"2026-03-25T07:35:15.714132Z","end":"2026-03-25T07:35:15.871576Z","steps":["trace[1630359972] 'read index received' (duration: 39.293593ms)","trace[1630359972] 'applied index is now lower than readState.Index' (duration: 118.148783ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:15.871738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.584177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:35:15.871768Z","caller":"traceutil/trace.go:171","msg":"trace[932005413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"157.676099ms","start":"2026-03-25T07:35:15.714079Z","end":"2026-03-25T07:35:15.871756Z","steps":["trace[932005413] 'agreement among raft nodes before linearized reading' (duration: 157.603628ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:15.871743Z","caller":"traceutil/trace.go:171","msg":"trace[1517864548] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"304.248294ms","start":"2026-03-25T07:35:15.567443Z","end":"2026-03-25T07:35:15.871691Z","steps":["trace[1517864548] 'process raft request' (duration: 186.053281ms)","trace[1517864548] 'compare' (duration: 117.487056ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:15.871989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:15.567421Z","time spent":"304.452997ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:35:16.417332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.627502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:35:16.417511Z","caller":"traceutil/trace.go:171","msg":"trace[1896695735] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:577; }","duration":"439.74293ms","start":"2026-03-25T07:35:15.977752Z","end":"2026-03-25T07:35:16.417495Z","steps":["trace[1896695735] 'read index received' (duration: 102.924787ms)","trace[1896695735] 'applied index is now lower than readState.Index' (duration: 336.817243ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:35:16.417527Z","caller":"traceutil/trace.go:171","msg":"trace[1304027583] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"456.168698ms","start":"2026-03-25T07:35:15.961333Z","end":"2026-03-25T07:35:16.417501Z","steps":["trace[1304027583] 'process raft request' (duration: 119.291634ms)","trace[1304027583] 'compare' (duration: 336.503441ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:16.417633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:15.961303Z","time spent":"456.27342ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:35:16.417726Z","caller":"traceutil/trace.go:171","msg":"trace[264014462] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"455.851686ms","start":"2026-03-25T07:35:15.961734Z","end":"2026-03-25T07:35:16.417585Z","steps":["trace[264014462] 'process raft request' (duration: 455.707735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:16.41786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.068853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-25T07:35:16.417955Z","caller":"traceutil/trace.go:171","msg":"trace[1693721874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:555; }","duration":"440.230315ms","start":"2026-03-25T07:35:15.977703Z","end":"2026-03-25T07:35:16.417933Z","steps":["trace[1693721874] 'agreement among raft nodes before linearized reading' (duration: 439.909491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:16.417991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:15.961713Z","time spent":"456.043788ms","remote":"127.0.0.1:55216","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-03-25T07:35:16.418009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:15.977685Z","time spent":"440.314565ms","remote":"127.0.0.1:55216","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-03-25T07:35:17.218446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.743998ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-25T07:35:17.21868Z","caller":"traceutil/trace.go:171","msg":"trace[2114621814] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"801.102342ms","start":"2026-03-25T07:35:16.417539Z","end":"2026-03-25T07:35:17.218641Z","steps":["trace[2114621814] 'read index received' (duration: 81.209933ms)","trace[2114621814] 'applied index is now lower than readState.Index' (duration: 719.891399ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:17.218804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.083160755s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T07:35:17.218834Z","caller":"traceutil/trace.go:171","msg":"trace[189567544] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:555; }","duration":"1.083254966s","start":"2026-03-25T07:35:16.135568Z","end":"2026-03-25T07:35:17.218823Z","steps":["trace[189567544] 'agreement among raft nodes before linearized reading' (duration: 1.083193955s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:17.218871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:16.135543Z","time spent":"1.083318176s","remote":"127.0.0.1:55394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-03-25T07:35:17.218964Z","caller":"traceutil/trace.go:171","msg":"trace[152999645] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"794.479135ms","start":"2026-03-25T07:35:16.424439Z","end":"2026-03-25T07:35:17.218918Z","steps":["trace[152999645] 'process raft request' (duration: 794.115211ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:17.2192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:16.424409Z","time spent":"794.669626ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:35:17.512187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.375984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:35:17.512279Z","caller":"traceutil/trace.go:171","msg":"trace[568830333] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:556; }","duration":"575.519505ms","start":"2026-03-25T07:35:16.936736Z","end":"2026-03-25T07:35:17.512255Z","steps":["trace[568830333] 'agreement among raft nodes before linearized reading' (duration: 282.204451ms)","trace[568830333] 'count revisions from in-memory index tree' (duration: 293.180243ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:17.512327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:16.936716Z","time spent":"575.599456ms","remote":"127.0.0.1:55066","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-03-25T07:35:17.512473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"793.50796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:35:17.512506Z","caller":"traceutil/trace.go:171","msg":"trace[186912730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"793.568481ms","start":"2026-03-25T07:35:16.718928Z","end":"2026-03-25T07:35:17.512497Z","steps":["trace[186912730] 'agreement among raft nodes before linearized reading' (duration: 499.987664ms)","trace[186912730] 'range keys from in-memory index tree' (duration: 293.517246ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:17.512523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:16.718911Z","time spent":"793.608132ms","remote":"127.0.0.1:54974","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-03-25T07:35:17.512579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.534486849s","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-03-25T07:35:17.512745Z","caller":"traceutil/trace.go:171","msg":"trace[313139231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"1.534605731s","start":"2026-03-25T07:35:15.978041Z","end":"2026-03-25T07:35:17.512647Z","steps":["trace[313139231] 'agreement among raft nodes before linearized reading' (duration: 1.240801382s)","trace[313139231] 'range keys from in-memory index tree' (duration: 293.567637ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:17.513656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:15.97803Z","time spent":"1.535608151s","remote":"127.0.0.1:55216","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-03-25T07:35:18.793237Z","caller":"traceutil/trace.go:171","msg":"trace[1008192224] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"365.900214ms","start":"2026-03-25T07:35:18.427314Z","end":"2026-03-25T07:35:18.793214Z","steps":["trace[1008192224] 'process raft request' (duration: 365.762933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:18.793375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:18.42729Z","time spent":"366.015635ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:35:18.843807Z","caller":"traceutil/trace.go:171","msg":"trace[149672169] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"170.947119ms","start":"2026-03-25T07:35:18.672835Z","end":"2026-03-25T07:35:18.843782Z","steps":["trace[149672169] 'read index received' (duration: 120.357292ms)","trace[149672169] 'applied index is now lower than readState.Index' (duration: 50.588197ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:35:18.843871Z","caller":"traceutil/trace.go:171","msg":"trace[669215415] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"415.116577ms","start":"2026-03-25T07:35:18.428722Z","end":"2026-03-25T07:35:18.843838Z","steps":["trace[669215415] 'process raft request' (duration: 414.940495ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:18.843971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.122331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:35:18.844007Z","caller":"traceutil/trace.go:171","msg":"trace[1164364058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"171.214551ms","start":"2026-03-25T07:35:18.672782Z","end":"2026-03-25T07:35:18.843996Z","steps":["trace[1164364058] 'agreement among raft nodes before linearized reading' (duration: 171.13826ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:18.844098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.777356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:35:18.844164Z","caller":"traceutil/trace.go:171","msg":"trace[1192489739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"127.873338ms","start":"2026-03-25T07:35:18.716275Z","end":"2026-03-25T07:35:18.844149Z","steps":["trace[1192489739] 'agreement among raft nodes before linearized reading' (duration: 127.775377ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:18.844987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:18.428696Z","time spent":"415.243058ms","remote":"127.0.0.1:55216","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-03-25T07:35:19.71983Z","caller":"traceutil/trace.go:171","msg":"trace[1353250096] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"129.239061ms","start":"2026-03-25T07:35:19.590566Z","end":"2026-03-25T07:35:19.719805Z","steps":["trace[1353250096] 'read index received' (duration: 128.987218ms)","trace[1353250096] 'applied index is now lower than readState.Index' (duration: 250.683µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:35:19.719967Z","caller":"traceutil/trace.go:171","msg":"trace[610954176] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"492.703694ms","start":"2026-03-25T07:35:19.227213Z","end":"2026-03-25T07:35:19.719916Z","steps":["trace[610954176] 'process raft request' (duration: 492.444621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:19.720064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.486663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-25T07:35:19.720114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:19.227177Z","time spent":"492.871066ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:35:19.720119Z","caller":"traceutil/trace.go:171","msg":"trace[1420054102] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:560; }","duration":"129.583524ms","start":"2026-03-25T07:35:19.590523Z","end":"2026-03-25T07:35:19.720106Z","steps":["trace[1420054102] 'agreement among raft nodes before linearized reading' (duration: 129.416392ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:20.250248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.697724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:35:20.250582Z","caller":"traceutil/trace.go:171","msg":"trace[108053132] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"361.757563ms","start":"2026-03-25T07:35:19.888812Z","end":"2026-03-25T07:35:20.250569Z","steps":["trace[108053132] 'read index received' (duration: 128.553994ms)","trace[108053132] 'applied index is now lower than readState.Index' (duration: 233.202459ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:35:20.250608Z","caller":"traceutil/trace.go:171","msg":"trace[1773235254] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"526.353845ms","start":"2026-03-25T07:35:19.724225Z","end":"2026-03-25T07:35:20.250579Z","steps":["trace[1773235254] 'process raft request' (duration: 293.263937ms)","trace[1773235254] 'compare' (duration: 232.586293ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:20.250688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.872184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T07:35:20.250727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:19.724209Z","time spent":"526.461956ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:35:20.250757Z","caller":"traceutil/trace.go:171","msg":"trace[866017450] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:561; }","duration":"361.991575ms","start":"2026-03-25T07:35:19.888746Z","end":"2026-03-25T07:35:20.250738Z","steps":["trace[866017450] 'agreement among raft nodes before linearized reading' (duration: 361.896555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:20.250937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:19.888721Z","time spent":"362.198067ms","remote":"127.0.0.1:55142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-03-25T07:35:20.518029Z","caller":"traceutil/trace.go:171","msg":"trace[2112627504] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"170.327228ms","start":"2026-03-25T07:35:20.347677Z","end":"2026-03-25T07:35:20.518004Z","steps":["trace[2112627504] 'process raft request' (duration: 90.877587ms)","trace[2112627504] 'compare' (duration: 79.204809ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:21.280085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.715768ms","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-03-25T07:35:21.280294Z","caller":"traceutil/trace.go:171","msg":"trace[1755724870] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:564; }","duration":"108.875309ms","start":"2026-03-25T07:35:21.171325Z","end":"2026-03-25T07:35:21.280201Z","steps":["trace[1755724870] 'range keys from in-memory index tree' (duration: 108.694548ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:22.202021Z","caller":"traceutil/trace.go:171","msg":"trace[622706923] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"469.398492ms","start":"2026-03-25T07:35:21.732588Z","end":"2026-03-25T07:35:22.201987Z","steps":["trace[622706923] 'process raft request' (duration: 469.274291ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:22.2022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:21.732567Z","time spent":"469.553934ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:35:22.513215Z","caller":"traceutil/trace.go:171","msg":"trace[1855856866] linearizableReadLoop","detail":"{readStateIndex:592; appliedIndex:591; }","duration":"258.613872ms","start":"2026-03-25T07:35:22.254578Z","end":"2026-03-25T07:35:22.513191Z","steps":["trace[1855856866] 'read index received' (duration: 194.010105ms)","trace[1855856866] 'applied index is now lower than readState.Index' (duration: 64.602627ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:22.513418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.826434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-25T07:35:22.513451Z","caller":"traceutil/trace.go:171","msg":"trace[1623938438] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:566; }","duration":"258.931956ms","start":"2026-03-25T07:35:22.254508Z","end":"2026-03-25T07:35:22.51344Z","steps":["trace[1623938438] 'agreement among raft nodes before linearized reading' (duration: 258.767104ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:22.513626Z","caller":"traceutil/trace.go:171","msg":"trace[772764117] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"638.993376ms","start":"2026-03-25T07:35:21.874621Z","end":"2026-03-25T07:35:22.513614Z","steps":["trace[772764117] 'process raft request' (duration: 573.956825ms)","trace[772764117] 'compare' (duration: 64.191303ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:22.513692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:21.874603Z","time spent":"639.047117ms","remote":"127.0.0.1:55064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":700,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:35:24.487904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.663392ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:35:24.488015Z","caller":"traceutil/trace.go:171","msg":"trace[218004203] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"771.4669ms","start":"2026-03-25T07:35:23.716524Z","end":"2026-03-25T07:35:24.487991Z","steps":["trace[218004203] 'read index received' (duration: 122.301µs)","trace[218004203] 'applied index is now lower than readState.Index' (duration: 771.342668ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:24.488246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.715952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:35:24.488302Z","caller":"traceutil/trace.go:171","msg":"trace[299436183] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"771.807172ms","start":"2026-03-25T07:35:23.716479Z","end":"2026-03-25T07:35:24.488287Z","steps":["trace[299436183] 'agreement among raft nodes before linearized reading' (duration: 771.60053ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:24.488285Z","caller":"traceutil/trace.go:171","msg":"trace[1325616799] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"788.123068ms","start":"2026-03-25T07:35:23.699919Z","end":"2026-03-25T07:35:24.488042Z","steps":["trace[1325616799] 'process raft request' (duration: 11.257144ms)","trace[1325616799] 'compare' (duration: 776.275318ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:35:24.488347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:23.716462Z","time spent":"771.873804ms","remote":"127.0.0.1:54974","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-03-25T07:35:24.488612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:23.699899Z","time spent":"788.621063ms","remote":"127.0.0.1:55064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":763,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:35:24.496849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.41066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-25T07:35:24.496984Z","caller":"traceutil/trace.go:171","msg":"trace[1152349420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"288.588511ms","start":"2026-03-25T07:35:24.208377Z","end":"2026-03-25T07:35:24.496965Z","steps":["trace[1152349420] 'agreement among raft nodes before linearized reading' (duration: 288.316669ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:35:24.497059Z","caller":"traceutil/trace.go:171","msg":"trace[2109930128] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"449.93235ms","start":"2026-03-25T07:35:24.047107Z","end":"2026-03-25T07:35:24.497039Z","steps":["trace[2109930128] 'process raft request' (duration: 449.426065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:35:24.497297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:35:24.047081Z","time spent":"450.107452ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:35:34.908856Z","caller":"traceutil/trace.go:171","msg":"trace[582087172] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"167.677275ms","start":"2026-03-25T07:35:34.741159Z","end":"2026-03-25T07:35:34.908836Z","steps":["trace[582087172] 'process raft request' (duration: 167.564304ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:36:05.970496Z","caller":"traceutil/trace.go:171","msg":"trace[1632510934] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"112.839652ms","start":"2026-03-25T07:36:05.857627Z","end":"2026-03-25T07:36:05.970466Z","steps":["trace[1632510934] 'process raft request' (duration: 112.647424ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:36:08.953706Z","caller":"traceutil/trace.go:171","msg":"trace[1716619765] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"184.161088ms","start":"2026-03-25T07:36:08.769504Z","end":"2026-03-25T07:36:08.953665Z","steps":["trace[1716619765] 'process raft request' (duration: 183.936909ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:36:09.547744Z","caller":"traceutil/trace.go:171","msg":"trace[1063805056] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"306.451649ms","start":"2026-03-25T07:36:09.241259Z","end":"2026-03-25T07:36:09.547711Z","steps":["trace[1063805056] 'process raft request' (duration: 306.21659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:09.547959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:09.241244Z","time spent":"306.610386ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:36:11.205594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.559278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-25T07:36:11.20567Z","caller":"traceutil/trace.go:171","msg":"trace[841837016] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:856; }","duration":"147.695543ms","start":"2026-03-25T07:36:11.05796Z","end":"2026-03-25T07:36:11.205656Z","steps":["trace[841837016] 'range keys from in-memory index tree' (duration: 147.4063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:11.206088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.927388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-25T07:36:11.206209Z","caller":"traceutil/trace.go:171","msg":"trace[571073741] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:856; }","duration":"155.357606ms","start":"2026-03-25T07:36:11.050828Z","end":"2026-03-25T07:36:11.206185Z","steps":["trace[571073741] 'range keys from in-memory index tree' (duration: 154.680108ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:36:11.789276Z","caller":"traceutil/trace.go:171","msg":"trace[1433606914] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"233.575249ms","start":"2026-03-25T07:36:11.555644Z","end":"2026-03-25T07:36:11.789219Z","steps":["trace[1433606914] 'process raft request' (duration: 233.226315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:11.8953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.712192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:36:11.895439Z","caller":"traceutil/trace.go:171","msg":"trace[1038840195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:860; }","duration":"103.929771ms","start":"2026-03-25T07:36:11.791472Z","end":"2026-03-25T07:36:11.895402Z","steps":["trace[1038840195] 'range keys from in-memory index tree' (duration: 103.574347ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:36:36.431343Z","caller":"traceutil/trace.go:171","msg":"trace[1564379169] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"516.9041ms","start":"2026-03-25T07:36:35.91441Z","end":"2026-03-25T07:36:36.431314Z","steps":["trace[1564379169] 'process raft request' (duration: 516.715453ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:36.431549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:35.914375Z","time spent":"517.071325ms","remote":"127.0.0.1:55216","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:36:45.647033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.58808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:36:45.647182Z","caller":"traceutil/trace.go:171","msg":"trace[886287639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"930.796208ms","start":"2026-03-25T07:36:44.716359Z","end":"2026-03-25T07:36:45.647155Z","steps":["trace[886287639] 'range keys from in-memory index tree' (duration: 930.401074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:45.647243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:44.71633Z","time spent":"930.900501ms","remote":"127.0.0.1:54974","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-03-25T07:36:45.648531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.061105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-25T07:36:45.648673Z","caller":"traceutil/trace.go:171","msg":"trace[986503559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:987; }","duration":"478.459972ms","start":"2026-03-25T07:36:45.170178Z","end":"2026-03-25T07:36:45.648638Z","steps":["trace[986503559] 'range keys from in-memory index tree' (duration: 476.119893ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:45.648751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:36:45.170147Z","time spent":"478.582786ms","remote":"127.0.0.1:55216","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-03-25T07:36:45.683787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.746431ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-25T07:36:45.684221Z","caller":"traceutil/trace.go:171","msg":"trace[2055278687] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1033; }","duration":"205.279862ms","start":"2026-03-25T07:36:45.478905Z","end":"2026-03-25T07:36:45.684185Z","steps":["trace[2055278687] 'read index received' (duration: 82.056034ms)","trace[2055278687] 'applied index is now lower than readState.Index' (duration: 123.219808ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:36:45.684541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.878029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-25T07:36:45.684638Z","caller":"traceutil/trace.go:171","msg":"trace[1757430534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:988; }","duration":"189.027804ms","start":"2026-03-25T07:36:45.495588Z","end":"2026-03-25T07:36:45.684616Z","steps":["trace[1757430534] 'agreement among raft nodes before linearized reading' (duration: 188.798367ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:45.685075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.974296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-25T07:36:45.685122Z","caller":"traceutil/trace.go:171","msg":"trace[679116226] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:988; }","duration":"206.282466ms","start":"2026-03-25T07:36:45.478829Z","end":"2026-03-25T07:36:45.685111Z","steps":["trace[679116226] 'agreement among raft nodes before linearized reading' (duration: 205.485449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:36:45.685322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.887847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-25T07:36:45.685373Z","caller":"traceutil/trace.go:171","msg":"trace[810817001] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:988; }","duration":"185.96342ms","start":"2026-03-25T07:36:45.499402Z","end":"2026-03-25T07:36:45.685366Z","steps":["trace[810817001] 'agreement among raft nodes before linearized reading' (duration: 185.876546ms)"],"step_count":1}