{"level":"warn","ts":"2026-02-25T16:59:02.713185Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T16:59:02.713355Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.146:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.146:2380","--initial-cluster=instance=https://199.19.213.146:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.146:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.146: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-02-25T16:59:02.713481Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T16:59:02.713498Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.146:2380"]} {"level":"info","ts":"2026-02-25T16:59:02.71352Z","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-02-25T16:59:02.714509Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"]} {"level":"info","ts":"2026-02-25T16:59:02.714665Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.146:2380"],"listen-peer-urls":["https://199.19.213.146:2380"],"advertise-client-urls":["https://199.19.213.146:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.146: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-02-25T16:59:02.719555Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.513654ms"} {"level":"info","ts":"2026-02-25T16:59:02.729534Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c8e9c1aa9bb537b7","cluster-id":"933c0a8774274c15"} {"level":"info","ts":"2026-02-25T16:59:02.729626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T16:59:02.72965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became follower at term 0"} {"level":"info","ts":"2026-02-25T16:59:02.729669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c8e9c1aa9bb537b7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T16:59:02.729677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became follower at term 1"} {"level":"info","ts":"2026-02-25T16:59:02.729715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=(14477315415660115895)"} {"level":"warn","ts":"2026-02-25T16:59:02.740707Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T16:59:02.742847Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T16:59:02.748713Z","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-02-25T16:59:02.752391Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c8e9c1aa9bb537b7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T16:59:02.752642Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c8e9c1aa9bb537b7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T16:59:02.752928Z","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-02-25T16:59:02.753033Z","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-02-25T16:59:02.753046Z","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-02-25T16:59:02.757608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 switched to configuration voters=(14477315415660115895)"} {"level":"info","ts":"2026-02-25T16:59:02.757755Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"933c0a8774274c15","local-member-id":"c8e9c1aa9bb537b7","added-peer-id":"c8e9c1aa9bb537b7","added-peer-peer-urls":["https://199.19.213.146:2380"]} {"level":"info","ts":"2026-02-25T16:59:02.759048Z","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-02-25T16:59:02.759216Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.146:2380"} {"level":"info","ts":"2026-02-25T16:59:02.759259Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.146:2380"} {"level":"info","ts":"2026-02-25T16:59:02.759591Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c8e9c1aa9bb537b7","initial-advertise-peer-urls":["https://199.19.213.146:2380"],"listen-peer-urls":["https://199.19.213.146:2380"],"advertise-client-urls":["https://199.19.213.146:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.146:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T16:59:02.759644Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T16:59:03.229866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T16:59:03.229965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T16:59:03.229987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 received MsgPreVoteResp from c8e9c1aa9bb537b7 at term 1"} {"level":"info","ts":"2026-02-25T16:59:03.23Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became candidate at term 2"} {"level":"info","ts":"2026-02-25T16:59:03.230007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 received MsgVoteResp from c8e9c1aa9bb537b7 at term 2"} {"level":"info","ts":"2026-02-25T16:59:03.230018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c8e9c1aa9bb537b7 became leader at term 2"} {"level":"info","ts":"2026-02-25T16:59:03.230026Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c8e9c1aa9bb537b7 elected leader c8e9c1aa9bb537b7 at term 2"} {"level":"info","ts":"2026-02-25T16:59:03.230997Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T16:59:03.23264Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T16:59:03.232713Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T16:59:03.23277Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"933c0a8774274c15","local-member-id":"c8e9c1aa9bb537b7","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T16:59:03.233283Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T16:59:03.233355Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T16:59:03.233115Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T16:59:03.235533Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T16:59:03.236043Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.146:2379"} {"level":"info","ts":"2026-02-25T16:59:03.233085Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c8e9c1aa9bb537b7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.146:2379]}","request-path":"/0/members/c8e9c1aa9bb537b7/attributes","cluster-id":"933c0a8774274c15","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T16:59:03.236238Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T16:59:10.710004Z","caller":"traceutil/trace.go:171","msg":"trace[2093445479] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:339; }","duration":"315.720479ms","start":"2026-02-25T16:59:10.394265Z","end":"2026-02-25T16:59:10.709985Z","steps":["trace[2093445479] 'read index received' (duration: 315.546868ms)","trace[2093445479] 'applied index is now lower than readState.Index' (duration: 172.941µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T16:59:10.710058Z","caller":"traceutil/trace.go:171","msg":"trace[1955375470] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"777.287064ms","start":"2026-02-25T16:59:09.932741Z","end":"2026-02-25T16:59:10.710028Z","steps":["trace[1955375470] 'process raft request' (duration: 777.142683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:10.710182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.90428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T16:59:10.710231Z","caller":"traceutil/trace.go:171","msg":"trace[1059627367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:327; }","duration":"316.074921ms","start":"2026-02-25T16:59:10.394149Z","end":"2026-02-25T16:59:10.710224Z","steps":["trace[1059627367] 'agreement among raft nodes before linearized reading' (duration: 315.93237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:10.710252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:10.394117Z","time spent":"316.130932ms","remote":"127.0.0.1:44060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T16:59:10.71053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:09.932723Z","time spent":"777.396685ms","remote":"127.0.0.1:43984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T16:59:11.395149Z","caller":"traceutil/trace.go:171","msg":"trace[1346076145] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"106.562239ms","start":"2026-02-25T16:59:11.288551Z","end":"2026-02-25T16:59:11.395113Z","steps":["trace[1346076145] 'process raft request' (duration: 95.213788ms)","trace[1346076145] 'compare' (duration: 10.940618ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T16:59:11.395681Z","caller":"traceutil/trace.go:171","msg":"trace[1783147340] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"105.151079ms","start":"2026-02-25T16:59:11.290472Z","end":"2026-02-25T16:59:11.395623Z","steps":["trace[1783147340] 'process raft request' (duration: 104.482654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:13.716329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.530378ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T16:59:13.716468Z","caller":"traceutil/trace.go:171","msg":"trace[1786656553] linearizableReadLoop","detail":"{readStateIndex:397; appliedIndex:396; }","duration":"667.096553ms","start":"2026-02-25T16:59:13.04935Z","end":"2026-02-25T16:59:13.716447Z","steps":["trace[1786656553] 'read index received' (duration: 38.051µs)","trace[1786656553] 'applied index is now lower than readState.Index' (duration: 667.056982ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T16:59:13.716544Z","caller":"traceutil/trace.go:171","msg":"trace[15211103] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"842.775728ms","start":"2026-02-25T16:59:12.873738Z","end":"2026-02-25T16:59:13.716514Z","steps":["trace[15211103] 'process raft request' (duration: 105.777796ms)","trace[15211103] 'compare' (duration: 736.321401ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T16:59:13.71666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:12.873716Z","time spent":"842.890981ms","remote":"127.0.0.1:43844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":755,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T16:59:13.716742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.377071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-25T16:59:13.716839Z","caller":"traceutil/trace.go:171","msg":"trace[1438710184] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:384; }","duration":"667.515975ms","start":"2026-02-25T16:59:13.049311Z","end":"2026-02-25T16:59:13.716827Z","steps":["trace[1438710184] 'agreement among raft nodes before linearized reading' (duration: 667.299019ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:13.716892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:13.049289Z","time spent":"667.590658ms","remote":"127.0.0.1:43958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6922,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-25T16:59:13.71718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.427204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-25T16:59:13.718021Z","caller":"traceutil/trace.go:171","msg":"trace[1761512356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:384; }","duration":"664.28834ms","start":"2026-02-25T16:59:13.053715Z","end":"2026-02-25T16:59:13.718003Z","steps":["trace[1761512356] 'agreement among raft nodes before linearized reading' (duration: 663.377123ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:13.718074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:13.053705Z","time spent":"664.354361ms","remote":"127.0.0.1:44060","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-02-25T16:59:13.717193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.631319ms","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-02-25T16:59:13.71831Z","caller":"traceutil/trace.go:171","msg":"trace[2092957776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:384; }","duration":"302.781023ms","start":"2026-02-25T16:59:13.415512Z","end":"2026-02-25T16:59:13.718293Z","steps":["trace[2092957776] 'agreement among raft nodes before linearized reading' (duration: 301.610679ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:13.718357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:13.415485Z","time spent":"302.859485ms","remote":"127.0.0.1:44060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T16:59:13.96466Z","caller":"traceutil/trace.go:171","msg":"trace[2048186161] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"241.745603ms","start":"2026-02-25T16:59:13.722862Z","end":"2026-02-25T16:59:13.964608Z","steps":["trace[2048186161] 'process raft request' (duration: 207.508013ms)","trace[2048186161] 'compare' (duration: 34.112456ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T16:59:13.988332Z","caller":"traceutil/trace.go:171","msg":"trace[1018729243] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"265.342709ms","start":"2026-02-25T16:59:13.722963Z","end":"2026-02-25T16:59:13.988306Z","steps":["trace[1018729243] 'process raft request' (duration: 265.154064ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:59:13.988614Z","caller":"traceutil/trace.go:171","msg":"trace[195364387] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"261.376552ms","start":"2026-02-25T16:59:13.727103Z","end":"2026-02-25T16:59:13.98848Z","steps":["trace[195364387] 'process raft request' (duration: 261.148466ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:59:14.935112Z","caller":"traceutil/trace.go:171","msg":"trace[455329773] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"109.257228ms","start":"2026-02-25T16:59:14.825832Z","end":"2026-02-25T16:59:14.935089Z","steps":["trace[455329773] 'read index received' (duration: 109.059012ms)","trace[455329773] 'applied index is now lower than readState.Index' (duration: 197.046µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T16:59:14.935285Z","caller":"traceutil/trace.go:171","msg":"trace[2018812326] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"222.237587ms","start":"2026-02-25T16:59:14.712976Z","end":"2026-02-25T16:59:14.935213Z","steps":["trace[2018812326] 'process raft request' (duration: 221.971749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:14.935721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.644576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T16:59:14.935828Z","caller":"traceutil/trace.go:171","msg":"trace[1199132869] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:389; }","duration":"102.795611ms","start":"2026-02-25T16:59:14.833011Z","end":"2026-02-25T16:59:14.935807Z","steps":["trace[1199132869] 'agreement among raft nodes before linearized reading' (duration: 102.526323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:14.936177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.315729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T16:59:14.936266Z","caller":"traceutil/trace.go:171","msg":"trace[1458343730] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:389; }","duration":"110.472003ms","start":"2026-02-25T16:59:14.825771Z","end":"2026-02-25T16:59:14.936243Z","steps":["trace[1458343730] 'agreement among raft nodes before linearized reading' (duration: 109.529066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:15.131036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.148061ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T16:59:15.131626Z","caller":"traceutil/trace.go:171","msg":"trace[1251553465] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"188.394738ms","start":"2026-02-25T16:59:14.943203Z","end":"2026-02-25T16:59:15.131597Z","steps":["trace[1251553465] 'process raft request' (duration: 187.938565ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:59:15.131775Z","caller":"traceutil/trace.go:171","msg":"trace[1305025368] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"188.751538ms","start":"2026-02-25T16:59:14.943005Z","end":"2026-02-25T16:59:15.131756Z","steps":["trace[1305025368] 'process raft request' (duration: 49.611818ms)","trace[1305025368] 'compare' (duration: 138.028148ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T16:59:16.467456Z","caller":"traceutil/trace.go:171","msg":"trace[328460415] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"494.008736ms","start":"2026-02-25T16:59:15.973427Z","end":"2026-02-25T16:59:16.467435Z","steps":["trace[328460415] 'process raft request' (duration: 493.866451ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:59:16.467553Z","caller":"traceutil/trace.go:171","msg":"trace[935192047] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:411; }","duration":"475.783779ms","start":"2026-02-25T16:59:15.991738Z","end":"2026-02-25T16:59:16.467522Z","steps":["trace[935192047] 'read index received' (duration: 475.771929ms)","trace[935192047] 'applied index is now lower than readState.Index' (duration: 9.79µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T16:59:16.467587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:15.973407Z","time spent":"494.116489ms","remote":"127.0.0.1:44060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T16:59:16.467737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.998965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-25T16:59:16.467781Z","caller":"traceutil/trace.go:171","msg":"trace[293489401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:397; }","duration":"476.076088ms","start":"2026-02-25T16:59:15.991694Z","end":"2026-02-25T16:59:16.46777Z","steps":["trace[293489401] 'agreement among raft nodes before linearized reading' (duration: 476.015826ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:16.467816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:15.991672Z","time spent":"476.135519ms","remote":"127.0.0.1:44060","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-02-25T16:59:28.507977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.363136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T16:59:28.508029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.321247308s","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-02-25T16:59:28.508117Z","caller":"traceutil/trace.go:171","msg":"trace[2026198998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:460; }","duration":"1.321403701s","start":"2026-02-25T16:59:27.186694Z","end":"2026-02-25T16:59:28.508097Z","steps":["trace[2026198998] 'range keys from in-memory index tree' (duration: 1.321114335s)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:59:28.508111Z","caller":"traceutil/trace.go:171","msg":"trace[16281594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:460; }","duration":"328.562461ms","start":"2026-02-25T16:59:28.179524Z","end":"2026-02-25T16:59:28.508087Z","steps":["trace[16281594] 'range keys from in-memory index tree' (duration: 328.274464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:28.508147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:27.186668Z","time spent":"1.321473763s","remote":"127.0.0.1:44060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T16:59:28.508167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:28.1795Z","time spent":"328.653203ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T16:59:28.675998Z","caller":"traceutil/trace.go:171","msg":"trace[255946668] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:477; }","duration":"138.516387ms","start":"2026-02-25T16:59:28.53746Z","end":"2026-02-25T16:59:28.675977Z","steps":["trace[255946668] 'read index received' (duration: 138.374004ms)","trace[255946668] 'applied index is now lower than readState.Index' (duration: 141.823µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T16:59:28.676123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.64876ms","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-02-25T16:59:28.676144Z","caller":"traceutil/trace.go:171","msg":"trace[1334870488] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:461; }","duration":"138.759043ms","start":"2026-02-25T16:59:28.537378Z","end":"2026-02-25T16:59:28.676137Z","steps":["trace[1334870488] 'agreement among raft nodes before linearized reading' (duration: 138.698602ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:59:28.676118Z","caller":"traceutil/trace.go:171","msg":"trace[1397349625] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"163.860856ms","start":"2026-02-25T16:59:28.512207Z","end":"2026-02-25T16:59:28.676068Z","steps":["trace[1397349625] 'process raft request' (duration: 163.674211ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:59:39.157366Z","caller":"traceutil/trace.go:171","msg":"trace[1287156002] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"378.293202ms","start":"2026-02-25T16:59:38.779042Z","end":"2026-02-25T16:59:39.157335Z","steps":["trace[1287156002] 'read index received' (duration: 378.128748ms)","trace[1287156002] 'applied index is now lower than readState.Index' (duration: 163.224µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T16:59:39.157694Z","caller":"traceutil/trace.go:171","msg":"trace[1967966778] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"422.263964ms","start":"2026-02-25T16:59:38.735419Z","end":"2026-02-25T16:59:39.157683Z","steps":["trace[1967966778] 'process raft request' (duration: 421.767913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:39.157844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:38.735395Z","time spent":"422.327255ms","remote":"127.0.0.1:44060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T16:59:39.158144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.1051ms","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-02-25T16:59:39.158178Z","caller":"traceutil/trace.go:171","msg":"trace[1890476463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:519; }","duration":"379.178491ms","start":"2026-02-25T16:59:38.778989Z","end":"2026-02-25T16:59:39.158167Z","steps":["trace[1890476463] 'agreement among raft nodes before linearized reading' (duration: 379.062859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:39.158208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:38.778965Z","time spent":"379.236303ms","remote":"127.0.0.1:44060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T16:59:39.159721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.488698ms","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-02-25T16:59:39.160351Z","caller":"traceutil/trace.go:171","msg":"trace[509526052] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:520; }","duration":"373.145364ms","start":"2026-02-25T16:59:38.787165Z","end":"2026-02-25T16:59:39.160311Z","steps":["trace[509526052] 'agreement among raft nodes before linearized reading' (duration: 372.420728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:39.160404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:38.787141Z","time spent":"373.247055ms","remote":"127.0.0.1:44060","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-02-25T16:59:39.727785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.691658ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T16:59:39.727946Z","caller":"traceutil/trace.go:171","msg":"trace[173890612] linearizableReadLoop","detail":"{readStateIndex:541; appliedIndex:539; }","duration":"556.861866ms","start":"2026-02-25T16:59:39.17107Z","end":"2026-02-25T16:59:39.727931Z","steps":["trace[173890612] 'read index received' (duration: 256.898722ms)","trace[173890612] 'applied index is now lower than readState.Index' (duration: 299.962134ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T16:59:39.728117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.056211ms","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-02-25T16:59:39.728142Z","caller":"traceutil/trace.go:171","msg":"trace[275799195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"557.120211ms","start":"2026-02-25T16:59:39.171013Z","end":"2026-02-25T16:59:39.728133Z","steps":["trace[275799195] 'agreement among raft nodes before linearized reading' (duration: 556.962838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:39.728183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:39.170996Z","time spent":"557.181283ms","remote":"127.0.0.1:44060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T16:59:39.728221Z","caller":"traceutil/trace.go:171","msg":"trace[408466354] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"563.476955ms","start":"2026-02-25T16:59:39.164709Z","end":"2026-02-25T16:59:39.728186Z","steps":["trace[408466354] 'process raft request' (duration: 563.182499ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:59:39.728238Z","caller":"traceutil/trace.go:171","msg":"trace[1669244805] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"563.882414ms","start":"2026-02-25T16:59:39.164288Z","end":"2026-02-25T16:59:39.72817Z","steps":["trace[1669244805] 'process raft request' (duration: 263.711725ms)","trace[1669244805] 'compare' (duration: 299.554595ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T16:59:39.728389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:39.164684Z","time spent":"563.611018ms","remote":"127.0.0.1:44060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T16:59:39.72843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:39.164269Z","time spent":"564.070168ms","remote":"127.0.0.1:44060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T16:59:46.092079Z","caller":"traceutil/trace.go:171","msg":"trace[1091392703] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"333.175857ms","start":"2026-02-25T16:59:45.758877Z","end":"2026-02-25T16:59:46.092053Z","steps":["trace[1091392703] 'process raft request' (duration: 333.046984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:46.092216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:45.758864Z","time spent":"333.284139ms","remote":"127.0.0.1:44060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T16:59:46.169233Z","caller":"traceutil/trace.go:171","msg":"trace[1946464513] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:577; }","duration":"407.832364ms","start":"2026-02-25T16:59:45.761379Z","end":"2026-02-25T16:59:46.169211Z","steps":["trace[1946464513] 'read index received' (duration: 330.53664ms)","trace[1946464513] 'applied index is now lower than readState.Index' (duration: 77.294644ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T16:59:46.169506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.10814ms","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-02-25T16:59:46.16956Z","caller":"traceutil/trace.go:171","msg":"trace[1743300053] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:560; }","duration":"408.224742ms","start":"2026-02-25T16:59:45.761323Z","end":"2026-02-25T16:59:46.169548Z","steps":["trace[1743300053] 'agreement among raft nodes before linearized reading' (duration: 408.019168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:46.169599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:45.761299Z","time spent":"408.291534ms","remote":"127.0.0.1:44060","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-02-25T16:59:46.169813Z","caller":"traceutil/trace.go:171","msg":"trace[919710404] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"311.206621ms","start":"2026-02-25T16:59:45.858593Z","end":"2026-02-25T16:59:46.1698Z","steps":["trace[919710404] 'process raft request' (duration: 310.578748ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:59:46.169844Z","caller":"traceutil/trace.go:171","msg":"trace[1540039134] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"408.611131ms","start":"2026-02-25T16:59:45.761178Z","end":"2026-02-25T16:59:46.16979Z","steps":["trace[1540039134] 'process raft request' (duration: 407.893046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:46.169945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:45.858569Z","time spent":"311.297434ms","remote":"127.0.0.1:44060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T16:59:46.17009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:45.761169Z","time spent":"408.784055ms","remote":"127.0.0.1:44060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T16:59:46.589979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.474672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T16:59:46.590097Z","caller":"traceutil/trace.go:171","msg":"trace[838564103] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"412.207541ms","start":"2026-02-25T16:59:46.177873Z","end":"2026-02-25T16:59:46.590081Z","steps":["trace[838564103] 'read index received' (duration: 29.353242ms)","trace[838564103] 'applied index is now lower than readState.Index' (duration: 382.853079ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T16:59:46.590182Z","caller":"traceutil/trace.go:171","msg":"trace[228906153] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"416.227055ms","start":"2026-02-25T16:59:46.173944Z","end":"2026-02-25T16:59:46.590171Z","steps":["trace[228906153] 'process raft request' (duration: 33.477448ms)","trace[228906153] 'compare' (duration: 382.361028ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T16:59:46.590689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:46.173921Z","time spent":"416.285546ms","remote":"127.0.0.1:44060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T16:59:46.590689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.795464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T16:59:46.590826Z","caller":"traceutil/trace.go:171","msg":"trace[1046916332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"412.947546ms","start":"2026-02-25T16:59:46.177842Z","end":"2026-02-25T16:59:46.59079Z","steps":["trace[1046916332] 'agreement among raft nodes before linearized reading' (duration: 412.64425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:46.590862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:46.177832Z","time spent":"413.021308ms","remote":"127.0.0.1:43748","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T16:59:46.591199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.846426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.146\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T16:59:46.591287Z","caller":"traceutil/trace.go:171","msg":"trace[2115133742] range","detail":"{range_begin:/registry/masterleases/199.19.213.146; range_end:; response_count:1; response_revision:561; }","duration":"197.956529ms","start":"2026-02-25T16:59:46.393288Z","end":"2026-02-25T16:59:46.591245Z","steps":["trace[2115133742] 'agreement among raft nodes before linearized reading' (duration: 197.728124ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:59:47.537916Z","caller":"traceutil/trace.go:171","msg":"trace[1599892632] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"930.641477ms","start":"2026-02-25T16:59:46.607249Z","end":"2026-02-25T16:59:47.53789Z","steps":["trace[1599892632] 'process raft request' (duration: 843.766926ms)","trace[1599892632] 'compare' (duration: 86.735648ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T16:59:47.538047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:46.607231Z","time spent":"930.750939ms","remote":"127.0.0.1:43958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T16:59:47.626902Z","caller":"traceutil/trace.go:171","msg":"trace[1934386326] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:582; }","duration":"433.689132ms","start":"2026-02-25T16:59:47.193187Z","end":"2026-02-25T16:59:47.626876Z","steps":["trace[1934386326] 'read index received' (duration: 257.839096ms)","trace[1934386326] 'applied index is now lower than readState.Index' (duration: 175.848626ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T16:59:47.627141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.946108ms","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-02-25T16:59:47.62719Z","caller":"traceutil/trace.go:171","msg":"trace[553284860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:563; }","duration":"434.012609ms","start":"2026-02-25T16:59:47.193171Z","end":"2026-02-25T16:59:47.627183Z","steps":["trace[553284860] 'agreement among raft nodes before linearized reading' (duration: 433.802105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:47.627219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:47.193123Z","time spent":"434.092311ms","remote":"127.0.0.1:44060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T16:59:47.627485Z","caller":"traceutil/trace.go:171","msg":"trace[372519285] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"991.947486ms","start":"2026-02-25T16:59:46.634982Z","end":"2026-02-25T16:59:47.626929Z","steps":["trace[372519285] 'process raft request' (duration: 991.774182ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:47.627554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:46.634956Z","time spent":"992.552019ms","remote":"127.0.0.1:43782","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T16:59:48.044206Z","caller":"traceutil/trace.go:171","msg":"trace[1328526906] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:584; }","duration":"412.06466ms","start":"2026-02-25T16:59:47.632123Z","end":"2026-02-25T16:59:48.044188Z","steps":["trace[1328526906] 'read index received' (duration: 404.276979ms)","trace[1328526906] 'applied index is now lower than readState.Index' (duration: 7.786961ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T16:59:48.044323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.193534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-02-25T16:59:48.044321Z","caller":"traceutil/trace.go:171","msg":"trace[864495840] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"413.406658ms","start":"2026-02-25T16:59:47.630889Z","end":"2026-02-25T16:59:48.044296Z","steps":["trace[864495840] 'process raft request' (duration: 405.581987ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T16:59:48.044344Z","caller":"traceutil/trace.go:171","msg":"trace[1037940116] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:564; }","duration":"412.271885ms","start":"2026-02-25T16:59:47.632067Z","end":"2026-02-25T16:59:48.044339Z","steps":["trace[1037940116] 'agreement among raft nodes before linearized reading' (duration: 412.182544ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T16:59:48.044467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:47.632052Z","time spent":"412.410139ms","remote":"127.0.0.1:44068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-25T16:59:48.044467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T16:59:47.630878Z","time spent":"413.507721ms","remote":"127.0.0.1:44060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T16:59:49.93557Z","caller":"traceutil/trace.go:171","msg":"trace[1134220556] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"253.720065ms","start":"2026-02-25T16:59:49.681828Z","end":"2026-02-25T16:59:49.935548Z","steps":["trace[1134220556] 'process raft request' (duration: 190.010612ms)","trace[1134220556] 'compare' (duration: 63.218622ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T17:01:36.844485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.867313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T17:01:36.844588Z","caller":"traceutil/trace.go:171","msg":"trace[100671569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1056; }","duration":"135.056765ms","start":"2026-02-25T17:01:36.70951Z","end":"2026-02-25T17:01:36.844567Z","steps":["trace[100671569] 'range keys from in-memory index tree' (duration: 134.59105ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T17:01:36.955197Z","caller":"traceutil/trace.go:171","msg":"trace[69516442] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"106.018193ms","start":"2026-02-25T17:01:36.849151Z","end":"2026-02-25T17:01:36.955169Z","steps":["trace[69516442] 'process raft request' (duration: 105.830051ms)"],"step_count":1}