{"level":"warn","ts":"2026-03-19T17:46:06.793918Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T17:46:06.793993Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.171: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.171:2380","--initial-cluster=instance=https://199.19.213.171:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.171:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.171: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-03-19T17:46:06.794052Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-19T17:46:06.794064Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.171:2380"]} {"level":"info","ts":"2026-03-19T17:46:06.794082Z","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-03-19T17:46:06.794724Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.171:2379"]} {"level":"info","ts":"2026-03-19T17:46:06.794791Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.171:2380"],"listen-peer-urls":["https://199.19.213.171:2380"],"advertise-client-urls":["https://199.19.213.171:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.171:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.171: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-03-19T17:46:06.829879Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"34.914675ms"} {"level":"info","ts":"2026-03-19T17:46:06.866052Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"837e89131cc6bd81","cluster-id":"8af92e75ab8f3e69"} {"level":"info","ts":"2026-03-19T17:46:06.866165Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 switched to configuration voters=()"} {"level":"info","ts":"2026-03-19T17:46:06.866196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became follower at term 0"} {"level":"info","ts":"2026-03-19T17:46:06.866212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 837e89131cc6bd81 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-19T17:46:06.866246Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became follower at term 1"} {"level":"info","ts":"2026-03-19T17:46:06.866315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 switched to configuration voters=(9475161381214272897)"} {"level":"warn","ts":"2026-03-19T17:46:06.885188Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-19T17:46:06.888755Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-19T17:46:06.890179Z","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-03-19T17:46:06.894685Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"837e89131cc6bd81","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-19T17:46:06.895059Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"837e89131cc6bd81","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-19T17:46:06.895117Z","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-03-19T17:46:06.895216Z","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-03-19T17:46:06.895227Z","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-03-19T17:46:06.898488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 switched to configuration voters=(9475161381214272897)"} {"level":"info","ts":"2026-03-19T17:46:06.898694Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8af92e75ab8f3e69","local-member-id":"837e89131cc6bd81","added-peer-id":"837e89131cc6bd81","added-peer-peer-urls":["https://199.19.213.171:2380"]} {"level":"info","ts":"2026-03-19T17:46:06.899432Z","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-03-19T17:46:06.899554Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.171:2380"} {"level":"info","ts":"2026-03-19T17:46:06.899588Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.171:2380"} {"level":"info","ts":"2026-03-19T17:46:06.899849Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"837e89131cc6bd81","initial-advertise-peer-urls":["https://199.19.213.171:2380"],"listen-peer-urls":["https://199.19.213.171:2380"],"advertise-client-urls":["https://199.19.213.171:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.171:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-19T17:46:06.899898Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-19T17:46:07.167795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 is starting a new election at term 1"} {"level":"info","ts":"2026-03-19T17:46:07.167853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-19T17:46:07.167882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 received MsgPreVoteResp from 837e89131cc6bd81 at term 1"} {"level":"info","ts":"2026-03-19T17:46:07.167897Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became candidate at term 2"} {"level":"info","ts":"2026-03-19T17:46:07.167904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 received MsgVoteResp from 837e89131cc6bd81 at term 2"} {"level":"info","ts":"2026-03-19T17:46:07.167915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"837e89131cc6bd81 became leader at term 2"} {"level":"info","ts":"2026-03-19T17:46:07.167928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 837e89131cc6bd81 elected leader 837e89131cc6bd81 at term 2"} {"level":"info","ts":"2026-03-19T17:46:07.244608Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:46:07.301085Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"837e89131cc6bd81","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.171:2379]}","request-path":"/0/members/837e89131cc6bd81/attributes","cluster-id":"8af92e75ab8f3e69","publish-timeout":"7s"} {"level":"info","ts":"2026-03-19T17:46:07.301106Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T17:46:07.301368Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-19T17:46:07.301914Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-19T17:46:07.301955Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-19T17:46:07.303731Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.171:2379"} {"level":"info","ts":"2026-03-19T17:46:07.30547Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-19T17:46:07.310931Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8af92e75ab8f3e69","local-member-id":"837e89131cc6bd81","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:46:07.311034Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:46:07.311074Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-19T17:46:08.916542Z","caller":"traceutil/trace.go:171","msg":"trace[194188722] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"147.349997ms","start":"2026-03-19T17:46:08.769177Z","end":"2026-03-19T17:46:08.916527Z","steps":["trace[194188722] 'process raft request' (duration: 147.322096ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:08.916613Z","caller":"traceutil/trace.go:171","msg":"trace[303446425] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"185.58659ms","start":"2026-03-19T17:46:08.730997Z","end":"2026-03-19T17:46:08.916584Z","steps":["trace[303446425] 'process raft request' (duration: 185.444713ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:08.916761Z","caller":"traceutil/trace.go:171","msg":"trace[2033829654] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"182.238959ms","start":"2026-03-19T17:46:08.734515Z","end":"2026-03-19T17:46:08.916754Z","steps":["trace[2033829654] 'process raft request' (duration: 181.945674ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:08.91676Z","caller":"traceutil/trace.go:171","msg":"trace[1399826318] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"186.062395ms","start":"2026-03-19T17:46:08.730652Z","end":"2026-03-19T17:46:08.916715Z","steps":["trace[1399826318] 'process raft request' (duration: 185.730138ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:08.91684Z","caller":"traceutil/trace.go:171","msg":"trace[588666376] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"186.051944ms","start":"2026-03-19T17:46:08.730737Z","end":"2026-03-19T17:46:08.916789Z","steps":["trace[588666376] 'process raft request' (duration: 185.677664ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:08.916967Z","caller":"traceutil/trace.go:171","msg":"trace[1153598821] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"186.394522ms","start":"2026-03-19T17:46:08.730556Z","end":"2026-03-19T17:46:08.91695Z","steps":["trace[1153598821] 'process raft request' (duration: 129.613461ms)","trace[1153598821] 'compare' (duration: 56.123677ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:08.917033Z","caller":"traceutil/trace.go:171","msg":"trace[1929022738] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"186.301227ms","start":"2026-03-19T17:46:08.730724Z","end":"2026-03-19T17:46:08.917025Z","steps":["trace[1929022738] 'process raft request' (duration: 185.704056ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:08.917089Z","caller":"traceutil/trace.go:171","msg":"trace[1688339258] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"160.13349ms","start":"2026-03-19T17:46:08.756951Z","end":"2026-03-19T17:46:08.917085Z","steps":["trace[1688339258] 'process raft request' (duration: 159.522288ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:08.917147Z","caller":"traceutil/trace.go:171","msg":"trace[262012256] linearizableReadLoop","detail":"{readStateIndex:20; appliedIndex:16; }","duration":"186.093336ms","start":"2026-03-19T17:46:08.730748Z","end":"2026-03-19T17:46:08.916841Z","steps":["trace[262012256] 'read index received' (duration: 35.42112ms)","trace[262012256] 'applied index is now lower than readState.Index' (duration: 150.670136ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:08.917249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.485526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-19T17:46:08.91729Z","caller":"traceutil/trace.go:171","msg":"trace[183272294] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:21; }","duration":"186.55323ms","start":"2026-03-19T17:46:08.730728Z","end":"2026-03-19T17:46:08.917281Z","steps":["trace[183272294] 'agreement among raft nodes before linearized reading' (duration: 186.466795ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:08.917429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.861181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T17:46:08.917452Z","caller":"traceutil/trace.go:171","msg":"trace[1825220231] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:21; }","duration":"182.909243ms","start":"2026-03-19T17:46:08.734535Z","end":"2026-03-19T17:46:08.917444Z","steps":["trace[1825220231] 'agreement among raft nodes before linearized reading' (duration: 182.871341ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:09.124378Z","caller":"traceutil/trace.go:171","msg":"trace[1358429971] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"152.145753ms","start":"2026-03-19T17:46:08.972193Z","end":"2026-03-19T17:46:09.124339Z","steps":["trace[1358429971] 'process raft request' (duration: 118.149146ms)","trace[1358429971] 'compare' (duration: 33.558415ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:09.124695Z","caller":"traceutil/trace.go:171","msg":"trace[1844758048] linearizableReadLoop","detail":"{readStateIndex:36; appliedIndex:34; }","duration":"115.762986ms","start":"2026-03-19T17:46:09.008918Z","end":"2026-03-19T17:46:09.124681Z","steps":["trace[1844758048] 'read index received' (duration: 81.537507ms)","trace[1844758048] 'applied index is now lower than readState.Index' (duration: 34.223399ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:09.12471Z","caller":"traceutil/trace.go:171","msg":"trace[499973145] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"151.137582ms","start":"2026-03-19T17:46:08.973518Z","end":"2026-03-19T17:46:09.124655Z","steps":["trace[499973145] 'process raft request' (duration: 150.925332ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:09.12493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.863555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-19T17:46:09.12497Z","caller":"traceutil/trace.go:171","msg":"trace[2098689340] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:33; }","duration":"119.962009ms","start":"2026-03-19T17:46:09.004998Z","end":"2026-03-19T17:46:09.12496Z","steps":["trace[2098689340] 'agreement among raft nodes before linearized reading' (duration: 119.79437ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:09.125059Z","caller":"traceutil/trace.go:171","msg":"trace[2030628243] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"112.964074ms","start":"2026-03-19T17:46:09.012068Z","end":"2026-03-19T17:46:09.125032Z","steps":["trace[2030628243] 'process raft request' (duration: 112.465659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:09.125718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.499611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-19T17:46:09.125775Z","caller":"traceutil/trace.go:171","msg":"trace[1499707956] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:38; }","duration":"112.575995ms","start":"2026-03-19T17:46:09.013187Z","end":"2026-03-19T17:46:09.125763Z","steps":["trace[1499707956] 'agreement among raft nodes before linearized reading' (duration: 112.454549ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:09.125848Z","caller":"traceutil/trace.go:171","msg":"trace[2005082423] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"113.196106ms","start":"2026-03-19T17:46:09.012585Z","end":"2026-03-19T17:46:09.125781Z","steps":["trace[2005082423] 'process raft request' (duration: 112.923212ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:09.12598Z","caller":"traceutil/trace.go:171","msg":"trace[101279991] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"113.302302ms","start":"2026-03-19T17:46:09.012667Z","end":"2026-03-19T17:46:09.125969Z","steps":["trace[101279991] 'process raft request' (duration: 112.87902ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:09.126178Z","caller":"traceutil/trace.go:171","msg":"trace[1825349671] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"113.790847ms","start":"2026-03-19T17:46:09.012374Z","end":"2026-03-19T17:46:09.126165Z","steps":["trace[1825349671] 'process raft request' (duration: 112.223328ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:09.126283Z","caller":"traceutil/trace.go:171","msg":"trace[1756622147] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"113.767555ms","start":"2026-03-19T17:46:09.012502Z","end":"2026-03-19T17:46:09.126269Z","steps":["trace[1756622147] 'process raft request' (duration: 112.941263ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:09.126347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.409575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-19T17:46:09.126438Z","caller":"traceutil/trace.go:171","msg":"trace[1292197642] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"113.544883ms","start":"2026-03-19T17:46:09.012873Z","end":"2026-03-19T17:46:09.126418Z","steps":["trace[1292197642] 'process raft request' (duration: 112.710361ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:09.126444Z","caller":"traceutil/trace.go:171","msg":"trace[746701235] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:38; }","duration":"111.567793ms","start":"2026-03-19T17:46:09.014832Z","end":"2026-03-19T17:46:09.1264Z","steps":["trace[746701235] 'agreement among raft nodes before linearized reading' (duration: 111.384084ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:09.429943Z","caller":"traceutil/trace.go:171","msg":"trace[2011117982] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"112.568734ms","start":"2026-03-19T17:46:09.317341Z","end":"2026-03-19T17:46:09.429909Z","steps":["trace[2011117982] 'process raft request' (duration: 57.685432ms)","trace[2011117982] 'compare' (duration: 54.709123ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:09.443452Z","caller":"traceutil/trace.go:171","msg":"trace[1869807271] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"120.308886ms","start":"2026-03-19T17:46:09.323115Z","end":"2026-03-19T17:46:09.443424Z","steps":["trace[1869807271] 'process raft request' (duration: 120.20026ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:11.295051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.511922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:11.295254Z","caller":"traceutil/trace.go:171","msg":"trace[688859277] transaction","detail":"{read_only:false; response_revision:170; number_of_response:1; }","duration":"244.205777ms","start":"2026-03-19T17:46:11.051024Z","end":"2026-03-19T17:46:11.29523Z","steps":["trace[688859277] 'process raft request' (duration: 48.064613ms)","trace[688859277] 'compare' (duration: 195.325173ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:11.295269Z","caller":"traceutil/trace.go:171","msg":"trace[912031445] linearizableReadLoop","detail":"{readStateIndex:174; appliedIndex:173; }","duration":"140.76898ms","start":"2026-03-19T17:46:11.154428Z","end":"2026-03-19T17:46:11.295197Z","steps":["trace[912031445] 'read index received' (duration: 106.155µs)","trace[912031445] 'applied index is now lower than readState.Index' (duration: 140.661395ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:11.295521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.579731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-03-19T17:46:11.295541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.111846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-03-19T17:46:11.295586Z","caller":"traceutil/trace.go:171","msg":"trace[322224946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:170; }","duration":"140.648834ms","start":"2026-03-19T17:46:11.154918Z","end":"2026-03-19T17:46:11.295567Z","steps":["trace[322224946] 'agreement among raft nodes before linearized reading' (duration: 140.541449ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:11.295596Z","caller":"traceutil/trace.go:171","msg":"trace[688093469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:170; }","duration":"141.195841ms","start":"2026-03-19T17:46:11.154379Z","end":"2026-03-19T17:46:11.295575Z","steps":["trace[688093469] 'agreement among raft nodes before linearized reading' (duration: 140.926607ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:12.308768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368199433262004,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:46:12.809234Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368199433262004,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:46:13.309569Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368199433262004,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:46:13.670111Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.370938681s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-19T17:46:13.807909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999809254s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-19T17:46:13.808047Z","caller":"traceutil/trace.go:171","msg":"trace[1442416845] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999979002s","start":"2026-03-19T17:46:11.808033Z","end":"2026-03-19T17:46:13.808012Z","steps":["trace[1442416845] 'agreement among raft nodes before linearized reading' (duration: 1.999802974s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:13.808125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:11.807976Z","time spent":"2.0001331s","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/19 17:46:13 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-19T17:46:14.88997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00056776s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-19T17:46:14.890063Z","caller":"traceutil/trace.go:171","msg":"trace[384961712] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000706527s","start":"2026-03-19T17:46:12.889337Z","end":"2026-03-19T17:46:14.890044Z","steps":["trace[384961712] 'agreement among raft nodes before linearized reading' (duration: 2.000582531s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:14.890119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:12.889324Z","time spent":"2.000783031s","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:46:15.04329Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.37301225s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-19T17:46:15.795637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.125081948s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:15.795819Z","caller":"traceutil/trace.go:171","msg":"trace[2092134213] transaction","detail":"{read_only:false; response_revision:171; number_of_response:1; }","duration":"4.496724533s","start":"2026-03-19T17:46:11.299067Z","end":"2026-03-19T17:46:15.795792Z","steps":["trace[2092134213] 'process raft request' (duration: 2.371272707s)","trace[2092134213] 'compare' (duration: 2.123342367s)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:15.795936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:11.299051Z","time spent":"4.496820577s","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:15.79595Z","caller":"traceutil/trace.go:171","msg":"trace[1754506291] transaction","detail":"{read_only:false; response_revision:173; number_of_response:1; }","duration":"4.495303462s","start":"2026-03-19T17:46:11.300629Z","end":"2026-03-19T17:46:15.795933Z","steps":["trace[1754506291] 'process raft request' (duration: 4.495167345s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:15.796026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:11.300613Z","time spent":"4.495378786s","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:15.795895Z","caller":"traceutil/trace.go:171","msg":"trace[271496002] transaction","detail":"{read_only:false; response_revision:172; number_of_response:1; }","duration":"4.495599607s","start":"2026-03-19T17:46:11.300243Z","end":"2026-03-19T17:46:15.795842Z","steps":["trace[271496002] 'process raft request' (duration: 4.495487341s)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:15.796185Z","caller":"traceutil/trace.go:171","msg":"trace[962821532] linearizableReadLoop","detail":"{readStateIndex:178; appliedIndex:174; }","duration":"3.988099329s","start":"2026-03-19T17:46:11.808039Z","end":"2026-03-19T17:46:15.796138Z","steps":["trace[962821532] 'read index received' (duration: 1.862306646s)","trace[962821532] 'applied index is now lower than readState.Index' (duration: 2.125791922s)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:15.796253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:11.300229Z","time spent":"4.495938023s","remote":"127.0.0.1:43530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":756,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:46:15.796231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:12.368976Z","time spent":"3.427238958s","remote":"127.0.0.1:43266","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-03-19T17:46:15.796397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.987361669s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:15.796439Z","caller":"traceutil/trace.go:171","msg":"trace[1556947733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:173; }","duration":"1.987457563s","start":"2026-03-19T17:46:13.808968Z","end":"2026-03-19T17:46:15.796425Z","steps":["trace[1556947733] 'agreement among raft nodes before linearized reading' (duration: 1.987369099s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:15.796465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:13.808954Z","time spent":"1.987505596s","remote":"127.0.0.1:43188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:46:15.796513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.240818965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:15.796567Z","caller":"traceutil/trace.go:171","msg":"trace[769289882] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:173; }","duration":"2.240903888s","start":"2026-03-19T17:46:13.555649Z","end":"2026-03-19T17:46:15.796553Z","steps":["trace[769289882] 'agreement among raft nodes before linearized reading' (duration: 2.240769592s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:15.796603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.089633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T17:46:15.796398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.927466389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:15.796625Z","caller":"traceutil/trace.go:171","msg":"trace[1796231663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:173; }","duration":"406.133515ms","start":"2026-03-19T17:46:15.390486Z","end":"2026-03-19T17:46:15.796619Z","steps":["trace[1796231663] 'agreement among raft nodes before linearized reading' (duration: 406.102764ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:15.796642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:15.390472Z","time spent":"406.166837ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T17:46:15.796651Z","caller":"traceutil/trace.go:171","msg":"trace[2147459717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:173; }","duration":"2.927790484s","start":"2026-03-19T17:46:12.868846Z","end":"2026-03-19T17:46:15.796637Z","steps":["trace[2147459717] 'agreement among raft nodes before linearized reading' (duration: 2.9274859s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:15.796646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:13.555625Z","time spent":"2.240982643s","remote":"127.0.0.1:43324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-03-19T17:46:15.796716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:12.868824Z","time spent":"2.927875828s","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-19T17:46:15.796785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.751844165s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:15.796891Z","caller":"traceutil/trace.go:171","msg":"trace[1489987319] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:173; }","duration":"2.751933849s","start":"2026-03-19T17:46:13.04489Z","end":"2026-03-19T17:46:15.796824Z","steps":["trace[1489987319] 'agreement among raft nodes before linearized reading' (duration: 2.751832984s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:15.796935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:13.044876Z","time spent":"2.752051165s","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-19T17:46:16.300307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368199433262011,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:46:16.800903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13655368199433262011,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-19T17:46:17.033955Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.235801679s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-19T17:46:17.807294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000171263s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-19T17:46:17.807376Z","caller":"traceutil/trace.go:171","msg":"trace[333222852] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000300958s","start":"2026-03-19T17:46:15.807056Z","end":"2026-03-19T17:46:17.807357Z","steps":["trace[333222852] 'agreement among raft nodes before linearized reading' (duration: 2.000191323s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:17.807425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:15.807048Z","time spent":"2.000367952s","remote":"127.0.0.1:43188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:46:18.430787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999553458s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-03-19T17:46:18.43087Z","caller":"traceutil/trace.go:171","msg":"trace[741207515] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999672024s","start":"2026-03-19T17:46:16.431178Z","end":"2026-03-19T17:46:18.43085Z","steps":["trace[741207515] 'agreement among raft nodes before linearized reading' (duration: 1.999570249s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:18.430917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:16.431164Z","time spent":"1.999744416s","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/19 17:46:18 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-19T17:46:18.946619Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.912327298s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-19T17:46:18.971636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.937427805s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:18.971982Z","caller":"traceutil/trace.go:171","msg":"trace[1694642931] linearizableReadLoop","detail":"{readStateIndex:183; appliedIndex:178; }","duration":"3.172452356s","start":"2026-03-19T17:46:15.799506Z","end":"2026-03-19T17:46:18.971958Z","steps":["trace[1694642931] 'read index received' (duration: 1.234667245s)","trace[1694642931] 'applied index is now lower than readState.Index' (duration: 1.937783731s)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:18.972094Z","caller":"traceutil/trace.go:171","msg":"trace[1135492659] transaction","detail":"{read_only:false; response_revision:177; number_of_response:1; }","duration":"3.171296411s","start":"2026-03-19T17:46:15.800784Z","end":"2026-03-19T17:46:18.972081Z","steps":["trace[1135492659] 'process raft request' (duration: 3.171087401s)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:18.972209Z","caller":"traceutil/trace.go:171","msg":"trace[1720937725] transaction","detail":"{read_only:false; response_revision:174; number_of_response:1; }","duration":"3.174129425s","start":"2026-03-19T17:46:15.798059Z","end":"2026-03-19T17:46:18.972189Z","steps":["trace[1720937725] 'process raft request' (duration: 1.236072001s)","trace[1720937725] 'compare' (duration: 1.936180178s)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:18.972322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:15.798036Z","time spent":"3.174227409s","remote":"127.0.0.1:43266","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:46:18.972346Z","caller":"traceutil/trace.go:171","msg":"trace[1961082886] transaction","detail":"{read_only:false; response_revision:176; number_of_response:1; }","duration":"3.171868228s","start":"2026-03-19T17:46:15.800457Z","end":"2026-03-19T17:46:18.972326Z","steps":["trace[1961082886] 'process raft request' (duration: 3.171374056s)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:18.972351Z","caller":"traceutil/trace.go:171","msg":"trace[489648009] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"3.172745999s","start":"2026-03-19T17:46:15.799578Z","end":"2026-03-19T17:46:18.972324Z","steps":["trace[489648009] 'process raft request' (duration: 3.172166683s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:18.972411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171569832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-03-19T17:46:18.972439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:15.800422Z","time spent":"3.171963002s","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:46:18.972486Z","caller":"traceutil/trace.go:171","msg":"trace[1322909756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:178; }","duration":"1.171710889s","start":"2026-03-19T17:46:17.800759Z","end":"2026-03-19T17:46:18.97247Z","steps":["trace[1322909756] 'agreement among raft nodes before linearized reading' (duration: 1.171537601s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:18.972534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:17.800736Z","time spent":"1.171788812s","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-19T17:46:18.97256Z","caller":"traceutil/trace.go:171","msg":"trace[1879292969] transaction","detail":"{read_only:false; response_revision:178; number_of_response:1; }","duration":"3.171743251s","start":"2026-03-19T17:46:15.800803Z","end":"2026-03-19T17:46:18.972547Z","steps":["trace[1879292969] 'process raft request' (duration: 3.171114682s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:18.972639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:15.80079Z","time spent":"3.171819885s","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-19T17:46:18.972688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.173096396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-19T17:46:18.972486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:15.799565Z","time spent":"3.172861835s","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:46:18.972798Z","caller":"traceutil/trace.go:171","msg":"trace[1773244131] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:route-controller; range_end:; response_count:0; response_revision:178; }","duration":"3.173251953s","start":"2026-03-19T17:46:15.799482Z","end":"2026-03-19T17:46:18.972734Z","steps":["trace[1773244131] 'agreement among raft nodes before linearized reading' (duration: 3.172631624s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:18.972748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.17173506s","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-03-19T17:46:18.972931Z","caller":"traceutil/trace.go:171","msg":"trace[1110104152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:178; }","duration":"1.17194165s","start":"2026-03-19T17:46:17.800972Z","end":"2026-03-19T17:46:18.972914Z","steps":["trace[1110104152] 'agreement among raft nodes before linearized reading' (duration: 1.171752881s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:18.972894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:15.799471Z","time spent":"3.173409839s","remote":"127.0.0.1:43530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":29,"request content":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" "} {"level":"warn","ts":"2026-03-19T17:46:18.972999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:17.800961Z","time spent":"1.172016793s","remote":"127.0.0.1:43436","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-03-19T17:46:18.972705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.065264424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:18.973137Z","caller":"traceutil/trace.go:171","msg":"trace[1905691968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:178; }","duration":"1.065801059s","start":"2026-03-19T17:46:17.907324Z","end":"2026-03-19T17:46:18.973125Z","steps":["trace[1905691968] 'agreement among raft nodes before linearized reading' (duration: 1.065358038s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:18.973184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:17.907295Z","time spent":"1.065878122s","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-19T17:46:18.972176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:15.800772Z","time spent":"3.171357784s","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-19T17:46:19.286249Z","caller":"traceutil/trace.go:171","msg":"trace[1246928105] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"140.280669ms","start":"2026-03-19T17:46:19.145944Z","end":"2026-03-19T17:46:19.286225Z","steps":["trace[1246928105] 'process raft request' (duration: 80.585921ms)","trace[1246928105] 'compare' (duration: 59.51834ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:19.286374Z","caller":"traceutil/trace.go:171","msg":"trace[1302025598] linearizableReadLoop","detail":"{readStateIndex:212; appliedIndex:211; }","duration":"140.198945ms","start":"2026-03-19T17:46:19.146138Z","end":"2026-03-19T17:46:19.286337Z","steps":["trace[1302025598] 'read index received' (duration: 80.402263ms)","trace[1302025598] 'applied index is now lower than readState.Index' (duration: 59.794402ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:19.28655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.387354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/extension-apiserver-authentication-reader\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:19.286637Z","caller":"traceutil/trace.go:171","msg":"trace[574478423] range","detail":"{range_begin:/registry/roles/kube-system/extension-apiserver-authentication-reader; range_end:; response_count:0; response_revision:207; }","duration":"140.50861ms","start":"2026-03-19T17:46:19.146106Z","end":"2026-03-19T17:46:19.286614Z","steps":["trace[574478423] 'agreement among raft nodes before linearized reading' (duration: 140.333622ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:19.598021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.51834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:19.598107Z","caller":"traceutil/trace.go:171","msg":"trace[976914323] linearizableReadLoop","detail":"{readStateIndex:216; appliedIndex:215; }","duration":"216.059454ms","start":"2026-03-19T17:46:19.382034Z","end":"2026-03-19T17:46:19.598094Z","steps":["trace[976914323] 'read index received' (duration: 73.317083ms)","trace[976914323] 'applied index is now lower than readState.Index' (duration: 142.741381ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:19.598166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.143787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-19T17:46:19.598195Z","caller":"traceutil/trace.go:171","msg":"trace[1246123206] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:211; }","duration":"216.192609ms","start":"2026-03-19T17:46:19.381992Z","end":"2026-03-19T17:46:19.598185Z","steps":["trace[1246123206] 'agreement among raft nodes before linearized reading' (duration: 216.135326ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:19.598207Z","caller":"traceutil/trace.go:171","msg":"trace[1683160162] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"218.035822ms","start":"2026-03-19T17:46:19.380142Z","end":"2026-03-19T17:46:19.598177Z","steps":["trace[1683160162] 'process raft request' (duration: 75.299062ms)","trace[1683160162] 'compare' (duration: 142.433047ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:20.064672Z","caller":"traceutil/trace.go:171","msg":"trace[1469611021] linearizableReadLoop","detail":"{readStateIndex:234; appliedIndex:233; }","duration":"104.158317ms","start":"2026-03-19T17:46:19.960477Z","end":"2026-03-19T17:46:20.064635Z","steps":["trace[1469611021] 'read index received' (duration: 25.547194ms)","trace[1469611021] 'applied index is now lower than readState.Index' (duration: 78.609653ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:20.064894Z","caller":"traceutil/trace.go:171","msg":"trace[490339642] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"149.230043ms","start":"2026-03-19T17:46:19.915516Z","end":"2026-03-19T17:46:20.064746Z","steps":["trace[490339642] 'process raft request' (duration: 70.570318ms)","trace[490339642] 'compare' (duration: 78.365831ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:20.06534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.412218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.189e4f2e59f664d4\" ","response":"range_response_count:1 size:653"} {"level":"info","ts":"2026-03-19T17:46:20.065518Z","caller":"traceutil/trace.go:171","msg":"trace[863320120] range","detail":"{range_begin:/registry/events/default/instance.189e4f2e59f664d4; range_end:; response_count:1; response_revision:229; }","duration":"105.038306ms","start":"2026-03-19T17:46:19.960453Z","end":"2026-03-19T17:46:20.065491Z","steps":["trace[863320120] 'agreement among raft nodes before linearized reading' (duration: 104.312333ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:22.124681Z","caller":"traceutil/trace.go:171","msg":"trace[911820887] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"155.672808ms","start":"2026-03-19T17:46:21.968974Z","end":"2026-03-19T17:46:22.124647Z","steps":["trace[911820887] 'process raft request' (duration: 155.605025ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:22.124728Z","caller":"traceutil/trace.go:171","msg":"trace[737383013] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"159.313798ms","start":"2026-03-19T17:46:21.965373Z","end":"2026-03-19T17:46:22.124686Z","steps":["trace[737383013] 'process raft request' (duration: 73.137218ms)","trace[737383013] 'compare' (duration: 85.837636ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:22.755343Z","caller":"traceutil/trace.go:171","msg":"trace[1579363646] transaction","detail":"{read_only:false; response_revision:311; number_of_response:1; }","duration":"122.768242ms","start":"2026-03-19T17:46:22.632547Z","end":"2026-03-19T17:46:22.755315Z","steps":["trace[1579363646] 'process raft request' (duration: 122.711639ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:22.755383Z","caller":"traceutil/trace.go:171","msg":"trace[2080808634] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"122.900937ms","start":"2026-03-19T17:46:22.632435Z","end":"2026-03-19T17:46:22.755335Z","steps":["trace[2080808634] 'process raft request' (duration: 122.659507ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:22.841449Z","caller":"traceutil/trace.go:171","msg":"trace[339647020] transaction","detail":"{read_only:false; number_of_response:0; response_revision:311; }","duration":"164.420844ms","start":"2026-03-19T17:46:22.676996Z","end":"2026-03-19T17:46:22.841416Z","steps":["trace[339647020] 'process raft request' (duration: 136.312764ms)","trace[339647020] 'compare' (duration: 27.954903ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:22.841548Z","caller":"traceutil/trace.go:171","msg":"trace[1088778880] transaction","detail":"{read_only:false; number_of_response:0; response_revision:312; }","duration":"133.03082ms","start":"2026-03-19T17:46:22.708494Z","end":"2026-03-19T17:46:22.841525Z","steps":["trace[1088778880] 'process raft request' (duration: 132.900825ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:22.841673Z","caller":"traceutil/trace.go:171","msg":"trace[898255883] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"132.11096ms","start":"2026-03-19T17:46:22.709538Z","end":"2026-03-19T17:46:22.841649Z","steps":["trace[898255883] 'process raft request' (duration: 131.89252ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:22.841947Z","caller":"traceutil/trace.go:171","msg":"trace[41295312] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"130.411286ms","start":"2026-03-19T17:46:22.711506Z","end":"2026-03-19T17:46:22.841917Z","steps":["trace[41295312] 'process raft request' (duration: 130.014178ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:22.842029Z","caller":"traceutil/trace.go:171","msg":"trace[2043640982] transaction","detail":"{read_only:false; number_of_response:0; response_revision:314; }","duration":"130.26483ms","start":"2026-03-19T17:46:22.711745Z","end":"2026-03-19T17:46:22.84201Z","steps":["trace[2043640982] 'process raft request' (duration: 129.82046ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:22.84209Z","caller":"traceutil/trace.go:171","msg":"trace[917236442] linearizableReadLoop","detail":"{readStateIndex:318; appliedIndex:315; }","duration":"135.257647ms","start":"2026-03-19T17:46:22.706812Z","end":"2026-03-19T17:46:22.842069Z","steps":["trace[917236442] 'read index received' (duration: 48.362155ms)","trace[917236442] 'applied index is now lower than readState.Index' (duration: 86.892972ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:22.842107Z","caller":"traceutil/trace.go:171","msg":"trace[809890077] transaction","detail":"{read_only:false; number_of_response:0; response_revision:313; }","duration":"131.146558ms","start":"2026-03-19T17:46:22.710939Z","end":"2026-03-19T17:46:22.842086Z","steps":["trace[809890077] 'process raft request' (duration: 130.550852ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:22.841644Z","caller":"traceutil/trace.go:171","msg":"trace[1494944368] transaction","detail":"{read_only:false; response_revision:312; number_of_response:1; }","duration":"133.346934ms","start":"2026-03-19T17:46:22.708246Z","end":"2026-03-19T17:46:22.841593Z","steps":["trace[1494944368] 'process raft request' (duration: 133.066342ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:22.842232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.621646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-19T17:46:22.842282Z","caller":"traceutil/trace.go:171","msg":"trace[556239380] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:315; }","duration":"173.71671ms","start":"2026-03-19T17:46:22.668549Z","end":"2026-03-19T17:46:22.842266Z","steps":["trace[556239380] 'agreement among raft nodes before linearized reading' (duration: 173.582954ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:24.320376Z","caller":"traceutil/trace.go:171","msg":"trace[588303387] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"101.312519ms","start":"2026-03-19T17:46:24.21904Z","end":"2026-03-19T17:46:24.320353Z","steps":["trace[588303387] 'process raft request' (duration: 101.175483ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:25.510239Z","caller":"traceutil/trace.go:171","msg":"trace[477340777] linearizableReadLoop","detail":"{readStateIndex:367; appliedIndex:366; }","duration":"141.999358ms","start":"2026-03-19T17:46:25.368214Z","end":"2026-03-19T17:46:25.510213Z","steps":["trace[477340777] 'read index received' (duration: 108.918006ms)","trace[477340777] 'applied index is now lower than readState.Index' (duration: 33.080341ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:25.510376Z","caller":"traceutil/trace.go:171","msg":"trace[1111649944] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"185.960811ms","start":"2026-03-19T17:46:25.324379Z","end":"2026-03-19T17:46:25.510339Z","steps":["trace[1111649944] 'process raft request' (duration: 153.013295ms)","trace[1111649944] 'compare' (duration: 32.682585ms)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:25.510428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.194746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-03-19T17:46:25.510487Z","caller":"traceutil/trace.go:171","msg":"trace[222533740] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:357; }","duration":"142.31045ms","start":"2026-03-19T17:46:25.368159Z","end":"2026-03-19T17:46:25.51047Z","steps":["trace[222533740] 'agreement among raft nodes before linearized reading' (duration: 142.190395ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:26.281744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.216264ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:26.281903Z","caller":"traceutil/trace.go:171","msg":"trace[1487452952] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"262.30436ms","start":"2026-03-19T17:46:26.01958Z","end":"2026-03-19T17:46:26.281884Z","steps":["trace[1487452952] 'process raft request' (duration: 109.866108ms)","trace[1487452952] 'compare' (duration: 152.086837ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:26.282255Z","caller":"traceutil/trace.go:171","msg":"trace[748613107] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"260.502975ms","start":"2026-03-19T17:46:26.021711Z","end":"2026-03-19T17:46:26.282214Z","steps":["trace[748613107] 'process raft request' (duration: 260.131859ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:26.282355Z","caller":"traceutil/trace.go:171","msg":"trace[343571039] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:383; }","duration":"212.642377ms","start":"2026-03-19T17:46:26.069686Z","end":"2026-03-19T17:46:26.282328Z","steps":["trace[343571039] 'read index received' (duration: 59.777336ms)","trace[343571039] 'applied index is now lower than readState.Index' (duration: 152.862151ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:26.282345Z","caller":"traceutil/trace.go:171","msg":"trace[1569353624] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"257.907106ms","start":"2026-03-19T17:46:26.024379Z","end":"2026-03-19T17:46:26.282287Z","steps":["trace[1569353624] 'process raft request' (duration: 257.805732ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:26.282267Z","caller":"traceutil/trace.go:171","msg":"trace[375055450] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"258.222549ms","start":"2026-03-19T17:46:26.024018Z","end":"2026-03-19T17:46:26.282241Z","steps":["trace[375055450] 'process raft request' (duration: 258.094474ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:26.28255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.840126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-19T17:46:26.282604Z","caller":"traceutil/trace.go:171","msg":"trace[2001683835] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:377; }","duration":"212.93659ms","start":"2026-03-19T17:46:26.069653Z","end":"2026-03-19T17:46:26.282589Z","steps":["trace[2001683835] 'agreement among raft nodes before linearized reading' (duration: 212.818355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:26.282652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.411292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-19T17:46:26.28271Z","caller":"traceutil/trace.go:171","msg":"trace[855995347] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:377; }","duration":"163.497076ms","start":"2026-03-19T17:46:26.119201Z","end":"2026-03-19T17:46:26.282698Z","steps":["trace[855995347] 'agreement among raft nodes before linearized reading' (duration: 163.396632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:26.282789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.777651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-03-19T17:46:26.282853Z","caller":"traceutil/trace.go:171","msg":"trace[132888500] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:377; }","duration":"113.890926ms","start":"2026-03-19T17:46:26.16895Z","end":"2026-03-19T17:46:26.282841Z","steps":["trace[132888500] 'agreement among raft nodes before linearized reading' (duration: 113.757111ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:45.259964Z","caller":"traceutil/trace.go:171","msg":"trace[1053630105] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"111.73189ms","start":"2026-03-19T17:46:45.148206Z","end":"2026-03-19T17:46:45.259938Z","steps":["trace[1053630105] 'process raft request' (duration: 111.592385ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:45.278251Z","caller":"traceutil/trace.go:171","msg":"trace[480392841] linearizableReadLoop","detail":"{readStateIndex:501; appliedIndex:499; }","duration":"121.047503ms","start":"2026-03-19T17:46:45.157168Z","end":"2026-03-19T17:46:45.278215Z","steps":["trace[480392841] 'read index received' (duration: 102.743778ms)","trace[480392841] 'applied index is now lower than readState.Index' (duration: 18.302315ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:45.278306Z","caller":"traceutil/trace.go:171","msg":"trace[844932153] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"127.728675ms","start":"2026-03-19T17:46:45.150534Z","end":"2026-03-19T17:46:45.278262Z","steps":["trace[844932153] 'process raft request' (duration: 127.469016ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:45.278418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.215399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-19T17:46:45.278498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.445767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-19T17:46:45.2785Z","caller":"traceutil/trace.go:171","msg":"trace[2063336870] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:487; }","duration":"121.337292ms","start":"2026-03-19T17:46:45.157145Z","end":"2026-03-19T17:46:45.278482Z","steps":["trace[2063336870] 'agreement among raft nodes before linearized reading' (duration: 121.169676ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:45.278551Z","caller":"traceutil/trace.go:171","msg":"trace[1953281157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:487; }","duration":"117.581212ms","start":"2026-03-19T17:46:45.160953Z","end":"2026-03-19T17:46:45.278534Z","steps":["trace[1953281157] 'agreement among raft nodes before linearized reading' (duration: 117.458778ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.504628Z","caller":"traceutil/trace.go:171","msg":"trace[1751993367] linearizableReadLoop","detail":"{readStateIndex:545; appliedIndex:544; }","duration":"201.659627ms","start":"2026-03-19T17:46:49.302949Z","end":"2026-03-19T17:46:49.504608Z","steps":["trace[1751993367] 'read index received' (duration: 201.498142ms)","trace[1751993367] 'applied index is now lower than readState.Index' (duration: 160.815µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:49.504691Z","caller":"traceutil/trace.go:171","msg":"trace[495029770] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"218.753489ms","start":"2026-03-19T17:46:49.285902Z","end":"2026-03-19T17:46:49.504656Z","steps":["trace[495029770] 'process raft request' (duration: 218.596273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.504843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.875044ms","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-03-19T17:46:49.504873Z","caller":"traceutil/trace.go:171","msg":"trace[1478225785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"201.961507ms","start":"2026-03-19T17:46:49.302903Z","end":"2026-03-19T17:46:49.504864Z","steps":["trace[1478225785] 'agreement among raft nodes before linearized reading' (duration: 201.802611ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:49.50494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.383326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-19T17:46:49.504977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.874189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-19T17:46:49.504989Z","caller":"traceutil/trace.go:171","msg":"trace[1545138661] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:530; }","duration":"166.49011ms","start":"2026-03-19T17:46:49.338485Z","end":"2026-03-19T17:46:49.504975Z","steps":["trace[1545138661] 'agreement among raft nodes before linearized reading' (duration: 166.393947ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:49.505007Z","caller":"traceutil/trace.go:171","msg":"trace[2036935991] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"162.91784ms","start":"2026-03-19T17:46:49.342083Z","end":"2026-03-19T17:46:49.505Z","steps":["trace[2036935991] 'agreement among raft nodes before linearized reading' (duration: 162.870469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:51.034489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.511743ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-19T17:46:51.03463Z","caller":"traceutil/trace.go:171","msg":"trace[2027875151] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"244.509256ms","start":"2026-03-19T17:46:50.790097Z","end":"2026-03-19T17:46:51.034607Z","steps":["trace[2027875151] 'process raft request' (duration: 124.781577ms)","trace[2027875151] 'compare' (duration: 119.276084ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:51.040708Z","caller":"traceutil/trace.go:171","msg":"trace[17624714] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"250.536795ms","start":"2026-03-19T17:46:50.790144Z","end":"2026-03-19T17:46:51.040681Z","steps":["trace[17624714] 'process raft request' (duration: 250.264596ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:51.040969Z","caller":"traceutil/trace.go:171","msg":"trace[1192575614] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"250.197423ms","start":"2026-03-19T17:46:50.790765Z","end":"2026-03-19T17:46:51.040962Z","steps":["trace[1192575614] 'process raft request' (duration: 250.085669ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:51.04097Z","caller":"traceutil/trace.go:171","msg":"trace[687153327] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:566; }","duration":"244.744723ms","start":"2026-03-19T17:46:50.796207Z","end":"2026-03-19T17:46:51.040951Z","steps":["trace[687153327] 'read index received' (duration: 118.732927ms)","trace[687153327] 'applied index is now lower than readState.Index' (duration: 126.010826ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:46:51.041047Z","caller":"traceutil/trace.go:171","msg":"trace[1137284942] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"250.472933ms","start":"2026-03-19T17:46:50.790569Z","end":"2026-03-19T17:46:51.041042Z","steps":["trace[1137284942] 'process raft request' (duration: 249.974736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:51.04117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.948959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12755"} {"level":"info","ts":"2026-03-19T17:46:51.041205Z","caller":"traceutil/trace.go:171","msg":"trace[1651670324] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:555; }","duration":"245.018292ms","start":"2026-03-19T17:46:50.796175Z","end":"2026-03-19T17:46:51.041193Z","steps":["trace[1651670324] 'agreement among raft nodes before linearized reading' (duration: 244.847056ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:51.041374Z","caller":"traceutil/trace.go:171","msg":"trace[1695123193] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"246.78149ms","start":"2026-03-19T17:46:50.794586Z","end":"2026-03-19T17:46:51.041368Z","steps":["trace[1695123193] 'process raft request' (duration: 246.312655ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:53.701185Z","caller":"traceutil/trace.go:171","msg":"trace[1079697431] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"111.363262ms","start":"2026-03-19T17:46:53.589798Z","end":"2026-03-19T17:46:53.701161Z","steps":["trace[1079697431] 'process raft request' (duration: 110.301618ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:53.701722Z","caller":"traceutil/trace.go:171","msg":"trace[227335649] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"110.95362ms","start":"2026-03-19T17:46:53.59074Z","end":"2026-03-19T17:46:53.701694Z","steps":["trace[227335649] 'process raft request' (duration: 110.693181ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:53.701797Z","caller":"traceutil/trace.go:171","msg":"trace[2039456010] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"110.790755ms","start":"2026-03-19T17:46:53.590995Z","end":"2026-03-19T17:46:53.701785Z","steps":["trace[2039456010] 'process raft request' (duration: 110.562787ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:57.790164Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.042072916s","expected-duration":"1s"} {"level":"info","ts":"2026-03-19T17:46:57.790655Z","caller":"traceutil/trace.go:171","msg":"trace[571641871] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"190.232077ms","start":"2026-03-19T17:46:57.600394Z","end":"2026-03-19T17:46:57.790627Z","steps":["trace[571641871] 'read index received' (duration: 189.916527ms)","trace[571641871] 'applied index is now lower than readState.Index' (duration: 314.69µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:46:57.7912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.782234ms","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-03-19T17:46:57.791283Z","caller":"traceutil/trace.go:171","msg":"trace[184269554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:574; }","duration":"190.910398ms","start":"2026-03-19T17:46:57.600349Z","end":"2026-03-19T17:46:57.79126Z","steps":["trace[184269554] 'agreement among raft nodes before linearized reading' (duration: 190.64295ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:57.791674Z","caller":"traceutil/trace.go:171","msg":"trace[202188901] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"1.043687586s","start":"2026-03-19T17:46:56.747954Z","end":"2026-03-19T17:46:57.791641Z","steps":["trace[202188901] 'process raft request' (duration: 1.042453348s)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:57.791955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:56.747924Z","time spent":"1.043936614s","remote":"127.0.0.1:43368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:58.422708Z","caller":"traceutil/trace.go:171","msg":"trace[1827497844] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"625.502796ms","start":"2026-03-19T17:46:57.797169Z","end":"2026-03-19T17:46:58.422672Z","steps":["trace[1827497844] 'process raft request' (duration: 625.32269ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:58.422913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:57.797141Z","time spent":"625.65454ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:58.428682Z","caller":"traceutil/trace.go:171","msg":"trace[1655788460] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"631.312756ms","start":"2026-03-19T17:46:57.797352Z","end":"2026-03-19T17:46:58.428665Z","steps":["trace[1655788460] 'process raft request' (duration: 631.186102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:58.428984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:57.797329Z","time spent":"631.404578ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-19T17:46:58.429039Z","caller":"traceutil/trace.go:171","msg":"trace[1120871114] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"631.589474ms","start":"2026-03-19T17:46:57.797435Z","end":"2026-03-19T17:46:58.429024Z","steps":["trace[1120871114] 'process raft request' (duration: 631.192182ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:46:58.42937Z","caller":"traceutil/trace.go:171","msg":"trace[949818991] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"631.46302ms","start":"2026-03-19T17:46:57.797878Z","end":"2026-03-19T17:46:58.429341Z","steps":["trace[949818991] 'process raft request' (duration: 631.095668ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:58.429484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:57.797862Z","time spent":"631.575674ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:58.429754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:57.797422Z","time spent":"631.75869ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:46:59.63351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.94499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:46:59.633593Z","caller":"traceutil/trace.go:171","msg":"trace[886094405] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"932.080234ms","start":"2026-03-19T17:46:58.701496Z","end":"2026-03-19T17:46:59.633576Z","steps":["trace[886094405] 'range keys from in-memory index tree' (duration: 931.859158ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:46:59.633635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:58.701482Z","time spent":"932.144356ms","remote":"127.0.0.1:43202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-19T17:47:00.766399Z","caller":"traceutil/trace.go:171","msg":"trace[869826932] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"457.369579ms","start":"2026-03-19T17:47:00.30901Z","end":"2026-03-19T17:47:00.76638Z","steps":["trace[869826932] 'read index received' (duration: 457.211954ms)","trace[869826932] 'applied index is now lower than readState.Index' (duration: 156.655µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:47:00.76655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.523533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.171\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-19T17:47:00.766529Z","caller":"traceutil/trace.go:171","msg":"trace[785806524] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"787.967884ms","start":"2026-03-19T17:46:59.978524Z","end":"2026-03-19T17:47:00.766492Z","steps":["trace[785806524] 'process raft request' (duration: 787.716137ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:00.766613Z","caller":"traceutil/trace.go:171","msg":"trace[898696943] range","detail":"{range_begin:/registry/masterleases/199.19.213.171; range_end:; response_count:1; response_revision:579; }","duration":"457.596615ms","start":"2026-03-19T17:47:00.308997Z","end":"2026-03-19T17:47:00.766594Z","steps":["trace[898696943] 'agreement among raft nodes before linearized reading' (duration: 457.496082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:00.766644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:00.308937Z","time spent":"457.699389ms","remote":"127.0.0.1:43218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.171\" "} {"level":"warn","ts":"2026-03-19T17:47:00.766718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:46:59.978496Z","time spent":"788.129038ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-19T17:47:00.767157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.229569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-19T17:47:00.767197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.127816ms","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-03-19T17:47:00.767234Z","caller":"traceutil/trace.go:171","msg":"trace[1253178024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:579; }","duration":"333.360043ms","start":"2026-03-19T17:47:00.433852Z","end":"2026-03-19T17:47:00.767212Z","steps":["trace[1253178024] 'agreement among raft nodes before linearized reading' (duration: 333.175457ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:00.767259Z","caller":"traceutil/trace.go:171","msg":"trace[1902995271] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"333.211768ms","start":"2026-03-19T17:47:00.434031Z","end":"2026-03-19T17:47:00.767243Z","steps":["trace[1902995271] 'agreement among raft nodes before linearized reading' (duration: 332.983001ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:00.767154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.748694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-19T17:47:00.767281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:00.433841Z","time spent":"333.429035ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-19T17:47:00.767301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:00.434017Z","time spent":"333.27548ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-19T17:47:00.767317Z","caller":"traceutil/trace.go:171","msg":"trace[1148307265] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:579; }","duration":"332.966081ms","start":"2026-03-19T17:47:00.434341Z","end":"2026-03-19T17:47:00.767307Z","steps":["trace[1148307265] 'agreement among raft nodes before linearized reading' (duration: 332.652011ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:00.767362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:00.434332Z","time spent":"333.018672ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-19T17:47:00.767759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.713695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-19T17:47:00.767914Z","caller":"traceutil/trace.go:171","msg":"trace[108360498] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"339.945332ms","start":"2026-03-19T17:47:00.427937Z","end":"2026-03-19T17:47:00.767882Z","steps":["trace[108360498] 'agreement among raft nodes before linearized reading' (duration: 339.033815ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:00.767991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-19T17:47:00.427906Z","time spent":"340.069895ms","remote":"127.0.0.1:43436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-19T17:47:17.995236Z","caller":"traceutil/trace.go:171","msg":"trace[1003688551] linearizableReadLoop","detail":"{readStateIndex:795; appliedIndex:794; }","duration":"124.622727ms","start":"2026-03-19T17:47:17.87059Z","end":"2026-03-19T17:47:17.995213Z","steps":["trace[1003688551] 'read index received' (duration: 124.38027ms)","trace[1003688551] 'applied index is now lower than readState.Index' (duration: 241.096µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:47:17.995291Z","caller":"traceutil/trace.go:171","msg":"trace[529326829] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"127.151243ms","start":"2026-03-19T17:47:17.868099Z","end":"2026-03-19T17:47:17.99525Z","steps":["trace[529326829] 'process raft request' (duration: 126.947188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:47:17.99557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.952735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-6kxzc\" ","response":"range_response_count:1 size:5086"} {"level":"info","ts":"2026-03-19T17:47:17.995649Z","caller":"traceutil/trace.go:171","msg":"trace[145023518] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-6kxzc; range_end:; response_count:1; response_revision:771; }","duration":"125.071029ms","start":"2026-03-19T17:47:17.870558Z","end":"2026-03-19T17:47:17.995629Z","steps":["trace[145023518] 'agreement among raft nodes before linearized reading' (duration: 124.76501ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:22.948413Z","caller":"traceutil/trace.go:171","msg":"trace[1090410387] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"147.585605ms","start":"2026-03-19T17:47:22.800803Z","end":"2026-03-19T17:47:22.948389Z","steps":["trace[1090410387] 'process raft request' (duration: 147.412801ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:47:23.177368Z","caller":"traceutil/trace.go:171","msg":"trace[1086876614] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"130.499805ms","start":"2026-03-19T17:47:23.046827Z","end":"2026-03-19T17:47:23.177327Z","steps":["trace[1086876614] 'process raft request' (duration: 115.541935ms)","trace[1086876614] 'compare' (duration: 14.789666ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:48:31.578597Z","caller":"traceutil/trace.go:171","msg":"trace[1770540386] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"259.155305ms","start":"2026-03-19T17:48:31.319427Z","end":"2026-03-19T17:48:31.578582Z","steps":["trace[1770540386] 'read index received' (duration: 258.977272ms)","trace[1770540386] 'applied index is now lower than readState.Index' (duration: 177.473µs)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:48:31.578843Z","caller":"traceutil/trace.go:171","msg":"trace[840631273] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"270.822774ms","start":"2026-03-19T17:48:31.308013Z","end":"2026-03-19T17:48:31.578836Z","steps":["trace[840631273] 'process raft request' (duration: 270.436197ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:48:31.579002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.561993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-19T17:48:31.579035Z","caller":"traceutil/trace.go:171","msg":"trace[1742570285] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1023; }","duration":"259.624613ms","start":"2026-03-19T17:48:31.3194Z","end":"2026-03-19T17:48:31.579025Z","steps":["trace[1742570285] 'agreement among raft nodes before linearized reading' (duration: 259.536472ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:48:31.579173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.713127ms","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-03-19T17:48:31.57919Z","caller":"traceutil/trace.go:171","msg":"trace[969809678] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1023; }","duration":"123.766998ms","start":"2026-03-19T17:48:31.455418Z","end":"2026-03-19T17:48:31.579185Z","steps":["trace[969809678] 'agreement among raft nodes before linearized reading' (duration: 123.717497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-19T17:48:31.579259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.554849ms","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-03-19T17:48:31.579273Z","caller":"traceutil/trace.go:171","msg":"trace[1535386463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1023; }","duration":"116.60583ms","start":"2026-03-19T17:48:31.462664Z","end":"2026-03-19T17:48:31.579269Z","steps":["trace[1535386463] 'agreement among raft nodes before linearized reading' (duration: 116.563369ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:48:31.777104Z","caller":"traceutil/trace.go:171","msg":"trace[1299858453] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"148.418361ms","start":"2026-03-19T17:48:31.628655Z","end":"2026-03-19T17:48:31.777073Z","steps":["trace[1299858453] 'process raft request' (duration: 90.136676ms)","trace[1299858453] 'compare' (duration: 58.156683ms)"],"step_count":2} {"level":"info","ts":"2026-03-19T17:48:32.792016Z","caller":"traceutil/trace.go:171","msg":"trace[571880722] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"153.889029ms","start":"2026-03-19T17:48:32.638084Z","end":"2026-03-19T17:48:32.791973Z","steps":["trace[571880722] 'process raft request' (duration: 153.672286ms)"],"step_count":1} {"level":"info","ts":"2026-03-19T17:48:32.791973Z","caller":"traceutil/trace.go:171","msg":"trace[2024744527] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1068; }","duration":"118.821683ms","start":"2026-03-19T17:48:32.673118Z","end":"2026-03-19T17:48:32.791939Z","steps":["trace[2024744527] 'read index received' (duration: 118.547068ms)","trace[2024744527] 'applied index is now lower than readState.Index' (duration: 273.235µs)"],"step_count":2} {"level":"warn","ts":"2026-03-19T17:48:32.792151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.013756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-19T17:48:32.792193Z","caller":"traceutil/trace.go:171","msg":"trace[1669522303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1028; }","duration":"119.102148ms","start":"2026-03-19T17:48:32.673079Z","end":"2026-03-19T17:48:32.792181Z","steps":["trace[1669522303] 'agreement among raft nodes before linearized reading' (duration: 119.013757ms)"],"step_count":1}