{"level":"warn","ts":"2026-02-23T23:58:19.631034Z","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-23T23:58:19.632122Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.212:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.212:2380","--initial-cluster=instance=https://162.253.55.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.212: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-23T23:58:19.632201Z","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-23T23:58:19.632227Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-23T23:58:19.632277Z","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-23T23:58:19.633285Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"]} {"level":"info","ts":"2026-02-23T23:58:19.633728Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.212: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-23T23:58:19.639476Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.254177ms"} {"level":"info","ts":"2026-02-23T23:58:19.645362Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d078b1e39c21939c","cluster-id":"cdc9ba8de57e3589"} {"level":"info","ts":"2026-02-23T23:58:19.645432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=()"} {"level":"info","ts":"2026-02-23T23:58:19.64545Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 0"} {"level":"info","ts":"2026-02-23T23:58:19.64546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d078b1e39c21939c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-23T23:58:19.645467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 1"} {"level":"info","ts":"2026-02-23T23:58:19.645499Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"warn","ts":"2026-02-23T23:58:19.653275Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-23T23:58:19.654488Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-23T23:58:19.655399Z","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-23T23:58:19.657325Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d078b1e39c21939c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-23T23:58:19.657464Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d078b1e39c21939c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-23T23:58:19.657681Z","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-23T23:58:19.657742Z","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-23T23:58:19.657752Z","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-23T23:58:19.658246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"info","ts":"2026-02-23T23:58:19.658347Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","added-peer-id":"d078b1e39c21939c","added-peer-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-23T23:58:19.661499Z","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-23T23:58:19.661551Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-23T23:58:19.661581Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-23T23:58:19.66218Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d078b1e39c21939c","initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-23T23:58:19.662212Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-23T23:58:19.946125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c is starting a new election at term 1"} {"level":"info","ts":"2026-02-23T23:58:19.946179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-23T23:58:19.946212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgPreVoteResp from d078b1e39c21939c at term 1"} {"level":"info","ts":"2026-02-23T23:58:19.946231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became candidate at term 2"} {"level":"info","ts":"2026-02-23T23:58:19.946246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgVoteResp from d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-23T23:58:19.946266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became leader at term 2"} {"level":"info","ts":"2026-02-23T23:58:19.946282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d078b1e39c21939c elected leader d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-23T23:58:19.947157Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T23:58:19.947867Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d078b1e39c21939c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.212:2379]}","request-path":"/0/members/d078b1e39c21939c/attributes","cluster-id":"cdc9ba8de57e3589","publish-timeout":"7s"} {"level":"info","ts":"2026-02-23T23:58:19.94788Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-23T23:58:19.947914Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-23T23:58:19.948385Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T23:58:19.948533Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T23:58:19.948585Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-23T23:58:19.94982Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-23T23:58:19.950956Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.212:2379"} {"level":"info","ts":"2026-02-23T23:58:19.951364Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-23T23:58:19.951391Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-23T23:58:50.121113Z","caller":"traceutil/trace.go:171","msg":"trace[228956037] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"355.890344ms","start":"2026-02-23T23:58:49.765197Z","end":"2026-02-23T23:58:50.121087Z","steps":["trace[228956037] 'read index received' (duration: 355.75239ms)","trace[228956037] 'applied index is now lower than readState.Index' (duration: 136.784µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T23:58:50.121203Z","caller":"traceutil/trace.go:171","msg":"trace[293628664] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"357.399949ms","start":"2026-02-23T23:58:49.763762Z","end":"2026-02-23T23:58:50.121162Z","steps":["trace[293628664] 'process raft request' (duration: 357.171844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T23:58:50.121376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.761361ms","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-02-23T23:58:50.121459Z","caller":"traceutil/trace.go:171","msg":"trace[1655218520] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:466; }","duration":"355.943325ms","start":"2026-02-23T23:58:49.765504Z","end":"2026-02-23T23:58:50.121448Z","steps":["trace[1655218520] 'agreement among raft nodes before linearized reading' (duration: 355.7443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T23:58:50.121426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.19744ms","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-23T23:58:50.121606Z","caller":"traceutil/trace.go:171","msg":"trace[1428993173] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:466; }","duration":"356.430616ms","start":"2026-02-23T23:58:49.765148Z","end":"2026-02-23T23:58:50.121578Z","steps":["trace[1428993173] 'agreement among raft nodes before linearized reading' (duration: 356.15039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T23:58:50.121711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T23:58:49.765128Z","time spent":"356.5666ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-23T23:58:50.1215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T23:58:49.765496Z","time spent":"355.992946ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-23T23:58:50.12178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T23:58:49.763735Z","time spent":"357.538473ms","remote":"127.0.0.1:53288","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-23T23:58:54.266065Z","caller":"traceutil/trace.go:171","msg":"trace[1047342929] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"108.283356ms","start":"2026-02-23T23:58:54.157759Z","end":"2026-02-23T23:58:54.266042Z","steps":["trace[1047342929] 'read index received' (duration: 108.150003ms)","trace[1047342929] 'applied index is now lower than readState.Index' (duration: 130.733µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T23:58:54.266175Z","caller":"traceutil/trace.go:171","msg":"trace[82735856] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"119.928542ms","start":"2026-02-23T23:58:54.146237Z","end":"2026-02-23T23:58:54.266166Z","steps":["trace[82735856] 'process raft request' (duration: 119.662655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T23:58:54.266602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.542215ms","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-23T23:58:54.26668Z","caller":"traceutil/trace.go:171","msg":"trace[848157572] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:476; }","duration":"103.700799ms","start":"2026-02-23T23:58:54.16296Z","end":"2026-02-23T23:58:54.266661Z","steps":["trace[848157572] 'agreement among raft nodes before linearized reading' (duration: 103.520515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T23:58:54.266927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.097396ms","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-23T23:58:54.266972Z","caller":"traceutil/trace.go:171","msg":"trace[601032373] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:476; }","duration":"109.216599ms","start":"2026-02-23T23:58:54.157742Z","end":"2026-02-23T23:58:54.266958Z","steps":["trace[601032373] 'agreement among raft nodes before linearized reading' (duration: 108.550073ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T23:58:54.567293Z","caller":"traceutil/trace.go:171","msg":"trace[577712877] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"225.946176ms","start":"2026-02-23T23:58:54.341322Z","end":"2026-02-23T23:58:54.567268Z","steps":["trace[577712877] 'read index received' (duration: 225.755221ms)","trace[577712877] 'applied index is now lower than readState.Index' (duration: 190.015µs)"],"step_count":2} {"level":"info","ts":"2026-02-23T23:58:54.56738Z","caller":"traceutil/trace.go:171","msg":"trace[937404279] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"296.170674ms","start":"2026-02-23T23:58:54.271165Z","end":"2026-02-23T23:58:54.567336Z","steps":["trace[937404279] 'process raft request' (duration: 295.96378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T23:58:54.567446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.101549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T23:58:54.567499Z","caller":"traceutil/trace.go:171","msg":"trace[930234781] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:478; }","duration":"226.189561ms","start":"2026-02-23T23:58:54.341297Z","end":"2026-02-23T23:58:54.567487Z","steps":["trace[930234781] 'agreement among raft nodes before linearized reading' (duration: 226.098579ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T23:58:57.490034Z","caller":"traceutil/trace.go:171","msg":"trace[2059505843] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"280.511044ms","start":"2026-02-23T23:58:57.209499Z","end":"2026-02-23T23:58:57.49001Z","steps":["trace[2059505843] 'process raft request' (duration: 280.35543ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T23:58:58.715874Z","caller":"traceutil/trace.go:171","msg":"trace[1574870849] linearizableReadLoop","detail":"{readStateIndex:505; appliedIndex:504; }","duration":"436.292263ms","start":"2026-02-23T23:58:58.279555Z","end":"2026-02-23T23:58:58.715848Z","steps":["trace[1574870849] 'read index received' (duration: 411.129719ms)","trace[1574870849] 'applied index is now lower than readState.Index' (duration: 25.161624ms)"],"step_count":2} {"level":"warn","ts":"2026-02-23T23:58:58.716027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.453536ms","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-23T23:58:58.716056Z","caller":"traceutil/trace.go:171","msg":"trace[627134634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:488; }","duration":"436.537339ms","start":"2026-02-23T23:58:58.279512Z","end":"2026-02-23T23:58:58.716049Z","steps":["trace[627134634] 'agreement among raft nodes before linearized reading' (duration: 436.431776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T23:58:58.716081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T23:58:58.279492Z","time spent":"436.583539ms","remote":"127.0.0.1:53288","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-23T23:58:58.716187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.320357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-23T23:58:58.71623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.245578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-23T23:58:58.716317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.806296ms","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-23T23:58:58.716331Z","caller":"traceutil/trace.go:171","msg":"trace[1961906612] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:488; }","duration":"426.49273ms","start":"2026-02-23T23:58:58.289812Z","end":"2026-02-23T23:58:58.716305Z","steps":["trace[1961906612] 'agreement among raft nodes before linearized reading' (duration: 426.180343ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T23:58:58.716362Z","caller":"traceutil/trace.go:171","msg":"trace[1619674327] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"374.426092ms","start":"2026-02-23T23:58:58.34191Z","end":"2026-02-23T23:58:58.716336Z","steps":["trace[1619674327] 'agreement among raft nodes before linearized reading' (duration: 374.177446ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T23:58:58.716374Z","caller":"traceutil/trace.go:171","msg":"trace[414803240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:488; }","duration":"135.865538ms","start":"2026-02-23T23:58:58.580493Z","end":"2026-02-23T23:58:58.716359Z","steps":["trace[414803240] 'agreement among raft nodes before linearized reading' (duration: 135.53847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T23:58:58.716407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T23:58:58.289805Z","time spent":"426.586742ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-23T23:58:58.716427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T23:58:58.341897Z","time spent":"374.518674ms","remote":"127.0.0.1:53036","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-23T23:58:58.717057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.855787ms","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-23T23:58:58.717106Z","caller":"traceutil/trace.go:171","msg":"trace[1511457328] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:488; }","duration":"132.932879ms","start":"2026-02-23T23:58:58.584162Z","end":"2026-02-23T23:58:58.717095Z","steps":["trace[1511457328] 'agreement among raft nodes before linearized reading' (duration: 132.762075ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T23:58:59.149921Z","caller":"traceutil/trace.go:171","msg":"trace[1078552253] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"429.683617ms","start":"2026-02-23T23:58:58.720207Z","end":"2026-02-23T23:58:59.149891Z","steps":["trace[1078552253] 'process raft request' (duration: 429.408271ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T23:58:59.150257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T23:58:58.720178Z","time spent":"429.84393ms","remote":"127.0.0.1:53288","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-23T23:58:59.154923Z","caller":"traceutil/trace.go:171","msg":"trace[980778776] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"434.221963ms","start":"2026-02-23T23:58:58.720675Z","end":"2026-02-23T23:58:59.154897Z","steps":["trace[980778776] 'process raft request' (duration: 434.133161ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T23:58:59.154956Z","caller":"traceutil/trace.go:171","msg":"trace[947349894] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"434.5125ms","start":"2026-02-23T23:58:58.720424Z","end":"2026-02-23T23:58:59.154936Z","steps":["trace[947349894] 'process raft request' (duration: 434.218803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T23:58:59.1551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T23:58:58.720668Z","time spent":"434.339386ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-23T23:58:59.155111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T23:58:58.720402Z","time spent":"434.608202ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T23:58:59.155342Z","caller":"traceutil/trace.go:171","msg":"trace[1268937642] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"433.819424ms","start":"2026-02-23T23:58:58.721482Z","end":"2026-02-23T23:58:59.155302Z","steps":["trace[1268937642] 'process raft request' (duration: 433.374883ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T23:58:59.155531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T23:58:58.721475Z","time spent":"433.959767ms","remote":"127.0.0.1:53288","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-23T23:59:00.500524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.754499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T23:59:00.500611Z","caller":"traceutil/trace.go:171","msg":"trace[1498280359] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:500; }","duration":"158.897582ms","start":"2026-02-23T23:59:00.341695Z","end":"2026-02-23T23:59:00.500592Z","steps":["trace[1498280359] 'range keys from in-memory index tree' (duration: 158.643387ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T23:59:17.516833Z","caller":"traceutil/trace.go:171","msg":"trace[1856374813] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"173.528247ms","start":"2026-02-23T23:59:17.34328Z","end":"2026-02-23T23:59:17.516809Z","steps":["trace[1856374813] 'process raft request' (duration: 173.286662ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T23:59:47.344631Z","caller":"traceutil/trace.go:171","msg":"trace[2071153693] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"232.151154ms","start":"2026-02-23T23:59:47.11246Z","end":"2026-02-23T23:59:47.344611Z","steps":["trace[2071153693] 'process raft request' (duration: 232.046832ms)"],"step_count":1} {"level":"info","ts":"2026-02-23T23:59:47.990548Z","caller":"traceutil/trace.go:171","msg":"trace[1658446643] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"541.965676ms","start":"2026-02-23T23:59:47.448557Z","end":"2026-02-23T23:59:47.990523Z","steps":["trace[1658446643] 'process raft request' (duration: 541.802932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T23:59:47.990691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T23:59:47.448541Z","time spent":"542.079868ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T23:59:48.011574Z","caller":"traceutil/trace.go:171","msg":"trace[53961698] linearizableReadLoop","detail":"{readStateIndex:794; appliedIndex:792; }","duration":"461.995064ms","start":"2026-02-23T23:59:47.549558Z","end":"2026-02-23T23:59:48.011554Z","steps":["trace[53961698] 'read index received' (duration: 440.928535ms)","trace[53961698] 'applied index is now lower than readState.Index' (duration: 21.065839ms)"],"step_count":2} {"level":"info","ts":"2026-02-23T23:59:48.011624Z","caller":"traceutil/trace.go:171","msg":"trace[148505894] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"562.796339ms","start":"2026-02-23T23:59:47.448801Z","end":"2026-02-23T23:59:48.011598Z","steps":["trace[148505894] 'process raft request' (duration: 562.632675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T23:59:48.011804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.234259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-23T23:59:48.011811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T23:59:47.448776Z","time spent":"562.911182ms","remote":"127.0.0.1:53288","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-23T23:59:48.011834Z","caller":"traceutil/trace.go:171","msg":"trace[1156355596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"462.307421ms","start":"2026-02-23T23:59:47.549519Z","end":"2026-02-23T23:59:48.011826Z","steps":["trace[1156355596] 'agreement among raft nodes before linearized reading' (duration: 462.123297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T23:59:48.01182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.398662ms","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-23T23:59:48.011881Z","caller":"traceutil/trace.go:171","msg":"trace[1228890399] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:765; }","duration":"317.507024ms","start":"2026-02-23T23:59:47.69436Z","end":"2026-02-23T23:59:48.011867Z","steps":["trace[1228890399] 'agreement among raft nodes before linearized reading' (duration: 317.33759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T23:59:48.01191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T23:59:47.549505Z","time spent":"462.397453ms","remote":"127.0.0.1:53288","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-23T23:59:48.01193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T23:59:47.694338Z","time spent":"317.577425ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-23T23:59:49.657243Z","caller":"traceutil/trace.go:171","msg":"trace[423256774] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"306.105564ms","start":"2026-02-23T23:59:49.351121Z","end":"2026-02-23T23:59:49.657226Z","steps":["trace[423256774] 'process raft request' (duration: 305.91254ms)"],"step_count":1} {"level":"warn","ts":"2026-02-23T23:59:49.657746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-23T23:59:49.351105Z","time spent":"306.566316ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-23T23:59:49.657168Z","caller":"traceutil/trace.go:171","msg":"trace[950660539] linearizableReadLoop","detail":"{readStateIndex:798; appliedIndex:797; }","duration":"187.976849ms","start":"2026-02-23T23:59:49.469176Z","end":"2026-02-23T23:59:49.657152Z","steps":["trace[950660539] 'read index received' (duration: 187.896557ms)","trace[950660539] 'applied index is now lower than readState.Index' (duration: 79.592µs)"],"step_count":2} {"level":"warn","ts":"2026-02-23T23:59:49.657349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.155813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-23T23:59:49.657927Z","caller":"traceutil/trace.go:171","msg":"trace[1017254895] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:768; }","duration":"188.752097ms","start":"2026-02-23T23:59:49.469127Z","end":"2026-02-23T23:59:49.657879Z","steps":["trace[1017254895] 'agreement among raft nodes before linearized reading' (duration: 188.179293ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T00:00:02.073019Z","caller":"traceutil/trace.go:171","msg":"trace[48667188] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"302.850697ms","start":"2026-02-24T00:00:01.770145Z","end":"2026-02-24T00:00:02.072995Z","steps":["trace[48667188] 'process raft request' (duration: 302.640553ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:02.073175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:01.770132Z","time spent":"302.95927ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T00:00:02.393296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.783996ms","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-24T00:00:02.393368Z","caller":"traceutil/trace.go:171","msg":"trace[305373645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:852; }","duration":"250.908179ms","start":"2026-02-24T00:00:02.142445Z","end":"2026-02-24T00:00:02.393353Z","steps":["trace[305373645] 'range keys from in-memory index tree' (duration: 250.610901ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:02.393385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.688637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-24T00:00:02.393358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.834398ms","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-02-24T00:00:02.393549Z","caller":"traceutil/trace.go:171","msg":"trace[21154016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:852; }","duration":"235.059453ms","start":"2026-02-24T00:00:02.158471Z","end":"2026-02-24T00:00:02.39353Z","steps":["trace[21154016] 'range keys from in-memory index tree' (duration: 234.643204ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T00:00:02.393484Z","caller":"traceutil/trace.go:171","msg":"trace[482206080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:852; }","duration":"233.050506ms","start":"2026-02-24T00:00:02.160412Z","end":"2026-02-24T00:00:02.393463Z","steps":["trace[482206080] 'range keys from in-memory index tree' (duration: 232.550354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:02.394217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.835264ms","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-24T00:00:02.394299Z","caller":"traceutil/trace.go:171","msg":"trace[1904754065] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:852; }","duration":"233.960147ms","start":"2026-02-24T00:00:02.160319Z","end":"2026-02-24T00:00:02.394279Z","steps":["trace[1904754065] 'range keys from in-memory index tree' (duration: 232.610305ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:14.066782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.632687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-24T00:00:14.066904Z","caller":"traceutil/trace.go:171","msg":"trace[530134648] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"667.691261ms","start":"2026-02-24T00:00:13.399194Z","end":"2026-02-24T00:00:14.066885Z","steps":["trace[530134648] 'process raft request' (duration: 491.736157ms)","trace[530134648] 'compare' (duration: 175.478753ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T00:00:14.066976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:13.399175Z","time spent":"667.763143ms","remote":"127.0.0.1:53066","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-24T00:00:14.071086Z","caller":"traceutil/trace.go:171","msg":"trace[1593832408] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"508.034193ms","start":"2026-02-24T00:00:13.563032Z","end":"2026-02-24T00:00:14.071067Z","steps":["trace[1593832408] 'process raft request' (duration: 507.760527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:14.071199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:13.563017Z","time spent":"508.122555ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T00:00:24.631547Z","caller":"traceutil/trace.go:171","msg":"trace[852208839] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"262.981284ms","start":"2026-02-24T00:00:24.368551Z","end":"2026-02-24T00:00:24.631532Z","steps":["trace[852208839] 'process raft request' (duration: 262.734748ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T00:00:28.862669Z","caller":"traceutil/trace.go:171","msg":"trace[372869940] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"109.811419ms","start":"2026-02-24T00:00:28.75283Z","end":"2026-02-24T00:00:28.862642Z","steps":["trace[372869940] 'process raft request' (duration: 56.156319ms)","trace[372869940] 'compare' (duration: 53.281311ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T00:00:32.842129Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548549087069591,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-24T00:00:33.083713Z","caller":"traceutil/trace.go:171","msg":"trace[164101007] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1002; }","duration":"742.312389ms","start":"2026-02-24T00:00:32.341378Z","end":"2026-02-24T00:00:33.08369Z","steps":["trace[164101007] 'read index received' (duration: 742.264348ms)","trace[164101007] 'applied index is now lower than readState.Index' (duration: 46.881µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T00:00:33.083811Z","caller":"traceutil/trace.go:171","msg":"trace[1920293754] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"814.840855ms","start":"2026-02-24T00:00:32.268924Z","end":"2026-02-24T00:00:33.083765Z","steps":["trace[1920293754] 'process raft request' (duration: 814.60595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:33.08387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.468282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-24T00:00:33.083923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.13849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-24T00:00:33.083955Z","caller":"traceutil/trace.go:171","msg":"trace[1400301448] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:963; }","duration":"742.566044ms","start":"2026-02-24T00:00:32.341366Z","end":"2026-02-24T00:00:33.083932Z","steps":["trace[1400301448] 'agreement among raft nodes before linearized reading' (duration: 742.420691ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T00:00:33.083965Z","caller":"traceutil/trace.go:171","msg":"trace[710829312] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:963; }","duration":"610.234003ms","start":"2026-02-24T00:00:32.473718Z","end":"2026-02-24T00:00:33.083952Z","steps":["trace[710829312] 'agreement among raft nodes before linearized reading' (duration: 610.11655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:33.084003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:32.341316Z","time spent":"742.678217ms","remote":"127.0.0.1:53052","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-24T00:00:33.084004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:32.473701Z","time spent":"610.293324ms","remote":"127.0.0.1:53510","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-24T00:00:33.084015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:32.268908Z","time spent":"814.996169ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-24T00:00:33.084033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.278167ms","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-02-24T00:00:33.084085Z","caller":"traceutil/trace.go:171","msg":"trace[612005108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:963; }","duration":"330.361529ms","start":"2026-02-24T00:00:32.753708Z","end":"2026-02-24T00:00:33.08407Z","steps":["trace[612005108] 'agreement among raft nodes before linearized reading' (duration: 330.200345ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:33.084087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.446111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-24T00:00:33.084118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:32.753701Z","time spent":"330.409631ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T00:00:33.084136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.464626ms","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-24T00:00:33.084145Z","caller":"traceutil/trace.go:171","msg":"trace[234270887] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:963; }","duration":"330.539823ms","start":"2026-02-24T00:00:32.753589Z","end":"2026-02-24T00:00:33.084128Z","steps":["trace[234270887] 'agreement among raft nodes before linearized reading' (duration: 330.363509ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T00:00:33.084185Z","caller":"traceutil/trace.go:171","msg":"trace[897620827] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:963; }","duration":"334.544798ms","start":"2026-02-24T00:00:32.749626Z","end":"2026-02-24T00:00:33.084171Z","steps":["trace[897620827] 'agreement among raft nodes before linearized reading' (duration: 334.379504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:33.08419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:32.753566Z","time spent":"330.612875ms","remote":"127.0.0.1:53288","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-24T00:00:33.084216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:32.749604Z","time spent":"334.605069ms","remote":"127.0.0.1:53288","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-24T00:00:33.08429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.544829ms","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-24T00:00:33.084318Z","caller":"traceutil/trace.go:171","msg":"trace[191534578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:963; }","duration":"209.59733ms","start":"2026-02-24T00:00:32.874712Z","end":"2026-02-24T00:00:33.084309Z","steps":["trace[191534578] 'agreement among raft nodes before linearized reading' (duration: 209.476547ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T00:00:33.255889Z","caller":"traceutil/trace.go:171","msg":"trace[61825967] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"166.641234ms","start":"2026-02-24T00:00:33.089217Z","end":"2026-02-24T00:00:33.255859Z","steps":["trace[61825967] 'process raft request' (duration: 124.610949ms)","trace[61825967] 'compare' (duration: 41.878391ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T00:00:33.288023Z","caller":"traceutil/trace.go:171","msg":"trace[1050808261] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1003; }","duration":"162.017525ms","start":"2026-02-24T00:00:33.125991Z","end":"2026-02-24T00:00:33.288008Z","steps":["trace[1050808261] 'read index received' (duration: 87.834759ms)","trace[1050808261] 'applied index is now lower than readState.Index' (duration: 74.181866ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T00:00:33.288143Z","caller":"traceutil/trace.go:171","msg":"trace[411817420] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"197.899503ms","start":"2026-02-24T00:00:33.090201Z","end":"2026-02-24T00:00:33.2881Z","steps":["trace[411817420] 'process raft request' (duration: 197.731959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:33.288194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.182589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T00:00:33.288242Z","caller":"traceutil/trace.go:171","msg":"trace[105556084] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:967; }","duration":"162.296292ms","start":"2026-02-24T00:00:33.125934Z","end":"2026-02-24T00:00:33.28823Z","steps":["trace[105556084] 'agreement among raft nodes before linearized reading' (duration: 162.134008ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T00:00:33.28824Z","caller":"traceutil/trace.go:171","msg":"trace[649261296] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"197.699228ms","start":"2026-02-24T00:00:33.090511Z","end":"2026-02-24T00:00:33.28821Z","steps":["trace[649261296] 'process raft request' (duration: 197.462923ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T00:00:33.288422Z","caller":"traceutil/trace.go:171","msg":"trace[338617877] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"198.671712ms","start":"2026-02-24T00:00:33.089735Z","end":"2026-02-24T00:00:33.288406Z","steps":["trace[338617877] 'process raft request' (duration: 198.059918ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T00:00:33.46844Z","caller":"traceutil/trace.go:171","msg":"trace[538804702] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"124.818575ms","start":"2026-02-24T00:00:33.343596Z","end":"2026-02-24T00:00:33.468414Z","steps":["trace[538804702] 'read index received' (duration: 59.422016ms)","trace[538804702] 'applied index is now lower than readState.Index' (duration: 65.395039ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T00:00:33.46856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.944757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T00:00:33.468591Z","caller":"traceutil/trace.go:171","msg":"trace[923511393] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:967; }","duration":"125.03236ms","start":"2026-02-24T00:00:33.34355Z","end":"2026-02-24T00:00:33.468582Z","steps":["trace[923511393] 'agreement among raft nodes before linearized reading' (duration: 124.956168ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T00:00:35.531893Z","caller":"traceutil/trace.go:171","msg":"trace[2034155520] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"519.118836ms","start":"2026-02-24T00:00:35.012658Z","end":"2026-02-24T00:00:35.531777Z","steps":["trace[2034155520] 'process raft request' (duration: 518.574103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:35.534297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:35.012635Z","time spent":"521.278137ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T00:00:35.597772Z","caller":"traceutil/trace.go:171","msg":"trace[496544740] linearizableReadLoop","detail":"{readStateIndex:1012; appliedIndex:1010; }","duration":"508.872963ms","start":"2026-02-24T00:00:35.088868Z","end":"2026-02-24T00:00:35.597741Z","steps":["trace[496544740] 'read index received' (duration: 441.896748ms)","trace[496544740] 'applied index is now lower than readState.Index' (duration: 66.973885ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T00:00:35.597933Z","caller":"traceutil/trace.go:171","msg":"trace[2065174018] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"583.077519ms","start":"2026-02-24T00:00:35.014811Z","end":"2026-02-24T00:00:35.597888Z","steps":["trace[2065174018] 'process raft request' (duration: 582.69756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:35.598134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.226422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-24T00:00:35.598176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:35.014797Z","time spent":"583.236303ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T00:00:35.598213Z","caller":"traceutil/trace.go:171","msg":"trace[364562955] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:971; }","duration":"509.394306ms","start":"2026-02-24T00:00:35.0888Z","end":"2026-02-24T00:00:35.598194Z","steps":["trace[364562955] 'agreement among raft nodes before linearized reading' (duration: 509.071838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:35.598268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:35.08877Z","time spent":"509.489308ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T00:00:35.598262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.702605ms","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-02-24T00:00:35.598328Z","caller":"traceutil/trace.go:171","msg":"trace[1274007249] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:971; }","duration":"305.818177ms","start":"2026-02-24T00:00:35.29249Z","end":"2026-02-24T00:00:35.598308Z","steps":["trace[1274007249] 'agreement among raft nodes before linearized reading' (duration: 305.612852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:35.598385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:35.292477Z","time spent":"305.89496ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-24T00:00:35.598282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.753086ms","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-02-24T00:00:35.598461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.190754ms","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-24T00:00:35.598554Z","caller":"traceutil/trace.go:171","msg":"trace[1985927990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:971; }","duration":"338.363198ms","start":"2026-02-24T00:00:35.260175Z","end":"2026-02-24T00:00:35.598538Z","steps":["trace[1985927990] 'agreement among raft nodes before linearized reading' (duration: 337.823675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:35.598785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:35.26015Z","time spent":"338.620204ms","remote":"127.0.0.1:53288","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-24T00:00:35.598668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.372171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T00:00:35.598979Z","caller":"traceutil/trace.go:171","msg":"trace[551770474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:971; }","duration":"257.792501ms","start":"2026-02-24T00:00:35.341165Z","end":"2026-02-24T00:00:35.598958Z","steps":["trace[551770474] 'agreement among raft nodes before linearized reading' (duration: 257.030394ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T00:00:35.598462Z","caller":"traceutil/trace.go:171","msg":"trace[774539898] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:971; }","duration":"305.964891ms","start":"2026-02-24T00:00:35.292477Z","end":"2026-02-24T00:00:35.598442Z","steps":["trace[774539898] 'agreement among raft nodes before linearized reading' (duration: 305.729436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:35.599231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:35.292456Z","time spent":"306.760889ms","remote":"127.0.0.1:53288","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-24T00:00:35.598439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.388377ms","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-24T00:00:35.599385Z","caller":"traceutil/trace.go:171","msg":"trace[1964877117] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:971; }","duration":"306.419372ms","start":"2026-02-24T00:00:35.292951Z","end":"2026-02-24T00:00:35.59937Z","steps":["trace[1964877117] 'agreement among raft nodes before linearized reading' (duration: 305.072481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:35.599452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:35.292902Z","time spent":"306.534805ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T00:00:35.850241Z","caller":"traceutil/trace.go:171","msg":"trace[1505370301] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"246.763ms","start":"2026-02-24T00:00:35.603448Z","end":"2026-02-24T00:00:35.850211Z","steps":["trace[1505370301] 'process raft request' (duration: 181.920006ms)","trace[1505370301] 'compare' (duration: 64.6726ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T00:00:36.017652Z","caller":"traceutil/trace.go:171","msg":"trace[587114294] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"413.310833ms","start":"2026-02-24T00:00:35.604321Z","end":"2026-02-24T00:00:36.017632Z","steps":["trace[587114294] 'process raft request' (duration: 413.273831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:36.017804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:35.604314Z","time spent":"413.413785ms","remote":"127.0.0.1:53288","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-24T00:00:36.017941Z","caller":"traceutil/trace.go:171","msg":"trace[163265058] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"414.405018ms","start":"2026-02-24T00:00:35.603467Z","end":"2026-02-24T00:00:36.017872Z","steps":["trace[163265058] 'process raft request' (duration: 413.836294ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T00:00:36.018043Z","caller":"traceutil/trace.go:171","msg":"trace[2087659069] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"414.50845ms","start":"2026-02-24T00:00:35.603523Z","end":"2026-02-24T00:00:36.018031Z","steps":["trace[2087659069] 'process raft request' (duration: 414.040698ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T00:00:36.018036Z","caller":"traceutil/trace.go:171","msg":"trace[1649803274] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"414.49289ms","start":"2026-02-24T00:00:35.603497Z","end":"2026-02-24T00:00:36.01799Z","steps":["trace[1649803274] 'process raft request' (duration: 414.023898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:36.018205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:35.603424Z","time spent":"414.625513ms","remote":"127.0.0.1:53288","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-24T00:00:36.018262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:35.60348Z","time spent":"414.668874ms","remote":"127.0.0.1:53288","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-24T00:00:36.018099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:35.603516Z","time spent":"414.553532ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T00:00:37.996922Z","caller":"traceutil/trace.go:171","msg":"trace[1006516891] linearizableReadLoop","detail":"{readStateIndex:1024; appliedIndex:1023; }","duration":"142.589715ms","start":"2026-02-24T00:00:37.854308Z","end":"2026-02-24T00:00:37.996898Z","steps":["trace[1006516891] 'read index received' (duration: 142.314449ms)","trace[1006516891] 'applied index is now lower than readState.Index' (duration: 274.346µs)"],"step_count":2} {"level":"info","ts":"2026-02-24T00:00:37.997253Z","caller":"traceutil/trace.go:171","msg":"trace[540163264] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"350.288441ms","start":"2026-02-24T00:00:37.646941Z","end":"2026-02-24T00:00:37.99723Z","steps":["trace[540163264] 'process raft request' (duration: 349.735728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:37.997292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.792239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-02-24T00:00:37.997366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:37.646909Z","time spent":"350.399503ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-24T00:00:37.997439Z","caller":"traceutil/trace.go:171","msg":"trace[1449886805] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:983; }","duration":"143.061686ms","start":"2026-02-24T00:00:37.85426Z","end":"2026-02-24T00:00:37.997322Z","steps":["trace[1449886805] 'agreement among raft nodes before linearized reading' (duration: 142.733258ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T00:00:38.165509Z","caller":"traceutil/trace.go:171","msg":"trace[1986533835] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1024; }","duration":"142.441391ms","start":"2026-02-24T00:00:38.023038Z","end":"2026-02-24T00:00:38.16548Z","steps":["trace[1986533835] 'read index received' (duration: 74.649247ms)","trace[1986533835] 'applied index is now lower than readState.Index' (duration: 67.790344ms)"],"step_count":2} {"level":"info","ts":"2026-02-24T00:00:38.165587Z","caller":"traceutil/trace.go:171","msg":"trace[552983251] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"163.478868ms","start":"2026-02-24T00:00:38.002081Z","end":"2026-02-24T00:00:38.16556Z","steps":["trace[552983251] 'process raft request' (duration: 95.664804ms)","trace[552983251] 'compare' (duration: 67.22183ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T00:00:38.166004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.945699ms","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-24T00:00:38.166049Z","caller":"traceutil/trace.go:171","msg":"trace[961084000] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:984; }","duration":"142.03195ms","start":"2026-02-24T00:00:38.024006Z","end":"2026-02-24T00:00:38.166038Z","steps":["trace[961084000] 'agreement among raft nodes before linearized reading' (duration: 141.870527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:38.166053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.116752ms","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-24T00:00:38.166285Z","caller":"traceutil/trace.go:171","msg":"trace[88903686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:984; }","duration":"142.362629ms","start":"2026-02-24T00:00:38.023902Z","end":"2026-02-24T00:00:38.166265Z","steps":["trace[88903686] 'agreement among raft nodes before linearized reading' (duration: 142.025211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:38.165879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.479152ms","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-24T00:00:38.166584Z","caller":"traceutil/trace.go:171","msg":"trace[796816109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:984; }","duration":"143.208231ms","start":"2026-02-24T00:00:38.02335Z","end":"2026-02-24T00:00:38.166558Z","steps":["trace[796816109] 'agreement among raft nodes before linearized reading' (duration: 142.36836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:38.166941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.864995ms","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-02-24T00:00:38.167195Z","caller":"traceutil/trace.go:171","msg":"trace[1916543279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:984; }","duration":"144.04884ms","start":"2026-02-24T00:00:38.022985Z","end":"2026-02-24T00:00:38.167034Z","steps":["trace[1916543279] 'agreement among raft nodes before linearized reading' (duration: 142.662426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:38.841904Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548549087069634,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-24T00:00:39.043491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.797049ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T00:00:39.043832Z","caller":"traceutil/trace.go:171","msg":"trace[381175719] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:988; }","duration":"152.226548ms","start":"2026-02-24T00:00:38.891589Z","end":"2026-02-24T00:00:39.043815Z","steps":["trace[381175719] 'range keys from in-memory index tree' (duration: 151.781748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:39.045375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.530895ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-24T00:00:39.045438Z","caller":"traceutil/trace.go:171","msg":"trace[598515529] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"703.772499ms","start":"2026-02-24T00:00:38.341653Z","end":"2026-02-24T00:00:39.045426Z","steps":["trace[598515529] 'read index received' (duration: 505.115571ms)","trace[598515529] 'applied index is now lower than readState.Index' (duration: 198.655668ms)"],"step_count":2} {"level":"warn","ts":"2026-02-24T00:00:39.045492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.851811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-24T00:00:39.045514Z","caller":"traceutil/trace.go:171","msg":"trace[200142950] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:988; }","duration":"703.916863ms","start":"2026-02-24T00:00:38.341589Z","end":"2026-02-24T00:00:39.045506Z","steps":["trace[200142950] 'agreement among raft nodes before linearized reading' (duration: 703.869712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:00:39.045603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:00:38.341568Z","time spent":"704.021515ms","remote":"127.0.0.1:53036","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-24T00:00:41.459303Z","caller":"traceutil/trace.go:171","msg":"trace[752191279] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"142.276683ms","start":"2026-02-24T00:00:41.317002Z","end":"2026-02-24T00:00:41.459279Z","steps":["trace[752191279] 'process raft request' (duration: 142.126479ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T00:03:42.764107Z","caller":"traceutil/trace.go:171","msg":"trace[1094904266] transaction","detail":"{read_only:false; response_revision:1792; number_of_response:1; }","duration":"165.793263ms","start":"2026-02-24T00:03:42.598287Z","end":"2026-02-24T00:03:42.76408Z","steps":["trace[1094904266] 'process raft request' (duration: 165.579488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:03:43.391516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.194233ms","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-02-24T00:03:43.391602Z","caller":"traceutil/trace.go:171","msg":"trace[1947583113] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1792; }","duration":"357.309197ms","start":"2026-02-24T00:03:43.034277Z","end":"2026-02-24T00:03:43.391586Z","steps":["trace[1947583113] 'range keys from in-memory index tree' (duration: 357.055801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:03:43.391698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.566515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-24T00:03:43.391743Z","caller":"traceutil/trace.go:171","msg":"trace[294274846] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1792; }","duration":"331.628806ms","start":"2026-02-24T00:03:43.060103Z","end":"2026-02-24T00:03:43.391732Z","steps":["trace[294274846] 'range keys from in-memory index tree' (duration: 331.480033ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:03:43.39177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:03:43.060094Z","time spent":"331.667908ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-24T00:03:43.391718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.272176ms","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-02-24T00:03:43.392348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.78977ms","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-24T00:03:43.392409Z","caller":"traceutil/trace.go:171","msg":"trace[649535757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1792; }","duration":"363.851321ms","start":"2026-02-24T00:03:43.028542Z","end":"2026-02-24T00:03:43.392394Z","steps":["trace[649535757] 'range keys from in-memory index tree' (duration: 363.616496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:03:43.391997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.253782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-24T00:03:43.392452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:03:43.028513Z","time spent":"363.926483ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-24T00:03:43.392521Z","caller":"traceutil/trace.go:171","msg":"trace[1659628423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1792; }","duration":"367.800304ms","start":"2026-02-24T00:03:43.024679Z","end":"2026-02-24T00:03:43.392479Z","steps":["trace[1659628423] 'range keys from in-memory index tree' (duration: 367.067197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:03:43.392608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:03:43.02466Z","time spent":"367.932018ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-24T00:03:43.392295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.585863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-24T00:03:43.392833Z","caller":"traceutil/trace.go:171","msg":"trace[153392031] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:1792; }","duration":"256.143186ms","start":"2026-02-24T00:03:43.136662Z","end":"2026-02-24T00:03:43.392806Z","steps":["trace[153392031] 'range keys from in-memory index tree' (duration: 255.450179ms)"],"step_count":1} {"level":"info","ts":"2026-02-24T00:03:43.392964Z","caller":"traceutil/trace.go:171","msg":"trace[1585680301] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1792; }","duration":"352.961873ms","start":"2026-02-24T00:03:43.039413Z","end":"2026-02-24T00:03:43.392375Z","steps":["trace[1585680301] 'range keys from in-memory index tree' (duration: 352.075662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:03:43.393043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:03:43.039405Z","time spent":"353.617708ms","remote":"127.0.0.1:53288","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-24T00:03:43.392199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.081644ms","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-24T00:03:43.393108Z","caller":"traceutil/trace.go:171","msg":"trace[626926711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1792; }","duration":"367.025007ms","start":"2026-02-24T00:03:43.026075Z","end":"2026-02-24T00:03:43.3931Z","steps":["trace[626926711] 'range keys from in-memory index tree' (duration: 365.928981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-24T00:03:43.393135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:03:43.026066Z","time spent":"367.060498ms","remote":"127.0.0.1:53288","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-24T00:03:43.392176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-24T00:03:43.034271Z","time spent":"357.44071ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}