{"level":"warn","ts":"2026-02-03T17:27:29.833685Z","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-03T17:27:29.833959Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.219: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.219:2380","--initial-cluster=instance=https://199.19.213.219:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.219:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.219: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-03T17:27:29.835525Z","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-03T17:27:29.835602Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.219:2380"]} {"level":"info","ts":"2026-02-03T17:27:29.837123Z","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-03T17:27:29.837781Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"]} {"level":"info","ts":"2026-02-03T17:27:29.83793Z","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":2,"max-cpu-available":2,"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.219:2380"],"listen-peer-urls":["https://199.19.213.219:2380"],"advertise-client-urls":["https://199.19.213.219:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.219: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-03T17:27:29.844497Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.292262ms"} {"level":"info","ts":"2026-02-03T17:27:29.854043Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7e142186203fa167","cluster-id":"e7586aea7e662ee6"} {"level":"info","ts":"2026-02-03T17:27:29.854205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T17:27:29.854266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became follower at term 0"} {"level":"info","ts":"2026-02-03T17:27:29.854338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7e142186203fa167 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T17:27:29.854404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became follower at term 1"} {"level":"info","ts":"2026-02-03T17:27:29.854515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=(9084923208263508327)"} {"level":"warn","ts":"2026-02-03T17:27:29.861573Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T17:27:29.871782Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T17:27:29.874251Z","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-03T17:27:29.880178Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7e142186203fa167","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T17:27:29.882615Z","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-03T17:27:29.883168Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7e142186203fa167","initial-advertise-peer-urls":["https://199.19.213.219:2380"],"listen-peer-urls":["https://199.19.213.219:2380"],"advertise-client-urls":["https://199.19.213.219:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T17:27:29.883273Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T17:27:29.883431Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7e142186203fa167","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T17:27:29.883585Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.219:2380"} {"level":"info","ts":"2026-02-03T17:27:29.884937Z","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-03T17:27:29.888205Z","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-03T17:27:29.888248Z","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-03T17:27:29.888585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=(9084923208263508327)"} {"level":"info","ts":"2026-02-03T17:27:29.888706Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7586aea7e662ee6","local-member-id":"7e142186203fa167","added-peer-id":"7e142186203fa167","added-peer-peer-urls":["https://199.19.213.219:2380"]} {"level":"info","ts":"2026-02-03T17:27:29.885101Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.219:2380"} {"level":"info","ts":"2026-02-03T17:27:30.162993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T17:27:30.163091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T17:27:30.163125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 received MsgPreVoteResp from 7e142186203fa167 at term 1"} {"level":"info","ts":"2026-02-03T17:27:30.163257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became candidate at term 2"} {"level":"info","ts":"2026-02-03T17:27:30.163279Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 received MsgVoteResp from 7e142186203fa167 at term 2"} {"level":"info","ts":"2026-02-03T17:27:30.163338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became leader at term 2"} {"level":"info","ts":"2026-02-03T17:27:30.16342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7e142186203fa167 elected leader 7e142186203fa167 at term 2"} {"level":"info","ts":"2026-02-03T17:27:30.165629Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:27:30.169416Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7e142186203fa167","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.219:2379]}","request-path":"/0/members/7e142186203fa167/attributes","cluster-id":"e7586aea7e662ee6","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T17:27:30.169919Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T17:27:30.170607Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T17:27:30.170644Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7586aea7e662ee6","local-member-id":"7e142186203fa167","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:27:30.17281Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:27:30.172897Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:27:30.170667Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T17:27:30.184871Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-03T17:27:30.188641Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T17:27:30.192769Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.219:2379"} {"level":"info","ts":"2026-02-03T17:27:40.777825Z","caller":"traceutil/trace.go:171","msg":"trace[763707012] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"185.768365ms","start":"2026-02-03T17:27:40.592039Z","end":"2026-02-03T17:27:40.777807Z","steps":["trace[763707012] 'process raft request' (duration: 109.694034ms)","trace[763707012] 'compare' (duration: 75.917287ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:40.818012Z","caller":"traceutil/trace.go:171","msg":"trace[292263256] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"224.581795ms","start":"2026-02-03T17:27:40.593409Z","end":"2026-02-03T17:27:40.817991Z","steps":["trace[292263256] 'process raft request' (duration: 224.509953ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:40.818392Z","caller":"traceutil/trace.go:171","msg":"trace[401534790] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"225.009825ms","start":"2026-02-03T17:27:40.593363Z","end":"2026-02-03T17:27:40.818373Z","steps":["trace[401534790] 'process raft request' (duration: 224.454732ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:41.596538Z","caller":"traceutil/trace.go:171","msg":"trace[1489042446] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"133.593933ms","start":"2026-02-03T17:27:41.46292Z","end":"2026-02-03T17:27:41.596514Z","steps":["trace[1489042446] 'process raft request' (duration: 133.44638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:41.900652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.08813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:27:41.900728Z","caller":"traceutil/trace.go:171","msg":"trace[207231789] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"292.764304ms","start":"2026-02-03T17:27:41.607953Z","end":"2026-02-03T17:27:41.900717Z","steps":["trace[207231789] 'process raft request' (duration: 83.277425ms)","trace[207231789] 'compare' (duration: 208.873794ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:48.333586Z","caller":"traceutil/trace.go:171","msg":"trace[693686128] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"195.377994ms","start":"2026-02-03T17:27:48.138184Z","end":"2026-02-03T17:27:48.333562Z","steps":["trace[693686128] 'process raft request' (duration: 194.971064ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:48.694285Z","caller":"traceutil/trace.go:171","msg":"trace[1828745287] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"223.094205ms","start":"2026-02-03T17:27:48.47116Z","end":"2026-02-03T17:27:48.694255Z","steps":["trace[1828745287] 'process raft request' (duration: 223.039624ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:48.694453Z","caller":"traceutil/trace.go:171","msg":"trace[134334502] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"219.068268ms","start":"2026-02-03T17:27:48.475376Z","end":"2026-02-03T17:27:48.694444Z","steps":["trace[134334502] 'process raft request' (duration: 219.030247ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:48.694462Z","caller":"traceutil/trace.go:171","msg":"trace[863334873] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"223.426873ms","start":"2026-02-03T17:27:48.471027Z","end":"2026-02-03T17:27:48.694454Z","steps":["trace[863334873] 'process raft request' (duration: 147.753289ms)","trace[863334873] 'compare' (duration: 75.310765ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:48.694592Z","caller":"traceutil/trace.go:171","msg":"trace[1307889376] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"223.348091ms","start":"2026-02-03T17:27:48.471235Z","end":"2026-02-03T17:27:48.694583Z","steps":["trace[1307889376] 'process raft request' (duration: 222.997182ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:48.698035Z","caller":"traceutil/trace.go:171","msg":"trace[421533290] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"175.851801ms","start":"2026-02-03T17:27:48.522167Z","end":"2026-02-03T17:27:48.698019Z","steps":["trace[421533290] 'process raft request' (duration: 175.80032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:49.543238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.972629ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T17:27:49.543508Z","caller":"traceutil/trace.go:171","msg":"trace[884615471] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"632.804934ms","start":"2026-02-03T17:27:48.910687Z","end":"2026-02-03T17:27:49.543492Z","steps":["trace[884615471] 'process raft request' (duration: 632.756092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:49.5436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:48.910672Z","time spent":"632.870545ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:49.543828Z","caller":"traceutil/trace.go:171","msg":"trace[1822834783] linearizableReadLoop","detail":"{readStateIndex:426; appliedIndex:425; }","duration":"641.181536ms","start":"2026-02-03T17:27:48.902635Z","end":"2026-02-03T17:27:49.543817Z","steps":["trace[1822834783] 'read index received' (duration: 35.644873ms)","trace[1822834783] 'applied index is now lower than readState.Index' (duration: 605.535642ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:49.543946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.637108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4173"} {"level":"info","ts":"2026-02-03T17:27:49.543976Z","caller":"traceutil/trace.go:171","msg":"trace[932602598] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:415; }","duration":"653.69326ms","start":"2026-02-03T17:27:48.890272Z","end":"2026-02-03T17:27:49.543965Z","steps":["trace[932602598] 'agreement among raft nodes before linearized reading' (duration: 653.582687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:49.544006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:48.89026Z","time spent":"653.740271ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4196,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"info","ts":"2026-02-03T17:27:49.544621Z","caller":"traceutil/trace.go:171","msg":"trace[1815467882] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"636.034381ms","start":"2026-02-03T17:27:48.908572Z","end":"2026-02-03T17:27:49.544606Z","steps":["trace[1815467882] 'process raft request' (duration: 634.76657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:49.544883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:48.90855Z","time spent":"636.100983ms","remote":"127.0.0.1:57238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3818,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:28:02.74083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.651383ms","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-03T17:28:02.740887Z","caller":"traceutil/trace.go:171","msg":"trace[198161660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:480; }","duration":"172.744055ms","start":"2026-02-03T17:28:02.568129Z","end":"2026-02-03T17:28:02.740873Z","steps":["trace[198161660] 'range keys from in-memory index tree' (duration: 172.585211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:02.740942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.701594ms","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-03T17:28:02.741402Z","caller":"traceutil/trace.go:171","msg":"trace[111221990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:480; }","duration":"173.197107ms","start":"2026-02-03T17:28:02.568192Z","end":"2026-02-03T17:28:02.741389Z","steps":["trace[111221990] 'range keys from in-memory index tree' (duration: 172.53321ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:03.077812Z","caller":"traceutil/trace.go:171","msg":"trace[1962066458] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"333.84396ms","start":"2026-02-03T17:28:02.743761Z","end":"2026-02-03T17:28:03.077605Z","steps":["trace[1962066458] 'process raft request' (duration: 333.687786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:03.077906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:02.743745Z","time spent":"334.106536ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:28:03.248571Z","caller":"traceutil/trace.go:171","msg":"trace[1365429409] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:495; }","duration":"292.319263ms","start":"2026-02-03T17:28:02.956235Z","end":"2026-02-03T17:28:03.248555Z","steps":["trace[1365429409] 'read index received' (duration: 121.32481ms)","trace[1365429409] 'applied index is now lower than readState.Index' (duration: 170.993763ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:03.248706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.455567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:03.248732Z","caller":"traceutil/trace.go:171","msg":"trace[1836686784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:483; }","duration":"292.535918ms","start":"2026-02-03T17:28:02.956189Z","end":"2026-02-03T17:28:03.248725Z","steps":["trace[1836686784] 'agreement among raft nodes before linearized reading' (duration: 292.429046ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:03.248943Z","caller":"traceutil/trace.go:171","msg":"trace[181915074] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"504.279729ms","start":"2026-02-03T17:28:02.744653Z","end":"2026-02-03T17:28:03.248933Z","steps":["trace[181915074] 'process raft request' (duration: 503.773577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:03.249007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:02.74464Z","time spent":"504.32562ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:28:03.249099Z","caller":"traceutil/trace.go:171","msg":"trace[1281223826] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"270.685669ms","start":"2026-02-03T17:28:02.978406Z","end":"2026-02-03T17:28:03.249092Z","steps":["trace[1281223826] 'process raft request' (duration: 270.121136ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:04.639825Z","caller":"traceutil/trace.go:171","msg":"trace[957835169] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"211.388842ms","start":"2026-02-03T17:28:04.42842Z","end":"2026-02-03T17:28:04.639809Z","steps":["trace[957835169] 'process raft request' (duration: 211.295549ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:06.387637Z","caller":"traceutil/trace.go:171","msg":"trace[1633177108] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"116.661966ms","start":"2026-02-03T17:28:06.270786Z","end":"2026-02-03T17:28:06.387448Z","steps":["trace[1633177108] 'process raft request' (duration: 116.521103ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:06.955215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.98489ms","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-03T17:28:06.955294Z","caller":"traceutil/trace.go:171","msg":"trace[1919070511] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:490; }","duration":"312.115363ms","start":"2026-02-03T17:28:06.643161Z","end":"2026-02-03T17:28:06.955276Z","steps":["trace[1919070511] 'range keys from in-memory index tree' (duration: 311.817106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:06.95534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:06.643143Z","time spent":"312.188535ms","remote":"127.0.0.1:56984","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-03T17:28:11.755559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.888573ms","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-03T17:28:11.755621Z","caller":"traceutil/trace.go:171","msg":"trace[941554978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:501; }","duration":"474.002366ms","start":"2026-02-03T17:28:11.281603Z","end":"2026-02-03T17:28:11.755606Z","steps":["trace[941554978] 'range keys from in-memory index tree' (duration: 473.76216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:11.755657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:11.281581Z","time spent":"474.068237ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:28:11.756035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.639085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-02-03T17:28:11.756085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.481591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-03T17:28:11.756111Z","caller":"traceutil/trace.go:171","msg":"trace[1710351849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:501; }","duration":"555.506042ms","start":"2026-02-03T17:28:11.200597Z","end":"2026-02-03T17:28:11.756103Z","steps":["trace[1710351849] 'range keys from in-memory index tree' (duration: 555.332687ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:11.756104Z","caller":"traceutil/trace.go:171","msg":"trace[1744143959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:501; }","duration":"716.743068ms","start":"2026-02-03T17:28:11.039343Z","end":"2026-02-03T17:28:11.756086Z","steps":["trace[1744143959] 'range keys from in-memory index tree' (duration: 716.250975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:11.756131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:11.200544Z","time spent":"555.582883ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-03T17:28:11.756144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:11.03933Z","time spent":"716.805009ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-03T17:28:11.756393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.261779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dkx28\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-03T17:28:11.756422Z","caller":"traceutil/trace.go:171","msg":"trace[384777100] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dkx28; range_end:; response_count:1; response_revision:501; }","duration":"159.316501ms","start":"2026-02-03T17:28:11.597097Z","end":"2026-02-03T17:28:11.756413Z","steps":["trace[384777100] 'range keys from in-memory index tree' (duration: 158.813359ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:11.913818Z","caller":"traceutil/trace.go:171","msg":"trace[1562566480] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"149.467203ms","start":"2026-02-03T17:28:11.764329Z","end":"2026-02-03T17:28:11.913796Z","steps":["trace[1562566480] 'process raft request' (duration: 149.32773ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:12.411431Z","caller":"traceutil/trace.go:171","msg":"trace[1516395952] linearizableReadLoop","detail":"{readStateIndex:523; appliedIndex:519; }","duration":"455.129688ms","start":"2026-02-03T17:28:11.956285Z","end":"2026-02-03T17:28:12.411415Z","steps":["trace[1516395952] 'read index received' (duration: 454.889902ms)","trace[1516395952] 'applied index is now lower than readState.Index' (duration: 239.186µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:12.411576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.275013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:12.411596Z","caller":"traceutil/trace.go:171","msg":"trace[496013283] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"455.334923ms","start":"2026-02-03T17:28:11.956254Z","end":"2026-02-03T17:28:12.411589Z","steps":["trace[496013283] 'agreement among raft nodes before linearized reading' (duration: 455.250991ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:12.411618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:11.95624Z","time spent":"455.373345ms","remote":"127.0.0.1:56750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T17:28:12.412414Z","caller":"traceutil/trace.go:171","msg":"trace[743704899] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"647.970142ms","start":"2026-02-03T17:28:11.764434Z","end":"2026-02-03T17:28:12.412404Z","steps":["trace[743704899] 'process raft request' (duration: 646.783762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:12.412475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:11.764429Z","time spent":"648.007413ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:28:12.41257Z","caller":"traceutil/trace.go:171","msg":"trace[164957266] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"648.064344ms","start":"2026-02-03T17:28:11.764499Z","end":"2026-02-03T17:28:12.412564Z","steps":["trace[164957266] 'process raft request' (duration: 646.799722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:12.412601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:11.764462Z","time spent":"648.122855ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:28:12.412691Z","caller":"traceutil/trace.go:171","msg":"trace[1015778143] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"645.220715ms","start":"2026-02-03T17:28:11.767462Z","end":"2026-02-03T17:28:12.412683Z","steps":["trace[1015778143] 'process raft request' (duration: 643.916822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:12.412726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:11.767457Z","time spent":"645.250235ms","remote":"127.0.0.1:56920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:28:12.41282Z","caller":"traceutil/trace.go:171","msg":"trace[1506834771] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"647.332056ms","start":"2026-02-03T17:28:11.765482Z","end":"2026-02-03T17:28:12.412814Z","steps":["trace[1506834771] 'process raft request' (duration: 645.841399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:12.412848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:11.765476Z","time spent":"647.356797ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:28:14.174279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.259095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:14.174334Z","caller":"traceutil/trace.go:171","msg":"trace[1946760739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"217.344797ms","start":"2026-02-03T17:28:13.956978Z","end":"2026-02-03T17:28:14.174322Z","steps":["trace[1946760739] 'range keys from in-memory index tree' (duration: 217.214634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:14.174545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.991684ms","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-03T17:28:14.17457Z","caller":"traceutil/trace.go:171","msg":"trace[764464748] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:516; }","duration":"256.063596ms","start":"2026-02-03T17:28:13.918498Z","end":"2026-02-03T17:28:14.174562Z","steps":["trace[764464748] 'range keys from in-memory index tree' (duration: 255.822339ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:20.8634Z","caller":"traceutil/trace.go:171","msg":"trace[1777939456] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"128.381181ms","start":"2026-02-03T17:28:20.735001Z","end":"2026-02-03T17:28:20.863382Z","steps":["trace[1777939456] 'process raft request' (duration: 128.271518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:24.777376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.577398ms","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-03T17:28:24.777526Z","caller":"traceutil/trace.go:171","msg":"trace[432108449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"280.744293ms","start":"2026-02-03T17:28:24.496769Z","end":"2026-02-03T17:28:24.777514Z","steps":["trace[432108449] 'range keys from in-memory index tree' (duration: 280.528018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:24.777782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.43021ms","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-03T17:28:24.777801Z","caller":"traceutil/trace.go:171","msg":"trace[2141446668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:634; }","duration":"293.486562ms","start":"2026-02-03T17:28:24.48431Z","end":"2026-02-03T17:28:24.777796Z","steps":["trace[2141446668] 'range keys from in-memory index tree' (duration: 293.343529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:24.778262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.516031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T17:28:24.778299Z","caller":"traceutil/trace.go:171","msg":"trace[331807429] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:634; }","duration":"243.92599ms","start":"2026-02-03T17:28:24.534353Z","end":"2026-02-03T17:28:24.778279Z","steps":["trace[331807429] 'range keys from in-memory index tree' (duration: 243.46035ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:24.778338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.359141ms","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-03T17:28:24.778352Z","caller":"traceutil/trace.go:171","msg":"trace[1411179300] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"289.753751ms","start":"2026-02-03T17:28:24.488595Z","end":"2026-02-03T17:28:24.778348Z","steps":["trace[1411179300] 'range keys from in-memory index tree' (duration: 289.315601ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:25.081891Z","caller":"traceutil/trace.go:171","msg":"trace[360744119] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"170.216304ms","start":"2026-02-03T17:28:24.911658Z","end":"2026-02-03T17:28:25.081874Z","steps":["trace[360744119] 'read index received' (duration: 46.017794ms)","trace[360744119] 'applied index is now lower than readState.Index' (duration: 124.19792ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:25.082056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.379987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-6zmmp\" ","response":"range_response_count:1 size:4987"} {"level":"info","ts":"2026-02-03T17:28:25.082084Z","caller":"traceutil/trace.go:171","msg":"trace[2105372003] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-6zmmp; range_end:; response_count:1; response_revision:646; }","duration":"170.413238ms","start":"2026-02-03T17:28:24.911655Z","end":"2026-02-03T17:28:25.082068Z","steps":["trace[2105372003] 'agreement among raft nodes before linearized reading' (duration: 170.291315ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:25.082368Z","caller":"traceutil/trace.go:171","msg":"trace[1751941017] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"170.912991ms","start":"2026-02-03T17:28:24.911448Z","end":"2026-02-03T17:28:25.082361Z","steps":["trace[1751941017] 'process raft request' (duration: 170.241884ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:25.082498Z","caller":"traceutil/trace.go:171","msg":"trace[84803228] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"169.609621ms","start":"2026-02-03T17:28:24.912855Z","end":"2026-02-03T17:28:25.082464Z","steps":["trace[84803228] 'process raft request' (duration: 168.967584ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:25.131231Z","caller":"traceutil/trace.go:171","msg":"trace[30762411] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"102.267817ms","start":"2026-02-03T17:28:25.028945Z","end":"2026-02-03T17:28:25.131213Z","steps":["trace[30762411] 'process raft request' (duration: 102.173195ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:25.313714Z","caller":"traceutil/trace.go:171","msg":"trace[544311981] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"220.716469ms","start":"2026-02-03T17:28:25.092975Z","end":"2026-02-03T17:28:25.313692Z","steps":["trace[544311981] 'process raft request' (duration: 220.656858ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:25.314141Z","caller":"traceutil/trace.go:171","msg":"trace[107746314] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"224.236273ms","start":"2026-02-03T17:28:25.08989Z","end":"2026-02-03T17:28:25.314127Z","steps":["trace[107746314] 'process raft request' (duration: 223.603718ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:26.469101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.444666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:26.469501Z","caller":"traceutil/trace.go:171","msg":"trace[1674734819] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:675; }","duration":"514.906757ms","start":"2026-02-03T17:28:25.954575Z","end":"2026-02-03T17:28:26.469482Z","steps":["trace[1674734819] 'range keys from in-memory index tree' (duration: 514.377005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:26.469657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.441061ms","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-03T17:28:26.469732Z","caller":"traceutil/trace.go:171","msg":"trace[1359628194] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:675; }","duration":"234.561314ms","start":"2026-02-03T17:28:26.235148Z","end":"2026-02-03T17:28:26.469709Z","steps":["trace[1359628194] 'range keys from in-memory index tree' (duration: 234.273437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:26.469869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:25.954562Z","time spent":"515.126713ms","remote":"127.0.0.1:56750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:28:26.9568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.331318ms","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-03T17:28:26.95685Z","caller":"traceutil/trace.go:171","msg":"trace[221243307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:677; }","duration":"125.404619ms","start":"2026-02-03T17:28:26.831435Z","end":"2026-02-03T17:28:26.956839Z","steps":["trace[221243307] 'range keys from in-memory index tree' (duration: 125.210265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:26.95702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.407631ms","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-03T17:28:26.957039Z","caller":"traceutil/trace.go:171","msg":"trace[1613255518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:677; }","duration":"142.448722ms","start":"2026-02-03T17:28:26.814585Z","end":"2026-02-03T17:28:26.957034Z","steps":["trace[1613255518] 'range keys from in-memory index tree' (duration: 142.34478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:26.957125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.461179ms","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-03T17:28:26.957141Z","caller":"traceutil/trace.go:171","msg":"trace[360675215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:677; }","duration":"127.50424ms","start":"2026-02-03T17:28:26.829633Z","end":"2026-02-03T17:28:26.957137Z","steps":["trace[360675215] 'range keys from in-memory index tree' (duration: 127.414768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:26.957227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.931395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T17:28:26.957244Z","caller":"traceutil/trace.go:171","msg":"trace[680115775] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:677; }","duration":"126.971617ms","start":"2026-02-03T17:28:26.830268Z","end":"2026-02-03T17:28:26.95724Z","steps":["trace[680115775] 'range keys from in-memory index tree' (duration: 126.868274ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:26.957653Z","caller":"traceutil/trace.go:171","msg":"trace[531352744] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"109.408541ms","start":"2026-02-03T17:28:26.848237Z","end":"2026-02-03T17:28:26.957645Z","steps":["trace[531352744] 'process raft request' (duration: 109.34036ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:27.256119Z","caller":"traceutil/trace.go:171","msg":"trace[2063081547] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"291.307329ms","start":"2026-02-03T17:28:26.964797Z","end":"2026-02-03T17:28:27.256104Z","steps":["trace[2063081547] 'process raft request' (duration: 291.288038ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:27.256391Z","caller":"traceutil/trace.go:171","msg":"trace[1203965076] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"295.0237ms","start":"2026-02-03T17:28:26.961361Z","end":"2026-02-03T17:28:27.256385Z","steps":["trace[1203965076] 'process raft request' (duration: 213.810011ms)","trace[1203965076] 'compare' (duration: 80.763347ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:28:27.256448Z","caller":"traceutil/trace.go:171","msg":"trace[801967554] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"294.088476ms","start":"2026-02-03T17:28:26.962356Z","end":"2026-02-03T17:28:27.256445Z","steps":["trace[801967554] 'process raft request' (duration: 293.689816ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:27.256502Z","caller":"traceutil/trace.go:171","msg":"trace[1729042003] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"292.653292ms","start":"2026-02-03T17:28:26.963845Z","end":"2026-02-03T17:28:27.256498Z","steps":["trace[1729042003] 'process raft request' (duration: 292.221082ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:28.394486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.957349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:28.394561Z","caller":"traceutil/trace.go:171","msg":"trace[1297938662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"437.078632ms","start":"2026-02-03T17:28:27.957463Z","end":"2026-02-03T17:28:28.394541Z","steps":["trace[1297938662] 'range keys from in-memory index tree' (duration: 436.895607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:28.394626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:27.957445Z","time spent":"437.171983ms","remote":"127.0.0.1:56750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T17:28:28.882065Z","caller":"traceutil/trace.go:171","msg":"trace[847699294] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"369.316969ms","start":"2026-02-03T17:28:28.512719Z","end":"2026-02-03T17:28:28.882036Z","steps":["trace[847699294] 'process raft request' (duration: 369.191936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:28.882425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:28.512704Z","time spent":"369.648057ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:28:31.028465Z","caller":"traceutil/trace.go:171","msg":"trace[1523862332] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"134.97771ms","start":"2026-02-03T17:28:30.893472Z","end":"2026-02-03T17:28:31.028449Z","steps":["trace[1523862332] 'process raft request' (duration: 134.674183ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:37.44604Z","caller":"traceutil/trace.go:171","msg":"trace[1405884886] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"118.112242ms","start":"2026-02-03T17:28:37.327904Z","end":"2026-02-03T17:28:37.446016Z","steps":["trace[1405884886] 'process raft request' (duration: 87.974402ms)","trace[1405884886] 'compare' (duration: 30.047718ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:28:45.526588Z","caller":"traceutil/trace.go:171","msg":"trace[20700459] linearizableReadLoop","detail":"{readStateIndex:760; appliedIndex:759; }","duration":"179.937481ms","start":"2026-02-03T17:28:45.346636Z","end":"2026-02-03T17:28:45.526573Z","steps":["trace[20700459] 'read index received' (duration: 179.816847ms)","trace[20700459] 'applied index is now lower than readState.Index' (duration: 120.104µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:45.526906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.254338ms","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-03T17:28:45.526928Z","caller":"traceutil/trace.go:171","msg":"trace[1435183448] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:735; }","duration":"180.32649ms","start":"2026-02-03T17:28:45.346595Z","end":"2026-02-03T17:28:45.526922Z","steps":["trace[1435183448] 'agreement among raft nodes before linearized reading' (duration: 180.210088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:45.526966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.470403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T17:28:45.527025Z","caller":"traceutil/trace.go:171","msg":"trace[1157806889] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"361.600992ms","start":"2026-02-03T17:28:45.165417Z","end":"2026-02-03T17:28:45.527018Z","steps":["trace[1157806889] 'process raft request' (duration: 361.073528ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:45.527032Z","caller":"traceutil/trace.go:171","msg":"trace[748904828] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:735; }","duration":"168.587346ms","start":"2026-02-03T17:28:45.358433Z","end":"2026-02-03T17:28:45.52702Z","steps":["trace[748904828] 'agreement among raft nodes before linearized reading' (duration: 168.34663ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:45.527084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:45.165408Z","time spent":"361.634053ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:28:45.527267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.928116ms","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-03T17:28:45.527282Z","caller":"traceutil/trace.go:171","msg":"trace[332247134] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"126.977418ms","start":"2026-02-03T17:28:45.4003Z","end":"2026-02-03T17:28:45.527277Z","steps":["trace[332247134] 'agreement among raft nodes before linearized reading' (duration: 126.934737ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:47.768924Z","caller":"traceutil/trace.go:171","msg":"trace[2033587096] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"201.704437ms","start":"2026-02-03T17:28:47.567206Z","end":"2026-02-03T17:28:47.76891Z","steps":["trace[2033587096] 'read index received' (duration: 201.584884ms)","trace[2033587096] 'applied index is now lower than readState.Index' (duration: 119.033µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:28:47.769106Z","caller":"traceutil/trace.go:171","msg":"trace[2005753056] transaction","detail":"{read_only:false; response_revision:741; number_of_response:1; }","duration":"234.506613ms","start":"2026-02-03T17:28:47.534595Z","end":"2026-02-03T17:28:47.769101Z","steps":["trace[2005753056] 'process raft request' (duration: 234.234016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:47.769252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.038986ms","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-03T17:28:47.769268Z","caller":"traceutil/trace.go:171","msg":"trace[1955012906] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:741; }","duration":"202.094457ms","start":"2026-02-03T17:28:47.567168Z","end":"2026-02-03T17:28:47.769263Z","steps":["trace[1955012906] 'agreement among raft nodes before linearized reading' (duration: 202.021365ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:47.769349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.713446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T17:28:47.76936Z","caller":"traceutil/trace.go:171","msg":"trace[1543381540] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:741; }","duration":"177.766867ms","start":"2026-02-03T17:28:47.59159Z","end":"2026-02-03T17:28:47.769357Z","steps":["trace[1543381540] 'agreement among raft nodes before linearized reading' (duration: 177.730456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:47.769466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.350578ms","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-03T17:28:47.769478Z","caller":"traceutil/trace.go:171","msg":"trace[22169976] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:741; }","duration":"177.372778ms","start":"2026-02-03T17:28:47.592102Z","end":"2026-02-03T17:28:47.769475Z","steps":["trace[22169976] 'agreement among raft nodes before linearized reading' (duration: 177.330857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:47.769545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.856949ms","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-03T17:28:47.769556Z","caller":"traceutil/trace.go:171","msg":"trace[1132930206] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"177.87837ms","start":"2026-02-03T17:28:47.591675Z","end":"2026-02-03T17:28:47.769553Z","steps":["trace[1132930206] 'agreement among raft nodes before linearized reading' (duration: 177.847789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:48.021058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.271143ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:28:48.021186Z","caller":"traceutil/trace.go:171","msg":"trace[1471051108] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"245.212182ms","start":"2026-02-03T17:28:47.775964Z","end":"2026-02-03T17:28:48.021176Z","steps":["trace[1471051108] 'process raft request' (duration: 245.16046ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:48.02125Z","caller":"traceutil/trace.go:171","msg":"trace[162136338] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"244.894823ms","start":"2026-02-03T17:28:47.776321Z","end":"2026-02-03T17:28:48.021216Z","steps":["trace[162136338] 'process raft request' (duration: 244.832622ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:48.02137Z","caller":"traceutil/trace.go:171","msg":"trace[303574322] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"246.724288ms","start":"2026-02-03T17:28:47.77464Z","end":"2026-02-03T17:28:48.021365Z","steps":["trace[303574322] 'process raft request' (duration: 116.089827ms)","trace[303574322] 'compare' (duration: 130.190591ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:50.016099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.010858ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T17:28:50.016198Z","caller":"traceutil/trace.go:171","msg":"trace[1033924067] linearizableReadLoop","detail":"{readStateIndex:771; appliedIndex:770; }","duration":"751.681307ms","start":"2026-02-03T17:28:49.264498Z","end":"2026-02-03T17:28:50.016179Z","steps":["trace[1033924067] 'read index received' (duration: 34.521µs)","trace[1033924067] 'applied index is now lower than readState.Index' (duration: 751.645386ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:50.016496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.987214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:50.016529Z","caller":"traceutil/trace.go:171","msg":"trace[201985849] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:745; }","duration":"752.055276ms","start":"2026-02-03T17:28:49.264461Z","end":"2026-02-03T17:28:50.016517Z","steps":["trace[201985849] 'agreement among raft nodes before linearized reading' (duration: 751.988564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:50.016561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:49.264448Z","time spent":"752.104666ms","remote":"127.0.0.1:57148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-02-03T17:28:50.016623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.814075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T17:28:50.016672Z","caller":"traceutil/trace.go:171","msg":"trace[1952062420] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:745; }","duration":"539.931477ms","start":"2026-02-03T17:28:49.476726Z","end":"2026-02-03T17:28:50.016658Z","steps":["trace[1952062420] 'agreement among raft nodes before linearized reading' (duration: 539.840995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:50.016695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:49.476708Z","time spent":"539.981469ms","remote":"127.0.0.1:57300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-03T17:28:50.016872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.985773ms","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-03T17:28:50.016886Z","caller":"traceutil/trace.go:171","msg":"trace[716117835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:745; }","duration":"172.014963ms","start":"2026-02-03T17:28:49.844867Z","end":"2026-02-03T17:28:50.016882Z","steps":["trace[716117835] 'agreement among raft nodes before linearized reading' (duration: 171.924161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:50.016938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.784784ms","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-03T17:28:50.016972Z","caller":"traceutil/trace.go:171","msg":"trace[1915712941] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:745; }","duration":"243.842846ms","start":"2026-02-03T17:28:49.773118Z","end":"2026-02-03T17:28:50.016961Z","steps":["trace[1915712941] 'agreement among raft nodes before linearized reading' (duration: 243.709413ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:50.245205Z","caller":"traceutil/trace.go:171","msg":"trace[1750428088] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"220.754569ms","start":"2026-02-03T17:28:50.024433Z","end":"2026-02-03T17:28:50.245188Z","steps":["trace[1750428088] 'read index received' (duration: 51.158091ms)","trace[1750428088] 'applied index is now lower than readState.Index' (duration: 169.595718ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:28:50.245946Z","caller":"traceutil/trace.go:171","msg":"trace[1703888262] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"224.383483ms","start":"2026-02-03T17:28:50.02155Z","end":"2026-02-03T17:28:50.245934Z","steps":["trace[1703888262] 'process raft request' (duration: 149.995732ms)","trace[1703888262] 'compare' (duration: 73.314596ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:50.246268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.822813ms","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-03T17:28:50.246305Z","caller":"traceutil/trace.go:171","msg":"trace[584684193] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:747; }","duration":"221.909035ms","start":"2026-02-03T17:28:50.024386Z","end":"2026-02-03T17:28:50.246295Z","steps":["trace[584684193] 'agreement among raft nodes before linearized reading' (duration: 221.792352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:50.24661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.980997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T17:28:50.246645Z","caller":"traceutil/trace.go:171","msg":"trace[495030934] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:747; }","duration":"222.035118ms","start":"2026-02-03T17:28:50.024601Z","end":"2026-02-03T17:28:50.246636Z","steps":["trace[495030934] 'agreement among raft nodes before linearized reading' (duration: 221.954936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:50.247181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.890276ms","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-03T17:28:50.247218Z","caller":"traceutil/trace.go:171","msg":"trace[1145109593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"221.953587ms","start":"2026-02-03T17:28:50.025255Z","end":"2026-02-03T17:28:50.247208Z","steps":["trace[1145109593] 'agreement among raft nodes before linearized reading' (duration: 221.830454ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:54.122002Z","caller":"traceutil/trace.go:171","msg":"trace[918597543] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:781; }","duration":"211.982374ms","start":"2026-02-03T17:28:53.909999Z","end":"2026-02-03T17:28:54.121981Z","steps":["trace[918597543] 'read index received' (duration: 211.848631ms)","trace[918597543] 'applied index is now lower than readState.Index' (duration: 132.533µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:54.121999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:53.746381Z","time spent":"375.614503ms","remote":"127.0.0.1:56790","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-03T17:28:54.122128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.112437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:54.122158Z","caller":"traceutil/trace.go:171","msg":"trace[702272867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"212.180839ms","start":"2026-02-03T17:28:53.909968Z","end":"2026-02-03T17:28:54.122149Z","steps":["trace[702272867] 'agreement among raft nodes before linearized reading' (duration: 212.114967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:54.12237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.883588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:54.122397Z","caller":"traceutil/trace.go:171","msg":"trace[1058838458] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"164.959219ms","start":"2026-02-03T17:28:53.95743Z","end":"2026-02-03T17:28:54.122389Z","steps":["trace[1058838458] 'agreement among raft nodes before linearized reading' (duration: 164.915798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:54.318993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.610327ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:28:54.320004Z","caller":"traceutil/trace.go:171","msg":"trace[453068780] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"192.844248ms","start":"2026-02-03T17:28:54.127139Z","end":"2026-02-03T17:28:54.319983Z","steps":["trace[453068780] 'process raft request' (duration: 65.098044ms)","trace[453068780] 'compare' (duration: 126.397813ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:28:54.321125Z","caller":"traceutil/trace.go:171","msg":"trace[540321567] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"192.8579ms","start":"2026-02-03T17:28:54.128223Z","end":"2026-02-03T17:28:54.321081Z","steps":["trace[540321567] 'process raft request' (duration: 191.028887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:54.986677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:54.572613Z","time spent":"414.047097ms","remote":"127.0.0.1:56428","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-03T17:28:54.987262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.042472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:28:54.987464Z","caller":"traceutil/trace.go:171","msg":"trace[275789107] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"658.516618ms","start":"2026-02-03T17:28:54.328932Z","end":"2026-02-03T17:28:54.987448Z","steps":["trace[275789107] 'process raft request' (duration: 478.222051ms)","trace[275789107] 'compare' (duration: 179.421786ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:54.987964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:54.32892Z","time spent":"659.01063ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:28:55.081772Z","caller":"traceutil/trace.go:171","msg":"trace[578439763] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:784; }","duration":"745.734828ms","start":"2026-02-03T17:28:54.336013Z","end":"2026-02-03T17:28:55.081748Z","steps":["trace[578439763] 'read index received' (duration: 471.167807ms)","trace[578439763] 'applied index is now lower than readState.Index' (duration: 274.565951ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:28:55.082071Z","caller":"traceutil/trace.go:171","msg":"trace[1371166739] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"750.728255ms","start":"2026-02-03T17:28:54.3313Z","end":"2026-02-03T17:28:55.082029Z","steps":["trace[1371166739] 'process raft request' (duration: 750.302834ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:55.082287Z","caller":"traceutil/trace.go:171","msg":"trace[481489380] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"332.433989ms","start":"2026-02-03T17:28:54.749806Z","end":"2026-02-03T17:28:55.08224Z","steps":["trace[481489380] 'process raft request' (duration: 331.899255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:55.082432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:54.331293Z","time spent":"750.880808ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:28:55.082565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:54.749783Z","time spent":"332.601152ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:28:55.083072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.070471ms","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-03T17:28:55.083202Z","caller":"traceutil/trace.go:171","msg":"trace[1423257702] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:760; }","duration":"747.229864ms","start":"2026-02-03T17:28:54.335959Z","end":"2026-02-03T17:28:55.083189Z","steps":["trace[1423257702] 'agreement among raft nodes before linearized reading' (duration: 746.986258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:55.08335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:54.335941Z","time spent":"747.361217ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:28:55.083889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.944246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-03T17:28:55.084053Z","caller":"traceutil/trace.go:171","msg":"trace[2113010388] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:760; }","duration":"747.11699ms","start":"2026-02-03T17:28:54.336922Z","end":"2026-02-03T17:28:55.084039Z","steps":["trace[2113010388] 'agreement among raft nodes before linearized reading' (duration: 746.895745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:55.084172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:54.336915Z","time spent":"747.244333ms","remote":"127.0.0.1:56984","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-03T17:28:55.084467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.421053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:55.084596Z","caller":"traceutil/trace.go:171","msg":"trace[837255622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:760; }","duration":"129.569045ms","start":"2026-02-03T17:28:54.95501Z","end":"2026-02-03T17:28:55.084579Z","steps":["trace[837255622] 'agreement among raft nodes before linearized reading' (duration: 129.415122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:55.084987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.773619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:55.085075Z","caller":"traceutil/trace.go:171","msg":"trace[1813846906] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:760; }","duration":"543.895762ms","start":"2026-02-03T17:28:54.541143Z","end":"2026-02-03T17:28:55.085039Z","steps":["trace[1813846906] 'agreement among raft nodes before linearized reading' (duration: 543.762029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:55.085131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:54.541124Z","time spent":"543.994844ms","remote":"127.0.0.1:56970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-03T17:28:55.08527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.346928ms","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-03T17:28:55.085295Z","caller":"traceutil/trace.go:171","msg":"trace[306573258] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:760; }","duration":"494.40659ms","start":"2026-02-03T17:28:54.590883Z","end":"2026-02-03T17:28:55.085289Z","steps":["trace[306573258] 'agreement among raft nodes before linearized reading' (duration: 494.365839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:55.085312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:54.590836Z","time spent":"494.471781ms","remote":"127.0.0.1:56872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-02-03T17:29:13.193206Z","caller":"traceutil/trace.go:171","msg":"trace[189105672] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"112.919559ms","start":"2026-02-03T17:29:13.080265Z","end":"2026-02-03T17:29:13.193184Z","steps":["trace[189105672] 'process raft request' (duration: 112.791606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:14.189517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.939358ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:29:14.189604Z","caller":"traceutil/trace.go:171","msg":"trace[2132400274] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:900; }","duration":"986.445771ms","start":"2026-02-03T17:29:13.203142Z","end":"2026-02-03T17:29:14.189588Z","steps":["trace[2132400274] 'read index received' (duration: 110.36384ms)","trace[2132400274] 'applied index is now lower than readState.Index' (duration: 876.080851ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:29:14.189828Z","caller":"traceutil/trace.go:171","msg":"trace[1645269366] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"988.621163ms","start":"2026-02-03T17:29:13.201195Z","end":"2026-02-03T17:29:14.189816Z","steps":["trace[1645269366] 'process raft request' (duration: 112.316036ms)","trace[1645269366] 'compare' (duration: 875.427956ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:14.189888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:13.201178Z","time spent":"988.678163ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:29:14.190105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"986.958013ms","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":"info","ts":"2026-02-03T17:29:14.190132Z","caller":"traceutil/trace.go:171","msg":"trace[2094873309] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:869; }","duration":"987.016845ms","start":"2026-02-03T17:29:13.203107Z","end":"2026-02-03T17:29:14.190123Z","steps":["trace[2094873309] 'agreement among raft nodes before linearized reading' (duration: 986.917272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:14.190158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:13.203088Z","time spent":"987.063715ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-03T17:29:14.190397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"979.106931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-03T17:29:14.190448Z","caller":"traceutil/trace.go:171","msg":"trace[1093795607] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:869; }","duration":"979.167532ms","start":"2026-02-03T17:29:13.211252Z","end":"2026-02-03T17:29:14.19042Z","steps":["trace[1093795607] 'agreement among raft nodes before linearized reading' (duration: 979.029199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:14.19048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:13.211239Z","time spent":"979.233494ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-03T17:29:14.190756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.930116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:29:14.19083Z","caller":"traceutil/trace.go:171","msg":"trace[932587142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:869; }","duration":"237.025928ms","start":"2026-02-03T17:29:13.953794Z","end":"2026-02-03T17:29:14.19082Z","steps":["trace[932587142] 'agreement among raft nodes before linearized reading' (duration: 236.855404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:14.191073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.477296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.219\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-03T17:29:14.191107Z","caller":"traceutil/trace.go:171","msg":"trace[1696830107] range","detail":"{range_begin:/registry/masterleases/199.19.213.219; range_end:; response_count:1; response_revision:869; }","duration":"444.535808ms","start":"2026-02-03T17:29:13.746564Z","end":"2026-02-03T17:29:14.191099Z","steps":["trace[1696830107] 'agreement among raft nodes before linearized reading' (duration: 444.430366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:14.191132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:13.746535Z","time spent":"444.591279ms","remote":"127.0.0.1:56790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.219\" "} {"level":"warn","ts":"2026-02-03T17:29:14.191271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.124477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T17:29:14.191298Z","caller":"traceutil/trace.go:171","msg":"trace[1817221043] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:869; }","duration":"822.179368ms","start":"2026-02-03T17:29:13.369111Z","end":"2026-02-03T17:29:14.19129Z","steps":["trace[1817221043] 'agreement among raft nodes before linearized reading' (duration: 822.131477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:14.191326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:13.369094Z","time spent":"822.224769ms","remote":"127.0.0.1:56798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":11,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-02-03T17:29:14.471724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.744363ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-03T17:29:14.472179Z","caller":"traceutil/trace.go:171","msg":"trace[878906964] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"273.765742ms","start":"2026-02-03T17:29:14.198402Z","end":"2026-02-03T17:29:14.472168Z","steps":["trace[878906964] 'process raft request' (duration: 273.395134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:19.718311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.408817ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T17:29:19.718382Z","caller":"traceutil/trace.go:171","msg":"trace[954076749] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:918; }","duration":"508.515188ms","start":"2026-02-03T17:29:19.209856Z","end":"2026-02-03T17:29:19.718371Z","steps":["trace[954076749] 'read index received' (duration: 163.999938ms)","trace[954076749] 'applied index is now lower than readState.Index' (duration: 344.51438ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:19.718481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.61862ms","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-03T17:29:19.718495Z","caller":"traceutil/trace.go:171","msg":"trace[1809231621] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:885; }","duration":"508.6606ms","start":"2026-02-03T17:29:19.209829Z","end":"2026-02-03T17:29:19.718489Z","steps":["trace[1809231621] 'agreement among raft nodes before linearized reading' (duration: 508.568278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:19.71852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:19.209817Z","time spent":"508.691611ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-03T17:29:19.82638Z","caller":"traceutil/trace.go:171","msg":"trace[1151956706] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"104.274947ms","start":"2026-02-03T17:29:19.722088Z","end":"2026-02-03T17:29:19.826363Z","steps":["trace[1151956706] 'process raft request' (duration: 104.173405ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:24.887476Z","caller":"traceutil/trace.go:171","msg":"trace[251543240] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"194.128518ms","start":"2026-02-03T17:29:24.69333Z","end":"2026-02-03T17:29:24.887458Z","steps":["trace[251543240] 'process raft request' (duration: 193.681768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:25.539068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.071876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:29:25.539139Z","caller":"traceutil/trace.go:171","msg":"trace[2117273180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"584.187449ms","start":"2026-02-03T17:29:24.954932Z","end":"2026-02-03T17:29:25.53912Z","steps":["trace[2117273180] 'range keys from in-memory index tree' (duration: 584.013525ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:25.539173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:24.954919Z","time spent":"584.24673ms","remote":"127.0.0.1:56750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-03T17:29:25.865169Z","caller":"traceutil/trace.go:171","msg":"trace[804883775] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"250.04133ms","start":"2026-02-03T17:29:25.615108Z","end":"2026-02-03T17:29:25.865149Z","steps":["trace[804883775] 'process raft request' (duration: 249.720843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:29.950273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.203542ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T17:29:29.950449Z","caller":"traceutil/trace.go:171","msg":"trace[600148977] linearizableReadLoop","detail":"{readStateIndex:954; appliedIndex:953; }","duration":"204.916249ms","start":"2026-02-03T17:29:29.745516Z","end":"2026-02-03T17:29:29.950432Z","steps":["trace[600148977] 'read index received' (duration: 3.508031ms)","trace[600148977] 'applied index is now lower than readState.Index' (duration: 201.406788ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:29.950588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.056712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:29:29.950607Z","caller":"traceutil/trace.go:171","msg":"trace[718971117] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:919; }","duration":"205.146335ms","start":"2026-02-03T17:29:29.745455Z","end":"2026-02-03T17:29:29.950601Z","steps":["trace[718971117] 'agreement among raft nodes before linearized reading' (duration: 205.068564ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:30.291153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.346147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:29:30.291223Z","caller":"traceutil/trace.go:171","msg":"trace[133375319] transaction","detail":"{read_only:false; response_revision:920; number_of_response:1; }","duration":"338.028199ms","start":"2026-02-03T17:29:29.953183Z","end":"2026-02-03T17:29:30.291212Z","steps":["trace[133375319] 'process raft request' (duration: 139.575709ms)","trace[133375319] 'compare' (duration: 198.227465ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:30.291255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:29.953171Z","time spent":"338.07044ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:29:30.318604Z","caller":"traceutil/trace.go:171","msg":"trace[111558058] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:955; }","duration":"357.678596ms","start":"2026-02-03T17:29:29.960903Z","end":"2026-02-03T17:29:30.318582Z","steps":["trace[111558058] 'read index received' (duration: 131.8687ms)","trace[111558058] 'applied index is now lower than readState.Index' (duration: 225.808966ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:29:30.318953Z","caller":"traceutil/trace.go:171","msg":"trace[537812055] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"358.331522ms","start":"2026-02-03T17:29:29.96061Z","end":"2026-02-03T17:29:30.318942Z","steps":["trace[537812055] 'process raft request' (duration: 357.865361ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:30.31905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:29.960593Z","time spent":"358.391173ms","remote":"127.0.0.1:56920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10605,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:29:30.319252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.344542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:29:30.319287Z","caller":"traceutil/trace.go:171","msg":"trace[309954567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:921; }","duration":"358.393142ms","start":"2026-02-03T17:29:29.960884Z","end":"2026-02-03T17:29:30.319277Z","steps":["trace[309954567] 'agreement among raft nodes before linearized reading' (duration: 358.341101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:30.319314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:29.960876Z","time spent":"358.432313ms","remote":"127.0.0.1:56750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-03T17:29:30.658192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.142513ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:29:30.65826Z","caller":"traceutil/trace.go:171","msg":"trace[1814841808] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"327.78692ms","start":"2026-02-03T17:29:30.330461Z","end":"2026-02-03T17:29:30.658248Z","steps":["trace[1814841808] 'process raft request' (duration: 80.537515ms)","trace[1814841808] 'compare' (duration: 246.790535ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:30.658312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:30.330445Z","time spent":"327.848912ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:29:30.676057Z","caller":"traceutil/trace.go:171","msg":"trace[1725705906] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"345.027701ms","start":"2026-02-03T17:29:30.331017Z","end":"2026-02-03T17:29:30.676044Z","steps":["trace[1725705906] 'process raft request' (duration: 344.751665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:30.676978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:30.33101Z","time spent":"345.450302ms","remote":"127.0.0.1:56894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:29:30.678004Z","caller":"traceutil/trace.go:171","msg":"trace[1883632262] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"344.829967ms","start":"2026-02-03T17:29:30.333166Z","end":"2026-02-03T17:29:30.677996Z","steps":["trace[1883632262] 'process raft request' (duration: 342.665246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:30.678122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:30.333161Z","time spent":"344.941619ms","remote":"127.0.0.1:56990","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:29:30.678397Z","caller":"traceutil/trace.go:171","msg":"trace[2100558043] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"342.653855ms","start":"2026-02-03T17:29:30.335736Z","end":"2026-02-03T17:29:30.67839Z","steps":["trace[2100558043] 'process raft request' (duration: 340.118686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:30.678466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:30.33573Z","time spent":"342.715617ms","remote":"127.0.0.1:57224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8757,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:29:30.678637Z","caller":"traceutil/trace.go:171","msg":"trace[324013546] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"340.988987ms","start":"2026-02-03T17:29:30.337643Z","end":"2026-02-03T17:29:30.678632Z","steps":["trace[324013546] 'process raft request' (duration: 338.236083ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:30.679043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:30.337637Z","time spent":"341.387646ms","remote":"127.0.0.1:56984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:29:30.808123Z","caller":"traceutil/trace.go:171","msg":"trace[530297379] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"112.9692ms","start":"2026-02-03T17:29:30.695136Z","end":"2026-02-03T17:29:30.808105Z","steps":["trace[530297379] 'process raft request' (duration: 105.779612ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:30.94334Z","caller":"traceutil/trace.go:171","msg":"trace[112144101] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"124.130139ms","start":"2026-02-03T17:29:30.819188Z","end":"2026-02-03T17:29:30.943318Z","steps":["trace[112144101] 'process raft request' (duration: 104.690427ms)","trace[112144101] 'compare' (duration: 19.094164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:39.89688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.824477ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-03T17:29:39.897016Z","caller":"traceutil/trace.go:171","msg":"trace[553919919] linearizableReadLoop","detail":"{readStateIndex:1000; appliedIndex:999; }","duration":"339.872371ms","start":"2026-02-03T17:29:39.557107Z","end":"2026-02-03T17:29:39.89698Z","steps":["trace[553919919] 'read index received' (duration: 107.8772ms)","trace[553919919] 'applied index is now lower than readState.Index' (duration: 231.993831ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:39.897121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.999484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T17:29:39.897151Z","caller":"traceutil/trace.go:171","msg":"trace[1676568712] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:962; }","duration":"340.069295ms","start":"2026-02-03T17:29:39.55707Z","end":"2026-02-03T17:29:39.897139Z","steps":["trace[1676568712] 'agreement among raft nodes before linearized reading' (duration: 339.975573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:39.897208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:39.557052Z","time spent":"340.146758ms","remote":"127.0.0.1:56990","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-02-03T17:29:42.504272Z","caller":"traceutil/trace.go:171","msg":"trace[187203383] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1039; }","duration":"135.571166ms","start":"2026-02-03T17:29:42.368673Z","end":"2026-02-03T17:29:42.504245Z","steps":["trace[187203383] 'read index received' (duration: 135.402313ms)","trace[187203383] 'applied index is now lower than readState.Index' (duration: 167.453µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:42.504477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.794711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-03T17:29:42.504511Z","caller":"traceutil/trace.go:171","msg":"trace[419346507] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1002; }","duration":"135.881213ms","start":"2026-02-03T17:29:42.368617Z","end":"2026-02-03T17:29:42.504499Z","steps":["trace[419346507] 'agreement among raft nodes before linearized reading' (duration: 135.72044ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:42.50461Z","caller":"traceutil/trace.go:171","msg":"trace[666064875] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"156.514713ms","start":"2026-02-03T17:29:42.348069Z","end":"2026-02-03T17:29:42.504584Z","steps":["trace[666064875] 'process raft request' (duration: 156.040972ms)"],"step_count":1}