{"level":"warn","ts":"2026-05-21T02:52:13.712346Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T02:52:13.712438Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.100: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://162.253.55.100:2380","--initial-cluster=instance=https://162.253.55.100:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.100:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.100:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-21T02:52:13.712514Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T02:52:13.712529Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.100:2380"]} {"level":"info","ts":"2026-05-21T02:52:13.712552Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T02:52:13.713348Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.100:2379"]} {"level":"info","ts":"2026-05-21T02:52:13.713441Z","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://162.253.55.100:2380"],"listen-peer-urls":["https://162.253.55.100:2380"],"advertise-client-urls":["https://162.253.55.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.100:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-21T02:52:13.719152Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.471122ms"} {"level":"info","ts":"2026-05-21T02:52:13.728409Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d716dd02ccafa30","cluster-id":"c093465db09ae4fe"} {"level":"info","ts":"2026-05-21T02:52:13.728478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T02:52:13.728499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became follower at term 0"} {"level":"info","ts":"2026-05-21T02:52:13.728509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d716dd02ccafa30 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T02:52:13.728531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became follower at term 1"} {"level":"info","ts":"2026-05-21T02:52:13.728569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 switched to configuration voters=(968676135733492272)"} {"level":"warn","ts":"2026-05-21T02:52:13.745238Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T02:52:13.747224Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T02:52:13.750079Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-21T02:52:13.753829Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d716dd02ccafa30","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T02:52:13.753968Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T02:52:13.754427Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T02:52:13.754458Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T02:52:13.755519Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d716dd02ccafa30","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T02:52:13.758533Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T02:52:13.75867Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.100:2380"} {"level":"info","ts":"2026-05-21T02:52:13.758726Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.100:2380"} {"level":"info","ts":"2026-05-21T02:52:13.758757Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d716dd02ccafa30","initial-advertise-peer-urls":["https://162.253.55.100:2380"],"listen-peer-urls":["https://162.253.55.100:2380"],"advertise-client-urls":["https://162.253.55.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T02:52:13.758815Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T02:52:13.760674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 switched to configuration voters=(968676135733492272)"} {"level":"info","ts":"2026-05-21T02:52:13.760773Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"c093465db09ae4fe","local-member-id":"d716dd02ccafa30","added-peer-id":"d716dd02ccafa30","added-peer-peer-urls":["https://162.253.55.100:2380"]} {"level":"info","ts":"2026-05-21T02:52:13.932696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T02:52:13.932741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T02:52:13.932755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 received MsgPreVoteResp from d716dd02ccafa30 at term 1"} {"level":"info","ts":"2026-05-21T02:52:13.932784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became candidate at term 2"} {"level":"info","ts":"2026-05-21T02:52:13.93279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 received MsgVoteResp from d716dd02ccafa30 at term 2"} {"level":"info","ts":"2026-05-21T02:52:13.932799Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d716dd02ccafa30 became leader at term 2"} {"level":"info","ts":"2026-05-21T02:52:13.932809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d716dd02ccafa30 elected leader d716dd02ccafa30 at term 2"} {"level":"info","ts":"2026-05-21T02:52:13.934463Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T02:52:13.935289Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T02:52:13.935289Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d716dd02ccafa30","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.100:2379]}","request-path":"/0/members/d716dd02ccafa30/attributes","cluster-id":"c093465db09ae4fe","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T02:52:13.935309Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T02:52:13.935577Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T02:52:13.935614Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T02:52:13.936097Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"c093465db09ae4fe","local-member-id":"d716dd02ccafa30","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T02:52:13.936218Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T02:52:13.936251Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T02:52:13.938544Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.100:2379"} {"level":"info","ts":"2026-05-21T02:52:13.938803Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T02:52:24.304399Z","caller":"traceutil/trace.go:171","msg":"trace[1917492371] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"136.216548ms","start":"2026-05-21T02:52:24.168162Z","end":"2026-05-21T02:52:24.304379Z","steps":["trace[1917492371] 'process raft request' (duration: 71.085905ms)","trace[1917492371] 'compare' (duration: 64.959621ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:52:24.576518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.220396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T02:52:24.576619Z","caller":"traceutil/trace.go:171","msg":"trace[1958786097] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"264.462769ms","start":"2026-05-21T02:52:24.312141Z","end":"2026-05-21T02:52:24.576604Z","steps":["trace[1958786097] 'process raft request' (duration: 148.79675ms)","trace[1958786097] 'compare' (duration: 115.018205ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:52:24.608052Z","caller":"traceutil/trace.go:171","msg":"trace[576563161] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:383; }","duration":"295.278503ms","start":"2026-05-21T02:52:24.312755Z","end":"2026-05-21T02:52:24.608034Z","steps":["trace[576563161] 'read index received' (duration: 148.185325ms)","trace[576563161] 'applied index is now lower than readState.Index' (duration: 147.092358ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:52:24.608275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.474316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-21T02:52:24.608363Z","caller":"traceutil/trace.go:171","msg":"trace[393347152] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:376; }","duration":"295.619996ms","start":"2026-05-21T02:52:24.312729Z","end":"2026-05-21T02:52:24.608349Z","steps":["trace[393347152] 'agreement among raft nodes before linearized reading' (duration: 295.463045ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:52:24.60823Z","caller":"traceutil/trace.go:171","msg":"trace[1104214475] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"294.561547ms","start":"2026-05-21T02:52:24.313627Z","end":"2026-05-21T02:52:24.608188Z","steps":["trace[1104214475] 'process raft request' (duration: 294.365696ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:52:24.608122Z","caller":"traceutil/trace.go:171","msg":"trace[77414786] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"295.535536ms","start":"2026-05-21T02:52:24.312573Z","end":"2026-05-21T02:52:24.608109Z","steps":["trace[77414786] 'process raft request' (duration: 295.311404ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:52:24.80101Z","caller":"traceutil/trace.go:171","msg":"trace[541140867] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"185.333836ms","start":"2026-05-21T02:52:24.615643Z","end":"2026-05-21T02:52:24.800976Z","steps":["trace[541140867] 'read index received' (duration: 185.205685ms)","trace[541140867] 'applied index is now lower than readState.Index' (duration: 127.061µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:52:24.801159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.500427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-05-21T02:52:24.801137Z","caller":"traceutil/trace.go:171","msg":"trace[84113631] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"185.759629ms","start":"2026-05-21T02:52:24.615345Z","end":"2026-05-21T02:52:24.801104Z","steps":["trace[84113631] 'process raft request' (duration: 185.495277ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:52:24.8012Z","caller":"traceutil/trace.go:171","msg":"trace[99796804] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:377; }","duration":"185.608218ms","start":"2026-05-21T02:52:24.615582Z","end":"2026-05-21T02:52:24.80119Z","steps":["trace[99796804] 'agreement among raft nodes before linearized reading' (duration: 185.498958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:27.914634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.214774ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T02:52:27.914935Z","caller":"traceutil/trace.go:171","msg":"trace[1776829173] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"222.413215ms","start":"2026-05-21T02:52:27.692491Z","end":"2026-05-21T02:52:27.914904Z","steps":["trace[1776829173] 'process raft request' (duration: 114.606117ms)","trace[1776829173] 'compare' (duration: 107.058143ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:52:30.627267Z","caller":"traceutil/trace.go:171","msg":"trace[1769846909] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"335.077009ms","start":"2026-05-21T02:52:30.291811Z","end":"2026-05-21T02:52:30.626888Z","steps":["trace[1769846909] 'process raft request' (duration: 280.248945ms)","trace[1769846909] 'compare' (duration: 54.602783ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:52:30.627718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:52:30.291793Z","time spent":"335.565022ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:52:31.234585Z","caller":"traceutil/trace.go:171","msg":"trace[2094468698] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"162.779405ms","start":"2026-05-21T02:52:31.071767Z","end":"2026-05-21T02:52:31.234546Z","steps":["trace[2094468698] 'process raft request' (duration: 162.516394ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:52:31.861841Z","caller":"traceutil/trace.go:171","msg":"trace[1455973896] linearizableReadLoop","detail":"{readStateIndex:430; appliedIndex:429; }","duration":"176.472591ms","start":"2026-05-21T02:52:31.685343Z","end":"2026-05-21T02:52:31.861816Z","steps":["trace[1455973896] 'read index received' (duration: 93.592443ms)","trace[1455973896] 'applied index is now lower than readState.Index' (duration: 82.879008ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:52:31.862408Z","caller":"traceutil/trace.go:171","msg":"trace[1609044262] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"248.07344ms","start":"2026-05-21T02:52:31.614319Z","end":"2026-05-21T02:52:31.862393Z","steps":["trace[1609044262] 'process raft request' (duration: 164.741459ms)","trace[1609044262] 'compare' (duration: 82.561966ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:52:31.862736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.688656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T02:52:31.862853Z","caller":"traceutil/trace.go:171","msg":"trace[347477559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:418; }","duration":"112.888288ms","start":"2026-05-21T02:52:31.749941Z","end":"2026-05-21T02:52:31.862829Z","steps":["trace[347477559] 'agreement among raft nodes before linearized reading' (duration: 112.645526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:31.863103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.68859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T02:52:31.863199Z","caller":"traceutil/trace.go:171","msg":"trace[52809848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:418; }","duration":"177.876151ms","start":"2026-05-21T02:52:31.685276Z","end":"2026-05-21T02:52:31.863152Z","steps":["trace[52809848] 'agreement among raft nodes before linearized reading' (duration: 177.529988ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:52:32.162974Z","caller":"traceutil/trace.go:171","msg":"trace[956963140] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:431; }","duration":"227.759633ms","start":"2026-05-21T02:52:31.935188Z","end":"2026-05-21T02:52:32.162947Z","steps":["trace[956963140] 'read index received' (duration: 217.367901ms)","trace[956963140] 'applied index is now lower than readState.Index' (duration: 10.390372ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:52:32.163188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.986195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T02:52:32.163331Z","caller":"traceutil/trace.go:171","msg":"trace[743569717] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"289.168062ms","start":"2026-05-21T02:52:31.874127Z","end":"2026-05-21T02:52:32.163295Z","steps":["trace[743569717] 'process raft request' (duration: 278.393357ms)","trace[743569717] 'compare' (duration: 10.209921ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:52:32.163297Z","caller":"traceutil/trace.go:171","msg":"trace[2146254713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:421; }","duration":"228.145246ms","start":"2026-05-21T02:52:31.935096Z","end":"2026-05-21T02:52:32.163241Z","steps":["trace[2146254713] 'agreement among raft nodes before linearized reading' (duration: 228.024825ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:52:32.163476Z","caller":"traceutil/trace.go:171","msg":"trace[917453201] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"287.026536ms","start":"2026-05-21T02:52:31.876257Z","end":"2026-05-21T02:52:32.163283Z","steps":["trace[917453201] 'process raft request' (duration: 286.619514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:32.566912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.661408ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T02:52:33.436902Z","caller":"traceutil/trace.go:171","msg":"trace[1467509659] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"194.00685ms","start":"2026-05-21T02:52:33.242871Z","end":"2026-05-21T02:52:33.436877Z","steps":["trace[1467509659] 'process raft request' (duration: 193.751398ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:52:39.567369Z","caller":"traceutil/trace.go:171","msg":"trace[1800945367] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"104.194901ms","start":"2026-05-21T02:52:39.46315Z","end":"2026-05-21T02:52:39.567345Z","steps":["trace[1800945367] 'process raft request' (duration: 103.988079ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:52:42.591334Z","caller":"traceutil/trace.go:171","msg":"trace[1437297881] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"329.148455ms","start":"2026-05-21T02:52:42.262162Z","end":"2026-05-21T02:52:42.591311Z","steps":["trace[1437297881] 'read index received' (duration: 329.001775ms)","trace[1437297881] 'applied index is now lower than readState.Index' (duration: 145.65µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:52:42.591614Z","caller":"traceutil/trace.go:171","msg":"trace[1253083370] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"364.124298ms","start":"2026-05-21T02:52:42.227423Z","end":"2026-05-21T02:52:42.591548Z","steps":["trace[1253083370] 'process raft request' (duration: 363.756636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:42.591754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:52:42.227406Z","time spent":"364.277629ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:52:42.591937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.750619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-21T02:52:42.592096Z","caller":"traceutil/trace.go:171","msg":"trace[21320315] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"329.954531ms","start":"2026-05-21T02:52:42.262122Z","end":"2026-05-21T02:52:42.592076Z","steps":["trace[21320315] 'agreement among raft nodes before linearized reading' (duration: 329.527528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:42.592245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:52:42.262106Z","time spent":"330.123492ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-21T02:52:56.570502Z","caller":"traceutil/trace.go:171","msg":"trace[630943152] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"969.770695ms","start":"2026-05-21T02:52:55.600705Z","end":"2026-05-21T02:52:56.570476Z","steps":["trace[630943152] 'process raft request' (duration: 969.582595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:56.570658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:52:55.600683Z","time spent":"969.910902ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T02:52:56.59025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18028083342362420370,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-21T02:52:56.633495Z","caller":"traceutil/trace.go:171","msg":"trace[1010894515] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:556; }","duration":"902.62312ms","start":"2026-05-21T02:52:55.730851Z","end":"2026-05-21T02:52:56.633474Z","steps":["trace[1010894515] 'read index received' (duration: 859.535442ms)","trace[1010894515] 'applied index is now lower than readState.Index' (duration: 43.086978ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:52:56.633625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.755887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T02:52:56.633658Z","caller":"traceutil/trace.go:171","msg":"trace[939865142] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:550; }","duration":"902.828891ms","start":"2026-05-21T02:52:55.730819Z","end":"2026-05-21T02:52:56.633647Z","steps":["trace[939865142] 'agreement among raft nodes before linearized reading' (duration: 902.732176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:56.633699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:52:55.730807Z","time spent":"902.884375ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-21T02:52:56.633972Z","caller":"traceutil/trace.go:171","msg":"trace[2033748022] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.028913466s","start":"2026-05-21T02:52:55.605048Z","end":"2026-05-21T02:52:56.633962Z","steps":["trace[2033748022] 'process raft request' (duration: 1.027663018s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:56.634053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:52:55.605017Z","time spent":"1.028991129s","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:52:56.634189Z","caller":"traceutil/trace.go:171","msg":"trace[1171066131] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.029078864s","start":"2026-05-21T02:52:55.605099Z","end":"2026-05-21T02:52:56.634178Z","steps":["trace[1171066131] 'process raft request' (duration: 1.027720091s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:56.634316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:52:55.605095Z","time spent":"1.029121656s","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:52:56.634456Z","caller":"traceutil/trace.go:171","msg":"trace[168770621] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.026462382s","start":"2026-05-21T02:52:55.607984Z","end":"2026-05-21T02:52:56.634447Z","steps":["trace[168770621] 'process raft request' (duration: 1.02491741s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:56.63451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:52:55.607974Z","time spent":"1.026514195s","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:52:56.634624Z","caller":"traceutil/trace.go:171","msg":"trace[194455375] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"1.021412209s","start":"2026-05-21T02:52:55.613204Z","end":"2026-05-21T02:52:56.634616Z","steps":["trace[194455375] 'process raft request' (duration: 1.01973702s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:56.634684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:52:55.613188Z","time spent":"1.021467492s","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:52:56.634803Z","caller":"traceutil/trace.go:171","msg":"trace[811246554] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"1.013683493s","start":"2026-05-21T02:52:55.621111Z","end":"2026-05-21T02:52:56.634795Z","steps":["trace[811246554] 'process raft request' (duration: 1.011922839s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:56.634852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:52:55.62109Z","time spent":"1.013742277s","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:52:56.634968Z","caller":"traceutil/trace.go:171","msg":"trace[1692150523] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"1.009671335s","start":"2026-05-21T02:52:55.625289Z","end":"2026-05-21T02:52:56.63496Z","steps":["trace[1692150523] 'process raft request' (duration: 1.007779225s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:56.63501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:52:55.625271Z","time spent":"1.009721288s","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:52:56.635122Z","caller":"traceutil/trace.go:171","msg":"trace[318988305] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"1.00958163s","start":"2026-05-21T02:52:55.625531Z","end":"2026-05-21T02:52:56.635113Z","steps":["trace[318988305] 'process raft request' (duration: 1.007579294s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:56.63517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:52:55.625515Z","time spent":"1.009636234s","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:52:56.635283Z","caller":"traceutil/trace.go:171","msg":"trace[1500741360] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"1.008358595s","start":"2026-05-21T02:52:55.626917Z","end":"2026-05-21T02:52:56.635275Z","steps":["trace[1500741360] 'process raft request' (duration: 1.006319457s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:56.635422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:52:55.626906Z","time spent":"1.008490953s","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:52:56.635628Z","caller":"traceutil/trace.go:171","msg":"trace[1747898382] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"1.005661349s","start":"2026-05-21T02:52:55.629955Z","end":"2026-05-21T02:52:56.635616Z","steps":["trace[1747898382] 'process raft request' (duration: 1.003351997s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:56.635687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:52:55.629942Z","time spent":"1.005721173s","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:52:56.635813Z","caller":"traceutil/trace.go:171","msg":"trace[540771231] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"960.164524ms","start":"2026-05-21T02:52:55.67564Z","end":"2026-05-21T02:52:56.635804Z","steps":["trace[540771231] 'process raft request' (duration: 957.715523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:56.635876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:52:55.675629Z","time spent":"960.223887ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:52:56.635992Z","caller":"traceutil/trace.go:171","msg":"trace[1227445798] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"807.774201ms","start":"2026-05-21T02:52:55.828209Z","end":"2026-05-21T02:52:56.635984Z","steps":["trace[1227445798] 'process raft request' (duration: 805.211594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:52:56.636039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:52:55.828171Z","time spent":"807.849334ms","remote":"127.0.0.1:39050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T02:52:56.679887Z","caller":"etcdserver/v3_server.go:874","msg":"ignored out-of-date read index response; local node read indexes queueing up and waiting to be in sync with leader","sent-request-id":18028083342362420383,"received-request-id":18028083342362420370} {"level":"info","ts":"2026-05-21T02:53:01.170253Z","caller":"traceutil/trace.go:171","msg":"trace[48786631] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"447.078738ms","start":"2026-05-21T02:53:00.723111Z","end":"2026-05-21T02:53:01.170189Z","steps":["trace[48786631] 'process raft request' (duration: 446.412035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:01.170478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:00.723095Z","time spent":"447.247916ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:53:01.338463Z","caller":"traceutil/trace.go:171","msg":"trace[1554610544] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"573.648336ms","start":"2026-05-21T02:53:00.764751Z","end":"2026-05-21T02:53:01.338399Z","steps":["trace[1554610544] 'read index received' (duration: 404.831824ms)","trace[1554610544] 'applied index is now lower than readState.Index' (duration: 168.815412ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:53:01.338527Z","caller":"traceutil/trace.go:171","msg":"trace[455741940] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"582.099776ms","start":"2026-05-21T02:53:00.756375Z","end":"2026-05-21T02:53:01.338475Z","steps":["trace[455741940] 'process raft request' (duration: 543.873058ms)","trace[455741940] 'compare' (duration: 37.808237ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:53:01.338735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:00.756344Z","time spent":"582.293355ms","remote":"127.0.0.1:39072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036165,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:53:01.33891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.031015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T02:53:01.33896Z","caller":"traceutil/trace.go:171","msg":"trace[1765769630] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"574.228094ms","start":"2026-05-21T02:53:00.764719Z","end":"2026-05-21T02:53:01.338947Z","steps":["trace[1765769630] 'agreement among raft nodes before linearized reading' (duration: 573.924539ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:01.339025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:00.764709Z","time spent":"574.303119ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T02:53:01.339038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.862059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T02:53:01.339085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.504266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T02:53:01.33915Z","caller":"traceutil/trace.go:171","msg":"trace[1949045173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"297.015307ms","start":"2026-05-21T02:53:01.042105Z","end":"2026-05-21T02:53:01.33912Z","steps":["trace[1949045173] 'agreement among raft nodes before linearized reading' (duration: 296.840968ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:01.339159Z","caller":"traceutil/trace.go:171","msg":"trace[1985413101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"546.624463ms","start":"2026-05-21T02:53:00.792512Z","end":"2026-05-21T02:53:01.339136Z","steps":["trace[1985413101] 'agreement among raft nodes before linearized reading' (duration: 546.394721ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:01.339203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:00.792488Z","time spent":"546.703877ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T02:53:01.339288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.571339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T02:53:01.339385Z","caller":"traceutil/trace.go:171","msg":"trace[383590415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"532.717797ms","start":"2026-05-21T02:53:00.806646Z","end":"2026-05-21T02:53:01.339364Z","steps":["trace[383590415] 'agreement among raft nodes before linearized reading' (duration: 532.408301ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:01.339437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:00.806626Z","time spent":"532.801401ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T02:53:02.746784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.453308ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T02:53:04.424442Z","caller":"traceutil/trace.go:171","msg":"trace[1665645175] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"134.913374ms","start":"2026-05-21T02:53:04.289503Z","end":"2026-05-21T02:53:04.424417Z","steps":["trace[1665645175] 'process raft request' (duration: 133.18731ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:04.424681Z","caller":"traceutil/trace.go:171","msg":"trace[1582967801] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"134.39686ms","start":"2026-05-21T02:53:04.290275Z","end":"2026-05-21T02:53:04.424672Z","steps":["trace[1582967801] 'process raft request' (duration: 134.089405ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:04.424788Z","caller":"traceutil/trace.go:171","msg":"trace[95931134] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"135.082942ms","start":"2026-05-21T02:53:04.289697Z","end":"2026-05-21T02:53:04.42478Z","steps":["trace[95931134] 'process raft request' (duration: 134.616209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:05.434249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.494893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:53:05.434377Z","caller":"traceutil/trace.go:171","msg":"trace[1383421154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"393.654321ms","start":"2026-05-21T02:53:05.040707Z","end":"2026-05-21T02:53:05.434362Z","steps":["trace[1383421154] 'range keys from in-memory index tree' (duration: 393.298794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:05.434426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:05.040691Z","time spent":"393.727593ms","remote":"127.0.0.1:39038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T02:53:05.434444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.511386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-21T02:53:05.434291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.322379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-89nqh\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-21T02:53:05.434526Z","caller":"traceutil/trace.go:171","msg":"trace[923605785] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:658; }","duration":"214.616722ms","start":"2026-05-21T02:53:05.21989Z","end":"2026-05-21T02:53:05.434507Z","steps":["trace[923605785] 'range keys from in-memory index tree' (duration: 214.348799ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:05.434551Z","caller":"traceutil/trace.go:171","msg":"trace[768252569] range","detail":"{range_begin:/registry/pods/kube-system/cilium-89nqh; range_end:; response_count:1; response_revision:658; }","duration":"405.629474ms","start":"2026-05-21T02:53:05.028901Z","end":"2026-05-21T02:53:05.43453Z","steps":["trace[768252569] 'range keys from in-memory index tree' (duration: 405.099018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:05.434617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:05.028876Z","time spent":"405.723278ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-89nqh\" "} {"level":"warn","ts":"2026-05-21T02:53:07.974428Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.535700926s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T02:53:07.975198Z","caller":"traceutil/trace.go:171","msg":"trace[1310039241] linearizableReadLoop","detail":"{readStateIndex:684; appliedIndex:683; }","duration":"2.536484022s","start":"2026-05-21T02:53:05.43863Z","end":"2026-05-21T02:53:07.975114Z","steps":["trace[1310039241] 'read index received' (duration: 96.844µs)","trace[1310039241] 'applied index is now lower than readState.Index' (duration: 2.536385858s)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:53:07.97566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.536977946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T02:53:07.97533Z","caller":"traceutil/trace.go:171","msg":"trace[928754948] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"2.536670912s","start":"2026-05-21T02:53:05.438605Z","end":"2026-05-21T02:53:07.975276Z","steps":["trace[928754948] 'process raft request' (duration: 2.536217311s)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:07.97592Z","caller":"traceutil/trace.go:171","msg":"trace[1783318836] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:659; }","duration":"2.537307361s","start":"2026-05-21T02:53:05.438594Z","end":"2026-05-21T02:53:07.975902Z","steps":["trace[1783318836] 'agreement among raft nodes before linearized reading' (duration: 2.536805547s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:07.976301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:05.438573Z","time spent":"2.537663658s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T02:53:07.976137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:05.438578Z","time spent":"2.537437548s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:53:08.040564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00033537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-21T02:53:08.040682Z","caller":"traceutil/trace.go:171","msg":"trace[1248795224] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000469276s","start":"2026-05-21T02:53:06.040165Z","end":"2026-05-21T02:53:08.040634Z","steps":["trace[1248795224] 'agreement among raft nodes before linearized reading' (duration: 2.000362242s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:08.040743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:06.04014Z","time spent":"2.000593053s","remote":"127.0.0.1:39030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/21 02:53:08 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-21T02:53:08.475999Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18028083342362420581,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T02:53:08.976701Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18028083342362420581,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T02:53:09.116549Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.14182885s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T02:53:09.199244Z","caller":"traceutil/trace.go:171","msg":"trace[1150317595] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"3.750361912s","start":"2026-05-21T02:53:05.448833Z","end":"2026-05-21T02:53:09.199195Z","steps":["trace[1150317595] 'process raft request' (duration: 3.66800591s)","trace[1150317595] 'compare' (duration: 81.973424ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:53:09.199417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:05.448795Z","time spent":"3.75053597s","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:53:09.199247Z","caller":"traceutil/trace.go:171","msg":"trace[1674074353] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"2.140798744s","start":"2026-05-21T02:53:07.058423Z","end":"2026-05-21T02:53:09.199222Z","steps":["trace[1674074353] 'process raft request' (duration: 2.140678549s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:09.199549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:07.058385Z","time spent":"2.141084617s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:53:09.515094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.102739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T02:53:09.515236Z","caller":"traceutil/trace.go:171","msg":"trace[1799525870] linearizableReadLoop","detail":"{readStateIndex:687; appliedIndex:684; }","duration":"1.539856159s","start":"2026-05-21T02:53:07.975362Z","end":"2026-05-21T02:53:09.515219Z","steps":["trace[1799525870] 'read index received' (duration: 1.141486854s)","trace[1799525870] 'applied index is now lower than readState.Index' (duration: 398.368145ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:53:09.515299Z","caller":"traceutil/trace.go:171","msg":"trace[545936439] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"1.534574853s","start":"2026-05-21T02:53:07.980701Z","end":"2026-05-21T02:53:09.515276Z","steps":["trace[545936439] 'process raft request' (duration: 1.336201012s)","trace[545936439] 'compare' (duration: 197.809925ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:53:09.515384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:07.980644Z","time spent":"1.534700898s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:53:09.515451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.030369331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-21T02:53:09.515477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.474578326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:53:09.515489Z","caller":"traceutil/trace.go:171","msg":"trace[1038556013] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:662; }","duration":"4.030422534s","start":"2026-05-21T02:53:05.485056Z","end":"2026-05-21T02:53:09.515478Z","steps":["trace[1038556013] 'agreement among raft nodes before linearized reading' (duration: 4.030264067s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:09.515474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.328125571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.100\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-21T02:53:09.515516Z","caller":"traceutil/trace.go:171","msg":"trace[1365166475] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:662; }","duration":"1.47465117s","start":"2026-05-21T02:53:08.040851Z","end":"2026-05-21T02:53:09.515502Z","steps":["trace[1365166475] 'agreement among raft nodes before linearized reading' (duration: 1.474588657s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:09.515526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:05.48505Z","time spent":"4.030467696s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T02:53:09.515545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:08.040833Z","time spent":"1.474704712s","remote":"127.0.0.1:39038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T02:53:09.515548Z","caller":"traceutil/trace.go:171","msg":"trace[1955809482] range","detail":"{range_begin:/registry/masterleases/162.253.55.100; range_end:; response_count:1; response_revision:662; }","duration":"2.328251837s","start":"2026-05-21T02:53:07.187278Z","end":"2026-05-21T02:53:09.51553Z","steps":["trace[1955809482] 'agreement among raft nodes before linearized reading' (duration: 2.327992665s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:09.515597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:07.187257Z","time spent":"2.328329001s","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.100\" "} {"level":"warn","ts":"2026-05-21T02:53:09.515574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.678497589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T02:53:09.515725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.031012772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-21T02:53:09.515731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.972562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-89nqh\" ","response":"range_response_count:1 size:17149"} {"level":"info","ts":"2026-05-21T02:53:09.515725Z","caller":"traceutil/trace.go:171","msg":"trace[1759475757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:662; }","duration":"1.678709479s","start":"2026-05-21T02:53:07.836976Z","end":"2026-05-21T02:53:09.515686Z","steps":["trace[1759475757] 'agreement among raft nodes before linearized reading' (duration: 1.678391565s)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:09.515761Z","caller":"traceutil/trace.go:171","msg":"trace[860315312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:662; }","duration":"4.031101375s","start":"2026-05-21T02:53:05.484648Z","end":"2026-05-21T02:53:09.51575Z","steps":["trace[860315312] 'agreement among raft nodes before linearized reading' (duration: 4.031012781s)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:09.515771Z","caller":"traceutil/trace.go:171","msg":"trace[158349493] range","detail":"{range_begin:/registry/pods/kube-system/cilium-89nqh; range_end:; response_count:1; response_revision:662; }","duration":"309.047986ms","start":"2026-05-21T02:53:09.206713Z","end":"2026-05-21T02:53:09.515761Z","steps":["trace[158349493] 'agreement among raft nodes before linearized reading' (duration: 308.937731ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:09.515792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:05.484633Z","time spent":"4.031152898s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T02:53:09.515798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:09.20669Z","time spent":"309.100918ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17172,"request content":"key:\"/registry/pods/kube-system/cilium-89nqh\" "} {"level":"warn","ts":"2026-05-21T02:53:09.515798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:07.83695Z","time spent":"1.678838995s","remote":"127.0.0.1:39030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T02:53:11.15497Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.569638113s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T02:53:11.155418Z","caller":"traceutil/trace.go:171","msg":"trace[186161131] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"1.634499575s","start":"2026-05-21T02:53:09.52089Z","end":"2026-05-21T02:53:11.15539Z","steps":["trace[186161131] 'process raft request' (duration: 1.634279555s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:11.155586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:09.52087Z","time spent":"1.63461594s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:53:11.155707Z","caller":"traceutil/trace.go:171","msg":"trace[1745284537] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.634515576s","start":"2026-05-21T02:53:09.521164Z","end":"2026-05-21T02:53:11.155679Z","steps":["trace[1745284537] 'process raft request' (duration: 1.63417295s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:11.155833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:09.521124Z","time spent":"1.634647301s","remote":"127.0.0.1:39062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T02:53:11.155785Z","caller":"traceutil/trace.go:171","msg":"trace[606192733] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:687; }","duration":"1.630606016s","start":"2026-05-21T02:53:09.52516Z","end":"2026-05-21T02:53:11.155766Z","steps":["trace[606192733] 'read index received' (duration: 60.175578ms)","trace[606192733] 'applied index is now lower than readState.Index' (duration: 1.570429208s)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:53:11.156276Z","caller":"traceutil/trace.go:171","msg":"trace[1220236979] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"1.63375443s","start":"2026-05-21T02:53:09.522503Z","end":"2026-05-21T02:53:11.156257Z","steps":["trace[1220236979] 'process raft request' (duration: 1.633107171s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:11.156359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.631197164s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:53:11.15642Z","caller":"traceutil/trace.go:171","msg":"trace[149988123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"1.631291248s","start":"2026-05-21T02:53:09.525116Z","end":"2026-05-21T02:53:11.156407Z","steps":["trace[149988123] 'agreement among raft nodes before linearized reading' (duration: 1.631135191s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:11.156456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:09.525106Z","time spent":"1.63134356s","remote":"127.0.0.1:39030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T02:53:11.156367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:09.522492Z","time spent":"1.633839373s","remote":"127.0.0.1:39244","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-05-21T02:53:12.702204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33520004s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T02:53:12.7028Z","caller":"traceutil/trace.go:171","msg":"trace[1162740169] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"3.115816968s","start":"2026-05-21T02:53:09.586936Z","end":"2026-05-21T02:53:12.702753Z","steps":["trace[1162740169] 'process raft request' (duration: 1.779946138s)","trace[1162740169] 'compare' (duration: 1.335038043s)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:53:12.702919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:09.586904Z","time spent":"3.115954384s","remote":"127.0.0.1:39042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:53:12.70309Z","caller":"traceutil/trace.go:171","msg":"trace[937497507] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"2.396780773s","start":"2026-05-21T02:53:10.306277Z","end":"2026-05-21T02:53:12.703058Z","steps":["trace[937497507] 'process raft request' (duration: 2.396037281s)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:12.703117Z","caller":"traceutil/trace.go:171","msg":"trace[790760099] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:691; }","duration":"1.546965182s","start":"2026-05-21T02:53:11.156101Z","end":"2026-05-21T02:53:12.703067Z","steps":["trace[790760099] 'read index received' (duration: 210.793658ms)","trace[790760099] 'applied index is now lower than readState.Index' (duration: 1.336168884s)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:53:12.703253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:10.306254Z","time spent":"2.396908809s","remote":"127.0.0.1:39244","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-05-21T02:53:12.729717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209119819s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-21T02:53:12.729688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.572175234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T02:53:12.72972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.748674977s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T02:53:12.729756Z","caller":"traceutil/trace.go:171","msg":"trace[778679012] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:667; }","duration":"1.209197091s","start":"2026-05-21T02:53:11.520549Z","end":"2026-05-21T02:53:12.729746Z","steps":["trace[778679012] 'agreement among raft nodes before linearized reading' (duration: 1.209058235s)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:12.729775Z","caller":"traceutil/trace.go:171","msg":"trace[1209968060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"1.572273258s","start":"2026-05-21T02:53:11.157485Z","end":"2026-05-21T02:53:12.729759Z","steps":["trace[1209968060] 'agreement among raft nodes before linearized reading' (duration: 1.572141352s)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:12.729783Z","caller":"traceutil/trace.go:171","msg":"trace[43644553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:667; }","duration":"2.7487672s","start":"2026-05-21T02:53:09.981Z","end":"2026-05-21T02:53:12.729767Z","steps":["trace[43644553] 'agreement among raft nodes before linearized reading' (duration: 2.722204529s)","trace[43644553] 'range keys from in-memory index tree' (duration: 26.378614ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:53:12.729821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:11.15743Z","time spent":"1.572373962s","remote":"127.0.0.1:39038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T02:53:12.729781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:11.52053Z","time spent":"1.209246304s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T02:53:12.729825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:09.980984Z","time spent":"2.748825633s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T02:53:13.389592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.353277ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T02:53:13.389724Z","caller":"traceutil/trace.go:171","msg":"trace[178541225] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"229.989933ms","start":"2026-05-21T02:53:13.159712Z","end":"2026-05-21T02:53:13.389701Z","steps":["trace[178541225] 'read index received' (duration: 40.652µs)","trace[178541225] 'applied index is now lower than readState.Index' (duration: 229.947441ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:53:13.389915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.191202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T02:53:13.389973Z","caller":"traceutil/trace.go:171","msg":"trace[549528787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:671; }","duration":"230.298577ms","start":"2026-05-21T02:53:13.159661Z","end":"2026-05-21T02:53:13.38996Z","steps":["trace[549528787] 'agreement among raft nodes before linearized reading' (duration: 230.109968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:13.390264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.413711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T02:53:13.390294Z","caller":"traceutil/trace.go:171","msg":"trace[1789536522] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"322.410044ms","start":"2026-05-21T02:53:13.067867Z","end":"2026-05-21T02:53:13.390277Z","steps":["trace[1789536522] 'process raft request' (duration: 41.73647ms)","trace[1789536522] 'compare' (duration: 279.029973ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:53:13.390326Z","caller":"traceutil/trace.go:171","msg":"trace[1068641317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:671; }","duration":"230.479264ms","start":"2026-05-21T02:53:13.159832Z","end":"2026-05-21T02:53:13.390312Z","steps":["trace[1068641317] 'agreement among raft nodes before linearized reading' (duration: 230.099227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:13.390365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:13.067836Z","time spent":"322.490028ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17232,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:53:14.206029Z","caller":"traceutil/trace.go:171","msg":"trace[287780538] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"136.712266ms","start":"2026-05-21T02:53:14.069283Z","end":"2026-05-21T02:53:14.205995Z","steps":["trace[287780538] 'process raft request' (duration: 134.731211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:15.284122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.52534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:53:15.284202Z","caller":"traceutil/trace.go:171","msg":"trace[1753479215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:679; }","duration":"241.649316ms","start":"2026-05-21T02:53:15.042534Z","end":"2026-05-21T02:53:15.284183Z","steps":["trace[1753479215] 'range keys from in-memory index tree' (duration: 241.392124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:15.284207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.626888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-21T02:53:15.284215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.188817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T02:53:15.284254Z","caller":"traceutil/trace.go:171","msg":"trace[1721827177] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:679; }","duration":"539.698851ms","start":"2026-05-21T02:53:14.744543Z","end":"2026-05-21T02:53:15.284242Z","steps":["trace[1721827177] 'range keys from in-memory index tree' (duration: 539.473421ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:15.284261Z","caller":"traceutil/trace.go:171","msg":"trace[1779642404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:679; }","duration":"535.26005ms","start":"2026-05-21T02:53:14.748989Z","end":"2026-05-21T02:53:15.284249Z","steps":["trace[1779642404] 'range keys from in-memory index tree' (duration: 535.074513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:15.284288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:14.744524Z","time spent":"539.756394ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T02:53:15.284292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:14.748977Z","time spent":"535.308243ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T02:53:15.284431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.102152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-89nqh\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-05-21T02:53:15.284466Z","caller":"traceutil/trace.go:171","msg":"trace[336792365] range","detail":"{range_begin:/registry/pods/kube-system/cilium-89nqh; range_end:; response_count:1; response_revision:679; }","duration":"218.157464ms","start":"2026-05-21T02:53:15.066295Z","end":"2026-05-21T02:53:15.284453Z","steps":["trace[336792365] 'range keys from in-memory index tree' (duration: 218.001026ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:16.205265Z","caller":"traceutil/trace.go:171","msg":"trace[1210047174] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:715; }","duration":"132.957936ms","start":"2026-05-21T02:53:16.072244Z","end":"2026-05-21T02:53:16.205202Z","steps":["trace[1210047174] 'read index received' (duration: 132.739007ms)","trace[1210047174] 'applied index is now lower than readState.Index' (duration: 217.829µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:53:16.205352Z","caller":"traceutil/trace.go:171","msg":"trace[1132049554] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"176.490012ms","start":"2026-05-21T02:53:16.028835Z","end":"2026-05-21T02:53:16.205325Z","steps":["trace[1132049554] 'process raft request' (duration: 176.19056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:16.205834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.52936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-89nqh\" ","response":"range_response_count:1 size:17708"} {"level":"info","ts":"2026-05-21T02:53:16.205937Z","caller":"traceutil/trace.go:171","msg":"trace[89466371] range","detail":"{range_begin:/registry/pods/kube-system/cilium-89nqh; range_end:; response_count:1; response_revision:688; }","duration":"133.696877ms","start":"2026-05-21T02:53:16.072179Z","end":"2026-05-21T02:53:16.205876Z","steps":["trace[89466371] 'agreement among raft nodes before linearized reading' (duration: 133.199677ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:16.218752Z","caller":"traceutil/trace.go:171","msg":"trace[534091841] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"144.388719ms","start":"2026-05-21T02:53:16.074346Z","end":"2026-05-21T02:53:16.218734Z","steps":["trace[534091841] 'process raft request' (duration: 144.303436ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:16.365208Z","caller":"traceutil/trace.go:171","msg":"trace[67603054] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"141.465725ms","start":"2026-05-21T02:53:16.223704Z","end":"2026-05-21T02:53:16.36517Z","steps":["trace[67603054] 'process raft request' (duration: 116.850006ms)","trace[67603054] 'compare' (duration: 24.432231ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:53:16.41231Z","caller":"traceutil/trace.go:171","msg":"trace[1601500752] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"188.484648ms","start":"2026-05-21T02:53:16.223798Z","end":"2026-05-21T02:53:16.412283Z","steps":["trace[1601500752] 'process raft request' (duration: 188.247188ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:16.412644Z","caller":"traceutil/trace.go:171","msg":"trace[1623794969] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"188.648405ms","start":"2026-05-21T02:53:16.223913Z","end":"2026-05-21T02:53:16.412562Z","steps":["trace[1623794969] 'process raft request' (duration: 188.321652ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:16.412757Z","caller":"traceutil/trace.go:171","msg":"trace[284175043] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"188.804511ms","start":"2026-05-21T02:53:16.223924Z","end":"2026-05-21T02:53:16.412728Z","steps":["trace[284175043] 'process raft request' (duration: 188.503289ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:16.569574Z","caller":"traceutil/trace.go:171","msg":"trace[548673583] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"197.280448ms","start":"2026-05-21T02:53:16.372273Z","end":"2026-05-21T02:53:16.569553Z","steps":["trace[548673583] 'process raft request' (duration: 197.217676ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:16.569747Z","caller":"traceutil/trace.go:171","msg":"trace[1573831385] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"198.876507ms","start":"2026-05-21T02:53:16.370832Z","end":"2026-05-21T02:53:16.569709Z","steps":["trace[1573831385] 'process raft request' (duration: 131.13742ms)","trace[1573831385] 'compare' (duration: 67.353601ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:53:16.66357Z","caller":"traceutil/trace.go:171","msg":"trace[1539236758] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:721; }","duration":"247.018147ms","start":"2026-05-21T02:53:16.41652Z","end":"2026-05-21T02:53:16.663538Z","steps":["trace[1539236758] 'read index received' (duration: 85.541877ms)","trace[1539236758] 'applied index is now lower than readState.Index' (duration: 161.474959ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:53:16.663736Z","caller":"traceutil/trace.go:171","msg":"trace[608095070] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"247.891053ms","start":"2026-05-21T02:53:16.415812Z","end":"2026-05-21T02:53:16.663703Z","steps":["trace[608095070] 'process raft request' (duration: 247.588271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:16.66379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.246697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-56g5h\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-05-21T02:53:16.663824Z","caller":"traceutil/trace.go:171","msg":"trace[1278649318] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-56g5h; range_end:; response_count:1; response_revision:697; }","duration":"247.32484ms","start":"2026-05-21T02:53:16.416489Z","end":"2026-05-21T02:53:16.663814Z","steps":["trace[1278649318] 'agreement among raft nodes before linearized reading' (duration: 247.140172ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:16.664058Z","caller":"traceutil/trace.go:171","msg":"trace[2047243619] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"246.61908ms","start":"2026-05-21T02:53:16.417136Z","end":"2026-05-21T02:53:16.663755Z","steps":["trace[2047243619] 'process raft request' (duration: 246.361139ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:16.697632Z","caller":"traceutil/trace.go:171","msg":"trace[1974495681] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"123.455886ms","start":"2026-05-21T02:53:16.574151Z","end":"2026-05-21T02:53:16.697607Z","steps":["trace[1974495681] 'process raft request' (duration: 123.218256ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:16.697951Z","caller":"traceutil/trace.go:171","msg":"trace[1731145215] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"122.82915ms","start":"2026-05-21T02:53:16.575106Z","end":"2026-05-21T02:53:16.697935Z","steps":["trace[1731145215] 'process raft request' (duration: 122.453714ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:17.118805Z","caller":"traceutil/trace.go:171","msg":"trace[1072973516] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"282.867324ms","start":"2026-05-21T02:53:16.835916Z","end":"2026-05-21T02:53:17.118783Z","steps":["trace[1072973516] 'process raft request' (duration: 282.817862ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:17.118867Z","caller":"traceutil/trace.go:171","msg":"trace[708112444] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"287.333683ms","start":"2026-05-21T02:53:16.831508Z","end":"2026-05-21T02:53:17.118842Z","steps":["trace[708112444] 'process raft request' (duration: 287.075492ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:17.132249Z","caller":"traceutil/trace.go:171","msg":"trace[509818889] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"225.226347ms","start":"2026-05-21T02:53:16.907Z","end":"2026-05-21T02:53:17.132226Z","steps":["trace[509818889] 'process raft request' (duration: 225.048169ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:17.132582Z","caller":"traceutil/trace.go:171","msg":"trace[1663229498] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"225.528439ms","start":"2026-05-21T02:53:16.907019Z","end":"2026-05-21T02:53:17.132548Z","steps":["trace[1663229498] 'process raft request' (duration: 225.157744ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:17.13263Z","caller":"traceutil/trace.go:171","msg":"trace[298049098] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"117.687438ms","start":"2026-05-21T02:53:17.014922Z","end":"2026-05-21T02:53:17.13261Z","steps":["trace[298049098] 'process raft request' (duration: 117.50512ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:19.463581Z","caller":"traceutil/trace.go:171","msg":"trace[1282613112] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"153.004639ms","start":"2026-05-21T02:53:19.310555Z","end":"2026-05-21T02:53:19.463559Z","steps":["trace[1282613112] 'read index received' (duration: 152.77781ms)","trace[1282613112] 'applied index is now lower than readState.Index' (duration: 225.879µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:53:19.463886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.332553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-21T02:53:19.463932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.36499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-21T02:53:19.463935Z","caller":"traceutil/trace.go:171","msg":"trace[349570681] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:739; }","duration":"153.439458ms","start":"2026-05-21T02:53:19.310484Z","end":"2026-05-21T02:53:19.463924Z","steps":["trace[349570681] 'agreement among raft nodes before linearized reading' (duration: 153.197458ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:19.464015Z","caller":"traceutil/trace.go:171","msg":"trace[1706090349] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:739; }","duration":"109.481975ms","start":"2026-05-21T02:53:19.354514Z","end":"2026-05-21T02:53:19.463996Z","steps":["trace[1706090349] 'agreement among raft nodes before linearized reading' (duration: 109.234385ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:19.463726Z","caller":"traceutil/trace.go:171","msg":"trace[2053550959] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"225.319017ms","start":"2026-05-21T02:53:19.238373Z","end":"2026-05-21T02:53:19.463692Z","steps":["trace[2053550959] 'process raft request' (duration: 225.033186ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:30.021193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18028083342362420993,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T02:53:30.522026Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18028083342362420993,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T02:53:30.988914Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.46791529s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T02:53:30.989511Z","caller":"traceutil/trace.go:171","msg":"trace[1582896621] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"1.468588324s","start":"2026-05-21T02:53:29.5209Z","end":"2026-05-21T02:53:30.989489Z","steps":["trace[1582896621] 'process raft request' (duration: 1.468422868s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:30.989802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:29.520272Z","time spent":"1.46931902s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:53:31.286164Z","caller":"traceutil/trace.go:171","msg":"trace[117050212] linearizableReadLoop","detail":"{readStateIndex:860; appliedIndex:857; }","duration":"1.765077553s","start":"2026-05-21T02:53:29.521071Z","end":"2026-05-21T02:53:31.286149Z","steps":["trace[117050212] 'read index received' (duration: 1.468077565s)","trace[117050212] 'applied index is now lower than readState.Index' (duration: 296.999388ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:53:31.286262Z","caller":"traceutil/trace.go:171","msg":"trace[1629026627] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"1.111834854s","start":"2026-05-21T02:53:30.17442Z","end":"2026-05-21T02:53:31.286255Z","steps":["trace[1629026627] 'process raft request' (duration: 1.11169757s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:31.286465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:30.174396Z","time spent":"1.111888657s","remote":"127.0.0.1:39218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:53:31.286595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.765522548s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T02:53:31.286573Z","caller":"traceutil/trace.go:171","msg":"trace[974805238] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"1.534024117s","start":"2026-05-21T02:53:29.752131Z","end":"2026-05-21T02:53:31.286155Z","steps":["trace[974805238] 'process raft request' (duration: 1.533880852s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:31.286661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.712765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T02:53:31.286674Z","caller":"traceutil/trace.go:171","msg":"trace[1056143556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:828; }","duration":"814.757106ms","start":"2026-05-21T02:53:30.471913Z","end":"2026-05-21T02:53:31.286671Z","steps":["trace[1056143556] 'agreement among raft nodes before linearized reading' (duration: 814.718555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:31.286688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:30.471892Z","time spent":"814.793878ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T02:53:31.286711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.760794737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-21T02:53:31.286726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.844186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T02:53:31.286618Z","caller":"traceutil/trace.go:171","msg":"trace[1020924828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:828; }","duration":"1.765557011s","start":"2026-05-21T02:53:29.521055Z","end":"2026-05-21T02:53:31.286613Z","steps":["trace[1020924828] 'agreement among raft nodes before linearized reading' (duration: 1.765485308s)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:31.28678Z","caller":"traceutil/trace.go:171","msg":"trace[647782737] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:828; }","duration":"1.76090103s","start":"2026-05-21T02:53:29.525857Z","end":"2026-05-21T02:53:31.286758Z","steps":["trace[647782737] 'agreement among raft nodes before linearized reading' (duration: 1.760704942s)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:31.286796Z","caller":"traceutil/trace.go:171","msg":"trace[2100579307] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:828; }","duration":"828.95026ms","start":"2026-05-21T02:53:30.457826Z","end":"2026-05-21T02:53:31.286777Z","steps":["trace[2100579307] 'agreement among raft nodes before linearized reading' (duration: 828.827516ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:31.286817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:29.525839Z","time spent":"1.760971982s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T02:53:31.286833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:30.457807Z","time spent":"829.015933ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-05-21T02:53:31.286825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.755240633s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-21T02:53:31.28683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:29.521046Z","time spent":"1.765748488s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-21T02:53:31.286876Z","caller":"traceutil/trace.go:171","msg":"trace[71230796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:828; }","duration":"1.755325216s","start":"2026-05-21T02:53:29.531537Z","end":"2026-05-21T02:53:31.286862Z","steps":["trace[71230796] 'agreement among raft nodes before linearized reading' (duration: 1.75514272s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:31.286912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:29.531516Z","time spent":"1.755389859s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T02:53:31.287124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.249173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:53:31.287168Z","caller":"traceutil/trace.go:171","msg":"trace[1980257072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:828; }","duration":"244.319506ms","start":"2026-05-21T02:53:31.042837Z","end":"2026-05-21T02:53:31.287156Z","steps":["trace[1980257072] 'agreement among raft nodes before linearized reading' (duration: 244.255444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:31.287484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:53:29.752095Z","time spent":"1.534547657s","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:53:45.465178Z","caller":"traceutil/trace.go:171","msg":"trace[1090122732] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"101.491349ms","start":"2026-05-21T02:53:45.363662Z","end":"2026-05-21T02:53:45.465153Z","steps":["trace[1090122732] 'process raft request' (duration: 101.279923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:53:45.778717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.812337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-v59qc\" ","response":"range_response_count:1 size:5677"} {"level":"info","ts":"2026-05-21T02:53:45.778803Z","caller":"traceutil/trace.go:171","msg":"trace[1896586362] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-v59qc; range_end:; response_count:1; response_revision:988; }","duration":"135.010663ms","start":"2026-05-21T02:53:45.64377Z","end":"2026-05-21T02:53:45.778781Z","steps":["trace[1896586362] 'range keys from in-memory index tree' (duration: 134.563078ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:53:59.229699Z","caller":"traceutil/trace.go:171","msg":"trace[2073526694] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"108.822398ms","start":"2026-05-21T02:53:59.120857Z","end":"2026-05-21T02:53:59.22968Z","steps":["trace[2073526694] 'process raft request' (duration: 108.705775ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:54:05.451623Z","caller":"traceutil/trace.go:171","msg":"trace[1942100393] linearizableReadLoop","detail":"{readStateIndex:1108; appliedIndex:1107; }","duration":"193.150969ms","start":"2026-05-21T02:54:05.258446Z","end":"2026-05-21T02:54:05.451597Z","steps":["trace[1942100393] 'read index received' (duration: 192.936423ms)","trace[1942100393] 'applied index is now lower than readState.Index' (duration: 213.626µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:54:05.4517Z","caller":"traceutil/trace.go:171","msg":"trace[1045367083] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"322.913429ms","start":"2026-05-21T02:54:05.12876Z","end":"2026-05-21T02:54:05.451673Z","steps":["trace[1045367083] 'process raft request' (duration: 322.702963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:54:05.451856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.399865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T02:54:05.451916Z","caller":"traceutil/trace.go:171","msg":"trace[1114538459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1068; }","duration":"193.495698ms","start":"2026-05-21T02:54:05.258405Z","end":"2026-05-21T02:54:05.451901Z","steps":["trace[1114538459] 'agreement among raft nodes before linearized reading' (duration: 193.319523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:54:05.451851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:54:05.128746Z","time spent":"323.011102ms","remote":"127.0.0.1:39166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4123,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:54:05.615462Z","caller":"traceutil/trace.go:171","msg":"trace[1042730092] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"157.610536ms","start":"2026-05-21T02:54:05.457812Z","end":"2026-05-21T02:54:05.615422Z","steps":["trace[1042730092] 'process raft request' (duration: 105.382648ms)","trace[1042730092] 'compare' (duration: 51.895259ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:54:05.7605Z","caller":"traceutil/trace.go:171","msg":"trace[1993803868] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1072; }","duration":"113.885468ms","start":"2026-05-21T02:54:05.646585Z","end":"2026-05-21T02:54:05.76047Z","steps":["trace[1993803868] 'process raft request' (duration: 93.16542ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:54:05.945967Z","caller":"traceutil/trace.go:171","msg":"trace[1951216783] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"179.967496ms","start":"2026-05-21T02:54:05.765966Z","end":"2026-05-21T02:54:05.945933Z","steps":["trace[1951216783] 'process raft request' (duration: 163.887498ms)","trace[1951216783] 'compare' (duration: 15.870743ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:54:05.945973Z","caller":"traceutil/trace.go:171","msg":"trace[206131897] transaction","detail":"{read_only:false; response_revision:1075; number_of_response:1; }","duration":"179.084753ms","start":"2026-05-21T02:54:05.766862Z","end":"2026-05-21T02:54:05.945947Z","steps":["trace[206131897] 'process raft request' (duration: 179.002841ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:54:14.582364Z","caller":"traceutil/trace.go:171","msg":"trace[2012859823] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"100.23976ms","start":"2026-05-21T02:54:14.482091Z","end":"2026-05-21T02:54:14.58233Z","steps":["trace[2012859823] 'process raft request' (duration: 100.028885ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:54:15.883041Z","caller":"traceutil/trace.go:171","msg":"trace[610168237] linearizableReadLoop","detail":"{readStateIndex:1146; appliedIndex:1145; }","duration":"191.405994ms","start":"2026-05-21T02:54:15.69161Z","end":"2026-05-21T02:54:15.883016Z","steps":["trace[610168237] 'read index received' (duration: 191.263571ms)","trace[610168237] 'applied index is now lower than readState.Index' (duration: 141.443µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:54:15.883117Z","caller":"traceutil/trace.go:171","msg":"trace[1203016420] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"193.032574ms","start":"2026-05-21T02:54:15.690051Z","end":"2026-05-21T02:54:15.883084Z","steps":["trace[1203016420] 'process raft request' (duration: 192.809439ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:54:15.883244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.611579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-21T02:54:15.88329Z","caller":"traceutil/trace.go:171","msg":"trace[436671584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1104; }","duration":"191.689351ms","start":"2026-05-21T02:54:15.691585Z","end":"2026-05-21T02:54:15.883274Z","steps":["trace[436671584] 'agreement among raft nodes before linearized reading' (duration: 191.532598ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:54:17.704812Z","caller":"traceutil/trace.go:171","msg":"trace[47194806] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"256.506689ms","start":"2026-05-21T02:54:17.448278Z","end":"2026-05-21T02:54:17.704785Z","steps":["trace[47194806] 'process raft request' (duration: 256.322435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:54:18.289841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.64828ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T02:54:18.289933Z","caller":"traceutil/trace.go:171","msg":"trace[1518886085] linearizableReadLoop","detail":"{readStateIndex:1156; appliedIndex:1155; }","duration":"283.409393ms","start":"2026-05-21T02:54:18.00651Z","end":"2026-05-21T02:54:18.289919Z","steps":["trace[1518886085] 'read index received' (duration: 13.607048ms)","trace[1518886085] 'applied index is now lower than readState.Index' (duration: 269.801505ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:54:18.290203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.68393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T02:54:18.29024Z","caller":"traceutil/trace.go:171","msg":"trace[692339942] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"322.002973ms","start":"2026-05-21T02:54:17.968227Z","end":"2026-05-21T02:54:18.29023Z","steps":["trace[692339942] 'process raft request' (duration: 51.899022ms)","trace[692339942] 'compare' (duration: 269.532758ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:54:18.290279Z","caller":"traceutil/trace.go:171","msg":"trace[200858650] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1113; }","duration":"283.808573ms","start":"2026-05-21T02:54:18.006454Z","end":"2026-05-21T02:54:18.290262Z","steps":["trace[200858650] 'agreement among raft nodes before linearized reading' (duration: 283.598038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:54:18.290293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:54:17.968198Z","time spent":"322.071355ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:54:18.290357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.709309ms","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":"warn","ts":"2026-05-21T02:54:18.29036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.205365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:54:18.290432Z","caller":"traceutil/trace.go:171","msg":"trace[1219362766] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1113; }","duration":"277.835092ms","start":"2026-05-21T02:54:18.012582Z","end":"2026-05-21T02:54:18.290417Z","steps":["trace[1219362766] 'agreement among raft nodes before linearized reading' (duration: 277.545375ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:54:18.290459Z","caller":"traceutil/trace.go:171","msg":"trace[1323447350] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1113; }","duration":"247.323198ms","start":"2026-05-21T02:54:18.043106Z","end":"2026-05-21T02:54:18.290429Z","steps":["trace[1323447350] 'agreement among raft nodes before linearized reading' (duration: 247.142134ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:54:18.888384Z","caller":"traceutil/trace.go:171","msg":"trace[1624909334] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"289.033914ms","start":"2026-05-21T02:54:18.599324Z","end":"2026-05-21T02:54:18.888358Z","steps":["trace[1624909334] 'process raft request' (duration: 288.727367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:54:28.739286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.915886ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T02:54:28.739407Z","caller":"traceutil/trace.go:171","msg":"trace[1715673316] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1191; }","duration":"336.245104ms","start":"2026-05-21T02:54:28.40314Z","end":"2026-05-21T02:54:28.739385Z","steps":["trace[1715673316] 'read index received' (duration: 132.160145ms)","trace[1715673316] 'applied index is now lower than readState.Index' (duration: 204.083369ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:54:28.739423Z","caller":"traceutil/trace.go:171","msg":"trace[443126810] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"359.637073ms","start":"2026-05-21T02:54:28.379757Z","end":"2026-05-21T02:54:28.739394Z","steps":["trace[443126810] 'process raft request' (duration: 155.502232ms)","trace[443126810] 'compare' (duration: 203.793963ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:54:28.739545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:54:28.379729Z","time spent":"359.741305ms","remote":"127.0.0.1:39244","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-05-21T02:54:28.739638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.491099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T02:54:28.739699Z","caller":"traceutil/trace.go:171","msg":"trace[1385536321] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1147; }","duration":"336.586091ms","start":"2026-05-21T02:54:28.4031Z","end":"2026-05-21T02:54:28.739686Z","steps":["trace[1385536321] 'agreement among raft nodes before linearized reading' (duration: 336.383276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:54:28.73974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:54:28.403083Z","time spent":"336.646703ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T02:54:29.154046Z","caller":"traceutil/trace.go:171","msg":"trace[71820690] linearizableReadLoop","detail":"{readStateIndex:1194; appliedIndex:1193; }","duration":"126.708907ms","start":"2026-05-21T02:54:29.027322Z","end":"2026-05-21T02:54:29.154031Z","steps":["trace[71820690] 'read index received' (duration: 126.580735ms)","trace[71820690] 'applied index is now lower than readState.Index' (duration: 127.682µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:54:29.154132Z","caller":"traceutil/trace.go:171","msg":"trace[261598907] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"147.274941ms","start":"2026-05-21T02:54:29.006849Z","end":"2026-05-21T02:54:29.154124Z","steps":["trace[261598907] 'process raft request' (duration: 147.090967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:54:29.154269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.936972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T02:54:29.154287Z","caller":"traceutil/trace.go:171","msg":"trace[30876603] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1149; }","duration":"127.012494ms","start":"2026-05-21T02:54:29.027269Z","end":"2026-05-21T02:54:29.154281Z","steps":["trace[30876603] 'agreement among raft nodes before linearized reading' (duration: 126.948012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:54:29.154452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.934698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:54:29.154466Z","caller":"traceutil/trace.go:171","msg":"trace[2039482430] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1149; }","duration":"111.948898ms","start":"2026-05-21T02:54:29.042512Z","end":"2026-05-21T02:54:29.154461Z","steps":["trace[2039482430] 'agreement among raft nodes before linearized reading' (duration: 111.923398ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:54:30.541418Z","caller":"traceutil/trace.go:171","msg":"trace[1626787644] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"158.261854ms","start":"2026-05-21T02:54:30.383125Z","end":"2026-05-21T02:54:30.541387Z","steps":["trace[1626787644] 'process raft request' (duration: 158.084811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:54:31.123988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.929902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T02:54:31.124048Z","caller":"traceutil/trace.go:171","msg":"trace[908608139] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1152; }","duration":"380.043155ms","start":"2026-05-21T02:54:30.743993Z","end":"2026-05-21T02:54:31.124036Z","steps":["trace[908608139] 'range keys from in-memory index tree' (duration: 379.747909ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:54:31.124075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:54:30.743948Z","time spent":"380.121757ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T02:54:31.124178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.942312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T02:54:31.124235Z","caller":"traceutil/trace.go:171","msg":"trace[1194553775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1152; }","duration":"359.041056ms","start":"2026-05-21T02:54:30.765179Z","end":"2026-05-21T02:54:31.12422Z","steps":["trace[1194553775] 'range keys from in-memory index tree' (duration: 358.798089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:54:31.124263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:54:30.765156Z","time spent":"359.101456ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T02:54:31.267574Z","caller":"traceutil/trace.go:171","msg":"trace[1229905212] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"137.267424ms","start":"2026-05-21T02:54:31.130279Z","end":"2026-05-21T02:54:31.267546Z","steps":["trace[1229905212] 'process raft request' (duration: 132.835479ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:54:31.267737Z","caller":"traceutil/trace.go:171","msg":"trace[1585354060] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"108.764248ms","start":"2026-05-21T02:54:31.158953Z","end":"2026-05-21T02:54:31.267717Z","steps":["trace[1585354060] 'process raft request' (duration: 108.542863ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:54:32.161761Z","caller":"traceutil/trace.go:171","msg":"trace[1022623400] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"102.087101ms","start":"2026-05-21T02:54:32.059652Z","end":"2026-05-21T02:54:32.161739Z","steps":["trace[1022623400] 'process raft request' (duration: 101.914908ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:54:32.727976Z","caller":"traceutil/trace.go:171","msg":"trace[468247677] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"452.831993ms","start":"2026-05-21T02:54:32.275118Z","end":"2026-05-21T02:54:32.72795Z","steps":["trace[468247677] 'process raft request' (duration: 452.161559ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:54:32.728136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:54:32.275094Z","time spent":"452.959156ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:54:32.728198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.475201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T02:54:32.728264Z","caller":"traceutil/trace.go:171","msg":"trace[1069189443] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1158; }","duration":"182.593074ms","start":"2026-05-21T02:54:32.545658Z","end":"2026-05-21T02:54:32.728251Z","steps":["trace[1069189443] 'agreement among raft nodes before linearized reading' (duration: 182.379079ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:54:32.728286Z","caller":"traceutil/trace.go:171","msg":"trace[1094445684] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1202; }","duration":"182.179705ms","start":"2026-05-21T02:54:32.54571Z","end":"2026-05-21T02:54:32.727889Z","steps":["trace[1094445684] 'read index received' (duration: 181.697845ms)","trace[1094445684] 'applied index is now lower than readState.Index' (duration: 480.76µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:54:32.981578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.151601ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-21T02:54:32.981873Z","caller":"traceutil/trace.go:171","msg":"trace[2096333515] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"250.265066ms","start":"2026-05-21T02:54:32.731586Z","end":"2026-05-21T02:54:32.981851Z","steps":["trace[2096333515] 'process raft request' (duration: 250.110793ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:54:33.305631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.974071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T02:54:33.305717Z","caller":"traceutil/trace.go:171","msg":"trace[440583226] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1159; }","duration":"207.108203ms","start":"2026-05-21T02:54:33.098588Z","end":"2026-05-21T02:54:33.305696Z","steps":["trace[440583226] 'count revisions from in-memory index tree' (duration: 206.897099ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:54:33.419146Z","caller":"traceutil/trace.go:171","msg":"trace[1123321731] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"145.679732ms","start":"2026-05-21T02:54:33.273443Z","end":"2026-05-21T02:54:33.419122Z","steps":["trace[1123321731] 'process raft request' (duration: 145.535109ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:54:33.624026Z","caller":"traceutil/trace.go:171","msg":"trace[609536116] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"314.424608ms","start":"2026-05-21T02:54:33.309559Z","end":"2026-05-21T02:54:33.623984Z","steps":["trace[609536116] 'process raft request' (duration: 314.363247ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:54:33.624076Z","caller":"traceutil/trace.go:171","msg":"trace[331039136] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"315.051683ms","start":"2026-05-21T02:54:33.308991Z","end":"2026-05-21T02:54:33.624043Z","steps":["trace[331039136] 'process raft request' (duration: 305.706846ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:54:33.624214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:54:33.30955Z","time spent":"314.558291ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T02:54:33.624237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:54:33.308973Z","time spent":"315.188495ms","remote":"127.0.0.1:39244","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-05-21T02:55:03.692977Z","caller":"traceutil/trace.go:171","msg":"trace[141330453] linearizableReadLoop","detail":"{readStateIndex:1309; appliedIndex:1308; }","duration":"353.265695ms","start":"2026-05-21T02:55:03.339686Z","end":"2026-05-21T02:55:03.692951Z","steps":["trace[141330453] 'read index received' (duration: 353.112161ms)","trace[141330453] 'applied index is now lower than readState.Index' (duration: 152.084µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:55:03.693207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.497349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T02:55:03.693294Z","caller":"traceutil/trace.go:171","msg":"trace[1720612599] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1257; }","duration":"353.642422ms","start":"2026-05-21T02:55:03.339632Z","end":"2026-05-21T02:55:03.693275Z","steps":["trace[1720612599] 'agreement among raft nodes before linearized reading' (duration: 353.420808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:55:03.693343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:55:03.339613Z","time spent":"353.719613ms","remote":"127.0.0.1:39414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-21T02:55:03.693669Z","caller":"traceutil/trace.go:171","msg":"trace[962705843] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"461.195419ms","start":"2026-05-21T02:55:03.232442Z","end":"2026-05-21T02:55:03.693638Z","steps":["trace[962705843] 'process raft request' (duration: 460.353093ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:55:03.693912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:55:03.232414Z","time spent":"461.393333ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:55:03.774558Z","caller":"traceutil/trace.go:171","msg":"trace[927051823] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"183.660362ms","start":"2026-05-21T02:55:03.590879Z","end":"2026-05-21T02:55:03.77454Z","steps":["trace[927051823] 'process raft request' (duration: 146.073808ms)","trace[927051823] 'compare' (duration: 37.448611ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:55:03.774892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.517915ms","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:12751"} {"level":"info","ts":"2026-05-21T02:55:03.774948Z","caller":"traceutil/trace.go:171","msg":"trace[959195431] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:1259; }","duration":"210.601607ms","start":"2026-05-21T02:55:03.564331Z","end":"2026-05-21T02:55:03.774933Z","steps":["trace[959195431] 'agreement among raft nodes before linearized reading' (duration: 210.445474ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:55:06.019577Z","caller":"traceutil/trace.go:171","msg":"trace[617469774] linearizableReadLoop","detail":"{readStateIndex:1317; appliedIndex:1316; }","duration":"250.317653ms","start":"2026-05-21T02:55:05.769236Z","end":"2026-05-21T02:55:06.019554Z","steps":["trace[617469774] 'read index received' (duration: 250.17974ms)","trace[617469774] 'applied index is now lower than readState.Index' (duration: 136.953µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:55:06.01962Z","caller":"traceutil/trace.go:171","msg":"trace[1541948194] transaction","detail":"{read_only:false; response_revision:1265; number_of_response:1; }","duration":"317.410615ms","start":"2026-05-21T02:55:05.702181Z","end":"2026-05-21T02:55:06.019592Z","steps":["trace[1541948194] 'process raft request' (duration: 317.244422ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:55:06.019748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.485955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:55:06.019814Z","caller":"traceutil/trace.go:171","msg":"trace[793648501] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1265; }","duration":"250.605827ms","start":"2026-05-21T02:55:05.769192Z","end":"2026-05-21T02:55:06.019798Z","steps":["trace[793648501] 'agreement among raft nodes before linearized reading' (duration: 250.487305ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:55:06.019832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:55:05.702164Z","time spent":"317.587158ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T02:55:06.028528Z","caller":"traceutil/trace.go:171","msg":"trace[568095585] transaction","detail":"{read_only:false; response_revision:1266; number_of_response:1; }","duration":"248.433038ms","start":"2026-05-21T02:55:05.780051Z","end":"2026-05-21T02:55:06.028484Z","steps":["trace[568095585] 'process raft request' (duration: 248.294715ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:55:06.02861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.89379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-21T02:55:06.028714Z","caller":"traceutil/trace.go:171","msg":"trace[1285986685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1266; }","duration":"157.027291ms","start":"2026-05-21T02:55:05.87167Z","end":"2026-05-21T02:55:06.028697Z","steps":["trace[1285986685] 'agreement among raft nodes before linearized reading' (duration: 156.781347ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:55:06.028814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.682339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-21T02:55:06.028889Z","caller":"traceutil/trace.go:171","msg":"trace[664493452] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1266; }","duration":"131.966713ms","start":"2026-05-21T02:55:05.896907Z","end":"2026-05-21T02:55:06.028874Z","steps":["trace[664493452] 'agreement among raft nodes before linearized reading' (duration: 131.594837ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:55:08.466497Z","caller":"traceutil/trace.go:171","msg":"trace[382386338] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"102.272183ms","start":"2026-05-21T02:55:08.364196Z","end":"2026-05-21T02:55:08.466468Z","steps":["trace[382386338] 'process raft request' (duration: 102.094219ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:55:08.655587Z","caller":"traceutil/trace.go:171","msg":"trace[1687905058] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"187.110403ms","start":"2026-05-21T02:55:08.468447Z","end":"2026-05-21T02:55:08.655557Z","steps":["trace[1687905058] 'process raft request' (duration: 149.865992ms)","trace[1687905058] 'compare' (duration: 37.124479ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:55:08.679602Z","caller":"traceutil/trace.go:171","msg":"trace[1784854945] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"208.275968ms","start":"2026-05-21T02:55:08.471299Z","end":"2026-05-21T02:55:08.679575Z","steps":["trace[1784854945] 'process raft request' (duration: 208.107495ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:55:08.679871Z","caller":"traceutil/trace.go:171","msg":"trace[361464412] linearizableReadLoop","detail":"{readStateIndex:1334; appliedIndex:1332; }","duration":"208.38284ms","start":"2026-05-21T02:55:08.471473Z","end":"2026-05-21T02:55:08.679856Z","steps":["trace[361464412] 'read index received' (duration: 146.84833ms)","trace[361464412] 'applied index is now lower than readState.Index' (duration: 61.53356ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T02:55:08.679875Z","caller":"traceutil/trace.go:171","msg":"trace[1218737070] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"208.339649ms","start":"2026-05-21T02:55:08.471517Z","end":"2026-05-21T02:55:08.679857Z","steps":["trace[1218737070] 'process raft request' (duration: 208.007223ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:55:08.680018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.529883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1490"} {"level":"info","ts":"2026-05-21T02:55:08.680058Z","caller":"traceutil/trace.go:171","msg":"trace[1473978520] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1282; }","duration":"208.600723ms","start":"2026-05-21T02:55:08.471447Z","end":"2026-05-21T02:55:08.680048Z","steps":["trace[1473978520] 'agreement among raft nodes before linearized reading' (duration: 208.468841ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:55:08.679975Z","caller":"traceutil/trace.go:171","msg":"trace[1350610164] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"207.82472ms","start":"2026-05-21T02:55:08.472078Z","end":"2026-05-21T02:55:08.679903Z","steps":["trace[1350610164] 'process raft request' (duration: 207.709288ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:55:17.480112Z","caller":"traceutil/trace.go:171","msg":"trace[1592236054] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"130.636832ms","start":"2026-05-21T02:55:17.349442Z","end":"2026-05-21T02:55:17.480078Z","steps":["trace[1592236054] 'process raft request' (duration: 129.070037ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T02:55:25.484111Z","caller":"traceutil/trace.go:171","msg":"trace[1155545980] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1426; }","duration":"417.697439ms","start":"2026-05-21T02:55:25.066391Z","end":"2026-05-21T02:55:25.484088Z","steps":["trace[1155545980] 'read index received' (duration: 417.452595ms)","trace[1155545980] 'applied index is now lower than readState.Index' (duration: 244.014µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T02:55:25.484367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.962823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T02:55:25.484424Z","caller":"traceutil/trace.go:171","msg":"trace[1556886255] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1369; }","duration":"418.072345ms","start":"2026-05-21T02:55:25.066336Z","end":"2026-05-21T02:55:25.484408Z","steps":["trace[1556886255] 'agreement among raft nodes before linearized reading' (duration: 417.853701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:55:25.484475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:55:25.066317Z","time spent":"418.144595ms","remote":"127.0.0.1:39130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-05-21T02:55:25.484477Z","caller":"traceutil/trace.go:171","msg":"trace[197776479] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"421.266305ms","start":"2026-05-21T02:55:25.063183Z","end":"2026-05-21T02:55:25.484449Z","steps":["trace[197776479] 'process raft request' (duration: 420.760757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T02:55:25.484604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T02:55:25.063165Z","time spent":"421.373098ms","remote":"127.0.0.1:39244","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}