{"level":"warn","ts":"2026-04-20T20:53:30.828791Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T20:53:30.828912Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.227:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.227:2380","--initial-cluster=instance=https://199.204.45.227:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.227:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.227: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-04-20T20:53:30.828998Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T20:53:30.829009Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-04-20T20:53:30.829042Z","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-04-20T20:53:30.829878Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"]} {"level":"info","ts":"2026-04-20T20:53:30.830004Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.227: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-04-20T20:53:30.836067Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.804408ms"} {"level":"info","ts":"2026-04-20T20:53:30.845014Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"572db18442eba1c2","cluster-id":"fb1028a037bf3cd6"} {"level":"info","ts":"2026-04-20T20:53:30.845098Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T20:53:30.845131Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 0"} {"level":"info","ts":"2026-04-20T20:53:30.845148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 572db18442eba1c2 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T20:53:30.845162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became follower at term 1"} {"level":"info","ts":"2026-04-20T20:53:30.845205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"warn","ts":"2026-04-20T20:53:30.857729Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T20:53:30.859011Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T20:53:30.860404Z","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-04-20T20:53:30.862518Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"572db18442eba1c2","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T20:53:30.862658Z","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-04-20T20:53:30.862732Z","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-04-20T20:53:30.862742Z","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-04-20T20:53:30.863752Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"572db18442eba1c2","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T20:53:30.864913Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 switched to configuration voters=(6281872236868248002)"} {"level":"info","ts":"2026-04-20T20:53:30.865087Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","added-peer-id":"572db18442eba1c2","added-peer-peer-urls":["https://199.204.45.227:2380"]} {"level":"info","ts":"2026-04-20T20:53:30.869563Z","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-04-20T20:53:30.869829Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"572db18442eba1c2","initial-advertise-peer-urls":["https://199.204.45.227:2380"],"listen-peer-urls":["https://199.204.45.227:2380"],"advertise-client-urls":["https://199.204.45.227:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.227:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T20:53:30.869865Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T20:53:30.869983Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-04-20T20:53:30.870004Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.227:2380"} {"level":"info","ts":"2026-04-20T20:53:31.146692Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T20:53:31.146751Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T20:53:31.146782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgPreVoteResp from 572db18442eba1c2 at term 1"} {"level":"info","ts":"2026-04-20T20:53:31.146797Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became candidate at term 2"} {"level":"info","ts":"2026-04-20T20:53:31.146802Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 received MsgVoteResp from 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-04-20T20:53:31.146812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"572db18442eba1c2 became leader at term 2"} {"level":"info","ts":"2026-04-20T20:53:31.146821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 572db18442eba1c2 elected leader 572db18442eba1c2 at term 2"} {"level":"info","ts":"2026-04-20T20:53:31.151076Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"572db18442eba1c2","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.227:2379]}","request-path":"/0/members/572db18442eba1c2/attributes","cluster-id":"fb1028a037bf3cd6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T20:53:31.151438Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T20:53:31.151475Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T20:53:31.151792Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:53:31.152054Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T20:53:31.152088Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T20:53:31.152659Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fb1028a037bf3cd6","local-member-id":"572db18442eba1c2","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:53:31.152786Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:53:31.152835Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T20:53:31.16555Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.227:2379"} {"level":"info","ts":"2026-04-20T20:53:31.166781Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T20:53:49.715615Z","caller":"traceutil/trace.go:171","msg":"trace[1559446072] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"424.023689ms","start":"2026-04-20T20:53:49.291562Z","end":"2026-04-20T20:53:49.715586Z","steps":["trace[1559446072] 'read index received' (duration: 423.807372ms)","trace[1559446072] 'applied index is now lower than readState.Index' (duration: 214.827µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:53:49.715729Z","caller":"traceutil/trace.go:171","msg":"trace[2043832914] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"572.820013ms","start":"2026-04-20T20:53:49.142832Z","end":"2026-04-20T20:53:49.715652Z","steps":["trace[2043832914] 'process raft request' (duration: 572.595767ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:53:49.716041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.569509ms","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-04-20T20:53:49.715915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.336427ms","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-04-20T20:53:49.716153Z","caller":"traceutil/trace.go:171","msg":"trace[2041853424] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:372; }","duration":"147.746245ms","start":"2026-04-20T20:53:49.568381Z","end":"2026-04-20T20:53:49.716127Z","steps":["trace[2041853424] 'agreement among raft nodes before linearized reading' (duration: 147.529578ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:53:49.716185Z","caller":"traceutil/trace.go:171","msg":"trace[1261880037] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:372; }","duration":"424.663078ms","start":"2026-04-20T20:53:49.29148Z","end":"2026-04-20T20:53:49.716143Z","steps":["trace[1261880037] 'agreement among raft nodes before linearized reading' (duration: 424.358808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:53:49.716273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:53:49.291443Z","time spent":"424.788321ms","remote":"127.0.0.1:58088","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-04-20T20:53:49.717264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:53:49.142817Z","time spent":"573.266037ms","remote":"127.0.0.1:58088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:53:49.914235Z","caller":"traceutil/trace.go:171","msg":"trace[582560354] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"192.864271ms","start":"2026-04-20T20:53:49.721342Z","end":"2026-04-20T20:53:49.914207Z","steps":["trace[582560354] 'process raft request' (duration: 190.657856ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:53:49.920142Z","caller":"traceutil/trace.go:171","msg":"trace[634646623] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"197.805766ms","start":"2026-04-20T20:53:49.722318Z","end":"2026-04-20T20:53:49.920124Z","steps":["trace[634646623] 'process raft request' (duration: 197.572929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:53:50.678579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.34039ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T20:53:50.678695Z","caller":"traceutil/trace.go:171","msg":"trace[472814458] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:389; }","duration":"378.45511ms","start":"2026-04-20T20:53:50.30022Z","end":"2026-04-20T20:53:50.678675Z","steps":["trace[472814458] 'read index received' (duration: 48.362µs)","trace[472814458] 'applied index is now lower than readState.Index' (duration: 378.404988ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:53:50.678959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.722487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:53:50.679127Z","caller":"traceutil/trace.go:171","msg":"trace[172723481] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:374; }","duration":"378.861782ms","start":"2026-04-20T20:53:50.300144Z","end":"2026-04-20T20:53:50.679006Z","steps":["trace[172723481] 'agreement among raft nodes before linearized reading' (duration: 378.582884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:53:50.679239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:53:50.300111Z","time spent":"379.111519ms","remote":"127.0.0.1:57856","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-04-20T20:53:56.051835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.450499ms","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-04-20T20:53:56.05183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.441325ms","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-04-20T20:53:56.051894Z","caller":"traceutil/trace.go:171","msg":"trace[101079684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:387; }","duration":"110.555781ms","start":"2026-04-20T20:53:55.941324Z","end":"2026-04-20T20:53:56.05188Z","steps":["trace[101079684] 'range keys from in-memory index tree' (duration: 110.344146ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:53:56.051912Z","caller":"traceutil/trace.go:171","msg":"trace[1738034125] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:387; }","duration":"111.564139ms","start":"2026-04-20T20:53:55.940329Z","end":"2026-04-20T20:53:56.051893Z","steps":["trace[1738034125] 'range keys from in-memory index tree' (duration: 111.336473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:08.932646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.325771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:54:08.932908Z","caller":"traceutil/trace.go:171","msg":"trace[774873588] linearizableReadLoop","detail":"{readStateIndex:473; appliedIndex:471; }","duration":"108.996937ms","start":"2026-04-20T20:54:08.823889Z","end":"2026-04-20T20:54:08.932886Z","steps":["trace[774873588] 'read index received' (duration: 393.899µs)","trace[774873588] 'applied index is now lower than readState.Index' (duration: 108.600328ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:54:08.933144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.209872ms","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-04-20T20:54:08.933094Z","caller":"traceutil/trace.go:171","msg":"trace[322003240] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"191.944185ms","start":"2026-04-20T20:54:08.741098Z","end":"2026-04-20T20:54:08.933042Z","steps":["trace[322003240] 'process raft request' (duration: 83.138352ms)","trace[322003240] 'compare' (duration: 108.176528ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:54:08.933189Z","caller":"traceutil/trace.go:171","msg":"trace[2020225055] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:452; }","duration":"109.336275ms","start":"2026-04-20T20:54:08.82384Z","end":"2026-04-20T20:54:08.933176Z","steps":["trace[2020225055] 'agreement among raft nodes before linearized reading' (duration: 109.14354ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:08.933149Z","caller":"traceutil/trace.go:171","msg":"trace[84898879] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"191.970995ms","start":"2026-04-20T20:54:08.741123Z","end":"2026-04-20T20:54:08.933094Z","steps":["trace[84898879] 'process raft request' (duration: 191.664207ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:09.147012Z","caller":"traceutil/trace.go:171","msg":"trace[1341634105] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"113.2086ms","start":"2026-04-20T20:54:09.033778Z","end":"2026-04-20T20:54:09.146986Z","steps":["trace[1341634105] 'process raft request' (duration: 113.047196ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:09.376866Z","caller":"traceutil/trace.go:171","msg":"trace[1463740872] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"206.383026ms","start":"2026-04-20T20:54:09.17045Z","end":"2026-04-20T20:54:09.376833Z","steps":["trace[1463740872] 'process raft request' (duration: 140.101524ms)","trace[1463740872] 'compare' (duration: 66.069228ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:54:09.377601Z","caller":"traceutil/trace.go:171","msg":"trace[510705531] transaction","detail":"{read_only:false; response_revision:460; number_of_response:1; }","duration":"204.088073ms","start":"2026-04-20T20:54:09.173496Z","end":"2026-04-20T20:54:09.377584Z","steps":["trace[510705531] 'process raft request' (duration: 203.763545ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:09.61214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.276476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:54:09.612311Z","caller":"traceutil/trace.go:171","msg":"trace[1336083082] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"228.064478ms","start":"2026-04-20T20:54:09.384201Z","end":"2026-04-20T20:54:09.612266Z","steps":["trace[1336083082] 'process raft request' (duration: 120.962482ms)","trace[1336083082] 'compare' (duration: 105.837126ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:54:09.612509Z","caller":"traceutil/trace.go:171","msg":"trace[1638524696] linearizableReadLoop","detail":"{readStateIndex:482; appliedIndex:481; }","duration":"227.996467ms","start":"2026-04-20T20:54:09.384458Z","end":"2026-04-20T20:54:09.612455Z","steps":["trace[1638524696] 'read index received' (duration: 120.747377ms)","trace[1638524696] 'applied index is now lower than readState.Index' (duration: 107.241849ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:54:09.612915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.428806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:54:09.612979Z","caller":"traceutil/trace.go:171","msg":"trace[1387765497] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:461; }","duration":"228.515358ms","start":"2026-04-20T20:54:09.384445Z","end":"2026-04-20T20:54:09.612961Z","steps":["trace[1387765497] 'agreement among raft nodes before linearized reading' (duration: 228.15763ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:09.613997Z","caller":"traceutil/trace.go:171","msg":"trace[1313299421] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"154.049413ms","start":"2026-04-20T20:54:09.459912Z","end":"2026-04-20T20:54:09.613962Z","steps":["trace[1313299421] 'process raft request' (duration: 153.062689ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:11.405661Z","caller":"traceutil/trace.go:171","msg":"trace[367356472] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"220.715126ms","start":"2026-04-20T20:54:11.184925Z","end":"2026-04-20T20:54:11.40564Z","steps":["trace[367356472] 'process raft request' (duration: 220.615234ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:11.418598Z","caller":"traceutil/trace.go:171","msg":"trace[2102573296] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"218.467775ms","start":"2026-04-20T20:54:11.200095Z","end":"2026-04-20T20:54:11.418563Z","steps":["trace[2102573296] 'process raft request' (duration: 218.26203ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:11.41875Z","caller":"traceutil/trace.go:171","msg":"trace[2082915750] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"218.584147ms","start":"2026-04-20T20:54:11.200156Z","end":"2026-04-20T20:54:11.41874Z","steps":["trace[2082915750] 'process raft request' (duration: 218.308801ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:11.418676Z","caller":"traceutil/trace.go:171","msg":"trace[2111124830] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"206.486188ms","start":"2026-04-20T20:54:11.212154Z","end":"2026-04-20T20:54:11.41864Z","steps":["trace[2111124830] 'process raft request' (duration: 206.341935ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:11.41904Z","caller":"traceutil/trace.go:171","msg":"trace[1233146474] linearizableReadLoop","detail":"{readStateIndex:519; appliedIndex:509; }","duration":"117.862692ms","start":"2026-04-20T20:54:11.301153Z","end":"2026-04-20T20:54:11.419016Z","steps":["trace[1233146474] 'read index received' (duration: 104.806141ms)","trace[1233146474] 'applied index is now lower than readState.Index' (duration: 13.055441ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:54:11.419071Z","caller":"traceutil/trace.go:171","msg":"trace[1567047842] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"179.069785ms","start":"2026-04-20T20:54:11.239984Z","end":"2026-04-20T20:54:11.419054Z","steps":["trace[1567047842] 'process raft request' (duration: 178.645566ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:11.419112Z","caller":"traceutil/trace.go:171","msg":"trace[2012525191] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"191.181943ms","start":"2026-04-20T20:54:11.227918Z","end":"2026-04-20T20:54:11.4191Z","steps":["trace[2012525191] 'process raft request' (duration: 190.610991ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:11.419152Z","caller":"traceutil/trace.go:171","msg":"trace[1386479570] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"191.137033ms","start":"2026-04-20T20:54:11.228Z","end":"2026-04-20T20:54:11.419137Z","steps":["trace[1386479570] 'process raft request' (duration: 190.55943ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:11.419187Z","caller":"traceutil/trace.go:171","msg":"trace[2013092912] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"186.396984ms","start":"2026-04-20T20:54:11.232783Z","end":"2026-04-20T20:54:11.419179Z","steps":["trace[2013092912] 'process raft request' (duration: 185.805751ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:11.41925Z","caller":"traceutil/trace.go:171","msg":"trace[1502468917] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"153.477694ms","start":"2026-04-20T20:54:11.265763Z","end":"2026-04-20T20:54:11.419241Z","steps":["trace[1502468917] 'process raft request' (duration: 153.006563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:11.419327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.165138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:54:11.41977Z","caller":"traceutil/trace.go:171","msg":"trace[526854976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"118.620129ms","start":"2026-04-20T20:54:11.301138Z","end":"2026-04-20T20:54:11.419758Z","steps":["trace[526854976] 'agreement among raft nodes before linearized reading' (duration: 118.118597ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:11.419913Z","caller":"traceutil/trace.go:171","msg":"trace[1785229188] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"175.531694ms","start":"2026-04-20T20:54:11.244367Z","end":"2026-04-20T20:54:11.419899Z","steps":["trace[1785229188] 'process raft request' (duration: 174.303376ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:11.420047Z","caller":"traceutil/trace.go:171","msg":"trace[1127303931] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"170.796024ms","start":"2026-04-20T20:54:11.249224Z","end":"2026-04-20T20:54:11.42002Z","steps":["trace[1127303931] 'process raft request' (duration: 169.491594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:11.830959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.31295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:54:11.831235Z","caller":"traceutil/trace.go:171","msg":"trace[67017861] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"419.190769ms","start":"2026-04-20T20:54:11.411993Z","end":"2026-04-20T20:54:11.831184Z","steps":["trace[67017861] 'process raft request' (duration: 278.557042ms)","trace[67017861] 'compare' (duration: 140.068395ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:54:11.831339Z","caller":"traceutil/trace.go:171","msg":"trace[836479154] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"418.644047ms","start":"2026-04-20T20:54:11.412667Z","end":"2026-04-20T20:54:11.831311Z","steps":["trace[836479154] 'process raft request' (duration: 418.447483ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:11.831399Z","caller":"traceutil/trace.go:171","msg":"trace[1364929997] transaction","detail":"{read_only:false; number_of_response:1; response_revision:500; }","duration":"417.401489ms","start":"2026-04-20T20:54:11.413958Z","end":"2026-04-20T20:54:11.831359Z","steps":["trace[1364929997] 'process raft request' (duration: 417.242155ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:11.831454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:11.411956Z","time spent":"419.393185ms","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:54:11.831644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:11.413945Z","time spent":"417.559423ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:54:11.831273Z","caller":"traceutil/trace.go:171","msg":"trace[1121670651] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:520; }","duration":"406.977138ms","start":"2026-04-20T20:54:11.424271Z","end":"2026-04-20T20:54:11.831248Z","steps":["trace[1121670651] 'read index received' (duration: 266.274569ms)","trace[1121670651] 'applied index is now lower than readState.Index' (duration: 140.701289ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:54:11.831701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.390468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T20:54:11.831552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:11.412654Z","time spent":"418.769752ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:54:11.831748Z","caller":"traceutil/trace.go:171","msg":"trace[1726320939] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"407.49928ms","start":"2026-04-20T20:54:11.424237Z","end":"2026-04-20T20:54:11.831736Z","steps":["trace[1726320939] 'agreement among raft nodes before linearized reading' (duration: 407.073849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:11.8318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:11.42422Z","time spent":"407.571272ms","remote":"127.0.0.1:57856","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-04-20T20:54:12.462855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.527667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:54:12.463214Z","caller":"traceutil/trace.go:171","msg":"trace[1538040114] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"1.032591306s","start":"2026-04-20T20:54:11.430582Z","end":"2026-04-20T20:54:12.463174Z","steps":["trace[1538040114] 'process raft request' (duration: 557.648968ms)","trace[1538040114] 'compare' (duration: 474.281802ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:54:12.463271Z","caller":"traceutil/trace.go:171","msg":"trace[1211665557] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"1.026538645s","start":"2026-04-20T20:54:11.436708Z","end":"2026-04-20T20:54:12.463246Z","steps":["trace[1211665557] 'process raft request' (duration: 1.02628589s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:12.463358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:11.430565Z","time spent":"1.032727689s","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:54:12.463375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:11.436674Z","time spent":"1.026648818s","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:54:12.463504Z","caller":"traceutil/trace.go:171","msg":"trace[2027337679] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"1.026632058s","start":"2026-04-20T20:54:11.436857Z","end":"2026-04-20T20:54:12.46349Z","steps":["trace[2027337679] 'process raft request' (duration: 1.026413483s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:12.463581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:11.436841Z","time spent":"1.026691719s","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:54:12.463707Z","caller":"traceutil/trace.go:171","msg":"trace[1444502309] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"1.02627924s","start":"2026-04-20T20:54:11.437419Z","end":"2026-04-20T20:54:12.463698Z","steps":["trace[1444502309] 'process raft request' (duration: 1.025938193s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:12.463748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:11.437373Z","time spent":"1.026358202s","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:54:12.463982Z","caller":"traceutil/trace.go:171","msg":"trace[392299429] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"1.019125534s","start":"2026-04-20T20:54:11.444848Z","end":"2026-04-20T20:54:12.463974Z","steps":["trace[392299429] 'process raft request' (duration: 1.018619563s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:12.464047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:11.444841Z","time spent":"1.019171725s","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:54:12.464042Z","caller":"traceutil/trace.go:171","msg":"trace[807299305] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"996.0122ms","start":"2026-04-20T20:54:11.468005Z","end":"2026-04-20T20:54:12.464017Z","steps":["trace[807299305] 'process raft request' (duration: 995.765305ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:12.464041Z","caller":"traceutil/trace.go:171","msg":"trace[1436427816] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"1.015623283s","start":"2026-04-20T20:54:11.448388Z","end":"2026-04-20T20:54:12.464011Z","steps":["trace[1436427816] 'process raft request' (duration: 1.015196884s)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:12.464102Z","caller":"traceutil/trace.go:171","msg":"trace[1415815452] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"1.023231378s","start":"2026-04-20T20:54:11.440864Z","end":"2026-04-20T20:54:12.464096Z","steps":["trace[1415815452] 'process raft request' (duration: 1.022537993s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:12.464133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:11.440847Z","time spent":"1.02327304s","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:54:12.464167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:11.467994Z","time spent":"996.103562ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:54:12.464197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:11.448369Z","time spent":"1.015745725s","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:54:12.464398Z","caller":"traceutil/trace.go:171","msg":"trace[1662199265] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"971.689369ms","start":"2026-04-20T20:54:11.492701Z","end":"2026-04-20T20:54:12.46439Z","steps":["trace[1662199265] 'process raft request' (duration: 971.182246ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:12.464435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:11.492649Z","time spent":"971.77234ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T20:54:12.464586Z","caller":"traceutil/trace.go:171","msg":"trace[1424431110] transaction","detail":"{read_only:false; number_of_response:1; response_revision:509; }","duration":"622.827183ms","start":"2026-04-20T20:54:11.841751Z","end":"2026-04-20T20:54:12.464578Z","steps":["trace[1424431110] 'process raft request' (duration: 622.549537ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:12.464592Z","caller":"traceutil/trace.go:171","msg":"trace[1335179694] linearizableReadLoop","detail":"{readStateIndex:534; appliedIndex:523; }","duration":"558.891757ms","start":"2026-04-20T20:54:11.905684Z","end":"2026-04-20T20:54:12.464576Z","steps":["trace[1335179694] 'read index received' (duration: 82.595048ms)","trace[1335179694] 'applied index is now lower than readState.Index' (duration: 476.295749ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:54:12.464629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:11.841722Z","time spent":"622.884504ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:54:12.464685Z","caller":"traceutil/trace.go:171","msg":"trace[1898359456] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"616.758703ms","start":"2026-04-20T20:54:11.847916Z","end":"2026-04-20T20:54:12.464675Z","steps":["trace[1898359456] 'process raft request' (duration: 616.517528ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:12.464744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:11.847891Z","time spent":"616.821635ms","remote":"127.0.0.1:57888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:54:12.464859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.210849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-20T20:54:12.4649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.224034ms","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-04-20T20:54:12.464932Z","caller":"traceutil/trace.go:171","msg":"trace[1589642967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"559.291475ms","start":"2026-04-20T20:54:11.905633Z","end":"2026-04-20T20:54:12.464925Z","steps":["trace[1589642967] 'agreement among raft nodes before linearized reading' (duration: 559.187413ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:12.464952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:11.90562Z","time spent":"559.328056ms","remote":"127.0.0.1:58088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T20:54:12.464902Z","caller":"traceutil/trace.go:171","msg":"trace[978011781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:510; }","duration":"229.307071ms","start":"2026-04-20T20:54:12.235584Z","end":"2026-04-20T20:54:12.464891Z","steps":["trace[978011781] 'agreement among raft nodes before linearized reading' (duration: 229.162758ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:12.464991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.952148ms","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-04-20T20:54:12.465029Z","caller":"traceutil/trace.go:171","msg":"trace[1936370220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:510; }","duration":"189.053251ms","start":"2026-04-20T20:54:12.275967Z","end":"2026-04-20T20:54:12.46502Z","steps":["trace[1936370220] 'agreement among raft nodes before linearized reading' (duration: 188.952718ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:19.208663Z","caller":"traceutil/trace.go:171","msg":"trace[147806531] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"541.610151ms","start":"2026-04-20T20:54:18.667031Z","end":"2026-04-20T20:54:19.208641Z","steps":["trace[147806531] 'process raft request' (duration: 541.472328ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:19.208811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:18.667003Z","time spent":"541.737584ms","remote":"127.0.0.1:58014","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-04-20T20:54:19.528918Z","caller":"traceutil/trace.go:171","msg":"trace[1960590873] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:624; }","duration":"466.370528ms","start":"2026-04-20T20:54:19.062519Z","end":"2026-04-20T20:54:19.528889Z","steps":["trace[1960590873] 'read index received' (duration: 146.040389ms)","trace[1960590873] 'applied index is now lower than readState.Index' (duration: 320.329249ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:54:19.529082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.725689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:54:19.529121Z","caller":"traceutil/trace.go:171","msg":"trace[268624613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:600; }","duration":"228.82003ms","start":"2026-04-20T20:54:19.300289Z","end":"2026-04-20T20:54:19.529109Z","steps":["trace[268624613] 'agreement among raft nodes before linearized reading' (duration: 228.740589ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:19.52903Z","caller":"traceutil/trace.go:171","msg":"trace[1371329021] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"781.132883ms","start":"2026-04-20T20:54:18.747826Z","end":"2026-04-20T20:54:19.528958Z","steps":["trace[1371329021] 'process raft request' (duration: 780.444989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:19.529308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.766896ms","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-04-20T20:54:19.529467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:18.747805Z","time spent":"781.455091ms","remote":"127.0.0.1:57934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:54:19.529509Z","caller":"traceutil/trace.go:171","msg":"trace[620621893] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:600; }","duration":"467.013122ms","start":"2026-04-20T20:54:19.062472Z","end":"2026-04-20T20:54:19.529485Z","steps":["trace[620621893] 'agreement among raft nodes before linearized reading' (duration: 466.511871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:19.529668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:19.062452Z","time spent":"467.199027ms","remote":"127.0.0.1:58088","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-04-20T20:54:20.155783Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11656052150524731938,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-20T20:54:20.934017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340454502s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T20:54:20.934234Z","caller":"traceutil/trace.go:171","msg":"trace[1565768869] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.399380126s","start":"2026-04-20T20:54:19.534824Z","end":"2026-04-20T20:54:20.934204Z","steps":["trace[1565768869] 'process raft request' (duration: 58.638988ms)","trace[1565768869] 'compare' (duration: 1.340031002s)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:54:20.934337Z","caller":"traceutil/trace.go:171","msg":"trace[140696882] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:626; }","duration":"1.279424072s","start":"2026-04-20T20:54:19.654883Z","end":"2026-04-20T20:54:20.934307Z","steps":["trace[140696882] 'read index received' (duration: 787.576705ms)","trace[140696882] 'applied index is now lower than readState.Index' (duration: 491.844887ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:54:20.934376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:19.534667Z","time spent":"1.399629661s","remote":"127.0.0.1:58088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T20:54:20.934358Z","caller":"traceutil/trace.go:171","msg":"trace[593486439] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.348025742s","start":"2026-04-20T20:54:19.586278Z","end":"2026-04-20T20:54:20.934304Z","steps":["trace[593486439] 'process raft request' (duration: 1.347848998s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:20.934717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:19.586249Z","time spent":"1.34839434s","remote":"127.0.0.1:58072","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T20:54:21.102495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.267386ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-20T20:54:21.102599Z","caller":"traceutil/trace.go:171","msg":"trace[819472179] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"168.188655ms","start":"2026-04-20T20:54:20.934392Z","end":"2026-04-20T20:54:21.102581Z","steps":["trace[819472179] 'read index received' (duration: 51.251µs)","trace[819472179] 'applied index is now lower than readState.Index' (duration: 168.136083ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:54:21.10269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.44779684s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:54:21.102769Z","caller":"traceutil/trace.go:171","msg":"trace[1876351005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"1.447914233s","start":"2026-04-20T20:54:19.654837Z","end":"2026-04-20T20:54:21.102751Z","steps":["trace[1876351005] 'agreement among raft nodes before linearized reading' (duration: 1.279590505s)","trace[1876351005] 'range keys from in-memory index tree' (duration: 168.181875ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:54:21.102838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:19.654819Z","time spent":"1.448007354s","remote":"127.0.0.1:57842","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-04-20T20:54:21.102861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.447709ms","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-04-20T20:54:21.102903Z","caller":"traceutil/trace.go:171","msg":"trace[729839431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:602; }","duration":"491.52426ms","start":"2026-04-20T20:54:20.611369Z","end":"2026-04-20T20:54:21.102894Z","steps":["trace[729839431] 'agreement among raft nodes before linearized reading' (duration: 491.311876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:21.102888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.178622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-04-20T20:54:21.102924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.057337ms","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-04-20T20:54:21.102996Z","caller":"traceutil/trace.go:171","msg":"trace[1541369595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:602; }","duration":"581.155439ms","start":"2026-04-20T20:54:20.52182Z","end":"2026-04-20T20:54:21.102976Z","steps":["trace[1541369595] 'agreement among raft nodes before linearized reading' (duration: 580.986756ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:21.102992Z","caller":"traceutil/trace.go:171","msg":"trace[1166678294] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:602; }","duration":"165.335174ms","start":"2026-04-20T20:54:20.937632Z","end":"2026-04-20T20:54:21.102967Z","steps":["trace[1166678294] 'agreement among raft nodes before linearized reading' (duration: 165.13462ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:21.103045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:20.521797Z","time spent":"581.239702ms","remote":"127.0.0.1:58088","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-04-20T20:54:21.102937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:20.611351Z","time spent":"491.579062ms","remote":"127.0.0.1:58088","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-04-20T20:54:21.103208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.150564ms","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-04-20T20:54:21.103252Z","caller":"traceutil/trace.go:171","msg":"trace[1340681973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:602; }","duration":"492.322928ms","start":"2026-04-20T20:54:20.610917Z","end":"2026-04-20T20:54:21.10324Z","steps":["trace[1340681973] 'agreement among raft nodes before linearized reading' (duration: 491.805806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:21.102759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.364653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T20:54:21.103296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:20.610895Z","time spent":"492.393319ms","remote":"127.0.0.1:58088","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-04-20T20:54:21.103335Z","caller":"traceutil/trace.go:171","msg":"trace[920177998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:602; }","duration":"802.961756ms","start":"2026-04-20T20:54:20.300335Z","end":"2026-04-20T20:54:21.103296Z","steps":["trace[920177998] 'agreement among raft nodes before linearized reading' (duration: 802.353613ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:21.103409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:20.300316Z","time spent":"803.077038ms","remote":"127.0.0.1:57856","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-04-20T20:54:23.09244Z","caller":"traceutil/trace.go:171","msg":"trace[335135015] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"154.326781ms","start":"2026-04-20T20:54:22.938094Z","end":"2026-04-20T20:54:23.092421Z","steps":["trace[335135015] 'read index received' (duration: 154.191068ms)","trace[335135015] 'applied index is now lower than readState.Index' (duration: 134.203µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:54:23.092502Z","caller":"traceutil/trace.go:171","msg":"trace[2086873818] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"198.160647ms","start":"2026-04-20T20:54:22.894319Z","end":"2026-04-20T20:54:23.09248Z","steps":["trace[2086873818] 'process raft request' (duration: 197.940243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:23.092606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.502805ms","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-04-20T20:54:23.09264Z","caller":"traceutil/trace.go:171","msg":"trace[481512535] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"154.576247ms","start":"2026-04-20T20:54:22.938052Z","end":"2026-04-20T20:54:23.092628Z","steps":["trace[481512535] 'agreement among raft nodes before linearized reading' (duration: 154.452814ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:23.803383Z","caller":"traceutil/trace.go:171","msg":"trace[1765896995] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"108.522932ms","start":"2026-04-20T20:54:23.694823Z","end":"2026-04-20T20:54:23.803346Z","steps":["trace[1765896995] 'process raft request' (duration: 108.296397ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:23.80498Z","caller":"traceutil/trace.go:171","msg":"trace[389297421] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"106.639023ms","start":"2026-04-20T20:54:23.698305Z","end":"2026-04-20T20:54:23.804944Z","steps":["trace[389297421] 'process raft request' (duration: 106.450339ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:26.237164Z","caller":"traceutil/trace.go:171","msg":"trace[128153871] linearizableReadLoop","detail":"{readStateIndex:708; appliedIndex:707; }","duration":"147.538604ms","start":"2026-04-20T20:54:26.089588Z","end":"2026-04-20T20:54:26.237126Z","steps":["trace[128153871] 'read index received' (duration: 146.961903ms)","trace[128153871] 'applied index is now lower than readState.Index' (duration: 575.341µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:54:26.237313Z","caller":"traceutil/trace.go:171","msg":"trace[1733225620] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"167.305977ms","start":"2026-04-20T20:54:26.069962Z","end":"2026-04-20T20:54:26.237268Z","steps":["trace[1733225620] 'process raft request' (duration: 166.744456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:26.237558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.924932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-04-20T20:54:26.237627Z","caller":"traceutil/trace.go:171","msg":"trace[1025825313] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:680; }","duration":"148.059975ms","start":"2026-04-20T20:54:26.089544Z","end":"2026-04-20T20:54:26.237604Z","steps":["trace[1025825313] 'agreement among raft nodes before linearized reading' (duration: 147.737968ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:37.560927Z","caller":"traceutil/trace.go:171","msg":"trace[1378465542] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:794; }","duration":"394.237665ms","start":"2026-04-20T20:54:37.166664Z","end":"2026-04-20T20:54:37.560901Z","steps":["trace[1378465542] 'read index received' (duration: 394.059202ms)","trace[1378465542] 'applied index is now lower than readState.Index' (duration: 176.983µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:54:37.561027Z","caller":"traceutil/trace.go:171","msg":"trace[934103363] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"774.336661ms","start":"2026-04-20T20:54:36.786656Z","end":"2026-04-20T20:54:37.560993Z","steps":["trace[934103363] 'process raft request' (duration: 774.076486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:37.561195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:36.786642Z","time spent":"774.463893ms","remote":"127.0.0.1:58014","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T20:54:37.561224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.514071ms","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-04-20T20:54:37.561258Z","caller":"traceutil/trace.go:171","msg":"trace[1130442684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:764; }","duration":"394.631452ms","start":"2026-04-20T20:54:37.166616Z","end":"2026-04-20T20:54:37.561247Z","steps":["trace[1130442684] 'agreement among raft nodes before linearized reading' (duration: 394.459409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:37.561307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:37.166542Z","time spent":"394.757734ms","remote":"127.0.0.1:58088","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-04-20T20:54:37.561339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.995577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-20T20:54:37.561415Z","caller":"traceutil/trace.go:171","msg":"trace[1146559369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:764; }","duration":"371.11023ms","start":"2026-04-20T20:54:37.190289Z","end":"2026-04-20T20:54:37.561399Z","steps":["trace[1146559369] 'agreement among raft nodes before linearized reading' (duration: 370.914296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:37.561453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:37.19027Z","time spent":"371.17556ms","remote":"127.0.0.1:58088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-20T20:54:37.561467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.518815ms","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-04-20T20:54:37.561504Z","caller":"traceutil/trace.go:171","msg":"trace[1408249202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:764; }","duration":"362.563895ms","start":"2026-04-20T20:54:37.19893Z","end":"2026-04-20T20:54:37.561494Z","steps":["trace[1408249202] 'agreement among raft nodes before linearized reading' (duration: 362.419523ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:37.561502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.399002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-20T20:54:37.561535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:37.198924Z","time spent":"362.604537ms","remote":"127.0.0.1:58088","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-04-20T20:54:37.561567Z","caller":"traceutil/trace.go:171","msg":"trace[191489531] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:764; }","duration":"239.485503ms","start":"2026-04-20T20:54:37.322062Z","end":"2026-04-20T20:54:37.561548Z","steps":["trace[191489531] 'agreement among raft nodes before linearized reading' (duration: 239.385332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:37.561603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.50243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-20T20:54:37.561631Z","caller":"traceutil/trace.go:171","msg":"trace[1164231519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:764; }","duration":"364.562722ms","start":"2026-04-20T20:54:37.197057Z","end":"2026-04-20T20:54:37.56162Z","steps":["trace[1164231519] 'agreement among raft nodes before linearized reading' (duration: 364.48586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:54:37.561656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T20:54:37.197039Z","time spent":"364.612283ms","remote":"127.0.0.1:58088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-20T20:54:37.750393Z","caller":"traceutil/trace.go:171","msg":"trace[1377775841] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"183.455057ms","start":"2026-04-20T20:54:37.566911Z","end":"2026-04-20T20:54:37.750366Z","steps":["trace[1377775841] 'process raft request' (duration: 155.880232ms)","trace[1377775841] 'compare' (duration: 27.238629ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:54:37.757932Z","caller":"traceutil/trace.go:171","msg":"trace[882711330] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"190.730279ms","start":"2026-04-20T20:54:37.567183Z","end":"2026-04-20T20:54:37.757914Z","steps":["trace[882711330] 'process raft request' (duration: 190.620047ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:37.75816Z","caller":"traceutil/trace.go:171","msg":"trace[139351251] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"190.567735ms","start":"2026-04-20T20:54:37.56758Z","end":"2026-04-20T20:54:37.758148Z","steps":["trace[139351251] 'process raft request' (duration: 190.3038ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:54:37.758316Z","caller":"traceutil/trace.go:171","msg":"trace[702630753] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"190.098386ms","start":"2026-04-20T20:54:37.56819Z","end":"2026-04-20T20:54:37.758289Z","steps":["trace[702630753] 'process raft request' (duration: 189.878913ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:55:10.195474Z","caller":"traceutil/trace.go:171","msg":"trace[457188417] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"124.280194ms","start":"2026-04-20T20:55:10.071152Z","end":"2026-04-20T20:55:10.195432Z","steps":["trace[457188417] 'process raft request' (duration: 123.95322ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:55:10.403787Z","caller":"traceutil/trace.go:171","msg":"trace[1324952061] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"184.029101ms","start":"2026-04-20T20:55:10.219702Z","end":"2026-04-20T20:55:10.403731Z","steps":["trace[1324952061] 'process raft request' (duration: 182.911355ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:55:10.476405Z","caller":"traceutil/trace.go:171","msg":"trace[1991706051] linearizableReadLoop","detail":"{readStateIndex:1043; appliedIndex:1041; }","duration":"175.614377ms","start":"2026-04-20T20:55:10.300759Z","end":"2026-04-20T20:55:10.476373Z","steps":["trace[1991706051] 'read index received' (duration: 101.976765ms)","trace[1991706051] 'applied index is now lower than readState.Index' (duration: 73.632642ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T20:55:10.476545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.763949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:55:10.476576Z","caller":"traceutil/trace.go:171","msg":"trace[776073060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1003; }","duration":"175.860449ms","start":"2026-04-20T20:55:10.300706Z","end":"2026-04-20T20:55:10.476567Z","steps":["trace[776073060] 'agreement among raft nodes before linearized reading' (duration: 175.759348ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:55:11.01145Z","caller":"traceutil/trace.go:171","msg":"trace[721908773] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"192.622982ms","start":"2026-04-20T20:55:10.818793Z","end":"2026-04-20T20:55:11.011416Z","steps":["trace[721908773] 'process raft request' (duration: 192.402029ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:32.404135Z","caller":"traceutil/trace.go:171","msg":"trace[1689742745] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1366; }","duration":"101.727378ms","start":"2026-04-20T20:56:32.302383Z","end":"2026-04-20T20:56:32.40411Z","steps":["trace[1689742745] 'read index received' (duration: 101.604956ms)","trace[1689742745] 'applied index is now lower than readState.Index' (duration: 121.392µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:56:32.404237Z","caller":"traceutil/trace.go:171","msg":"trace[366515620] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"146.739545ms","start":"2026-04-20T20:56:32.257445Z","end":"2026-04-20T20:56:32.404184Z","steps":["trace[366515620] 'process raft request' (duration: 146.500482ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:32.404577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.088511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T20:56:32.404775Z","caller":"traceutil/trace.go:171","msg":"trace[1672046850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1308; }","duration":"102.399883ms","start":"2026-04-20T20:56:32.302331Z","end":"2026-04-20T20:56:32.40473Z","steps":["trace[1672046850] 'agreement among raft nodes before linearized reading' (duration: 101.942149ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:32.742545Z","caller":"traceutil/trace.go:171","msg":"trace[1972861065] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1368; }","duration":"123.388632ms","start":"2026-04-20T20:56:32.619129Z","end":"2026-04-20T20:56:32.742518Z","steps":["trace[1972861065] 'read index received' (duration: 50.898184ms)","trace[1972861065] 'applied index is now lower than readState.Index' (duration: 72.489088ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:56:32.742582Z","caller":"traceutil/trace.go:171","msg":"trace[1117609637] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"185.728634ms","start":"2026-04-20T20:56:32.556797Z","end":"2026-04-20T20:56:32.742526Z","steps":["trace[1117609637] 'process raft request' (duration: 113.248106ms)","trace[1117609637] 'compare' (duration: 72.161485ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:56:32.742622Z","caller":"traceutil/trace.go:171","msg":"trace[181452397] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"185.460872ms","start":"2026-04-20T20:56:32.557117Z","end":"2026-04-20T20:56:32.742578Z","steps":["trace[181452397] 'process raft request' (duration: 185.336931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:32.742817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.656364ms","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-04-20T20:56:32.742867Z","caller":"traceutil/trace.go:171","msg":"trace[261607497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1311; }","duration":"123.771616ms","start":"2026-04-20T20:56:32.619083Z","end":"2026-04-20T20:56:32.742855Z","steps":["trace[261607497] 'agreement among raft nodes before linearized reading' (duration: 123.547903ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:36.918809Z","caller":"traceutil/trace.go:171","msg":"trace[738455656] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"111.176165ms","start":"2026-04-20T20:56:36.807598Z","end":"2026-04-20T20:56:36.918774Z","steps":["trace[738455656] 'process raft request' (duration: 79.937412ms)","trace[738455656] 'compare' (duration: 30.972861ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T20:56:37.814938Z","caller":"traceutil/trace.go:171","msg":"trace[2041111456] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"223.461955ms","start":"2026-04-20T20:56:37.591445Z","end":"2026-04-20T20:56:37.814907Z","steps":["trace[2041111456] 'process raft request' (duration: 223.285884ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T20:56:57.762919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.209666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/default/\" range_end:\"/registry/rolebindings/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T20:56:57.763276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.426392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-04-20T20:56:57.763377Z","caller":"traceutil/trace.go:171","msg":"trace[341715766] range","detail":"{range_begin:/registry/rolebindings/default/; range_end:/registry/rolebindings/default0; response_count:0; response_revision:1400; }","duration":"112.747831ms","start":"2026-04-20T20:56:57.650599Z","end":"2026-04-20T20:56:57.763347Z","steps":["trace[341715766] 'range keys from in-memory index tree' (duration: 112.099325ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T20:56:57.763457Z","caller":"traceutil/trace.go:171","msg":"trace[2089286933] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1400; }","duration":"138.654023ms","start":"2026-04-20T20:56:57.624743Z","end":"2026-04-20T20:56:57.763397Z","steps":["trace[2089286933] 'range keys from in-memory index tree' (duration: 138.122399ms)"],"step_count":1}