{"level":"warn","ts":"2026-03-25T07:30:23.197375Z","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-25T07:30:23.19749Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.145: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.145:2380","--initial-cluster=instance=https://199.19.213.145:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.145:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.145: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-25T07:30:23.197568Z","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-25T07:30:23.197576Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.145:2380"]} {"level":"info","ts":"2026-03-25T07:30:23.197595Z","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-25T07:30:23.201433Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.145:2379"]} {"level":"info","ts":"2026-03-25T07:30:23.201557Z","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.145:2380"],"listen-peer-urls":["https://199.19.213.145:2380"],"advertise-client-urls":["https://199.19.213.145:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.145:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.145: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-25T07:30:23.207011Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.131734ms"} {"level":"info","ts":"2026-03-25T07:30:23.216864Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"76a9cbc7029401f7","cluster-id":"ebd7ebb015925037"} {"level":"info","ts":"2026-03-25T07:30:23.217102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T07:30:23.217235Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became follower at term 0"} {"level":"info","ts":"2026-03-25T07:30:23.217287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 76a9cbc7029401f7 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T07:30:23.217404Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became follower at term 1"} {"level":"info","ts":"2026-03-25T07:30:23.217561Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 switched to configuration voters=(8550589423141782007)"} {"level":"warn","ts":"2026-03-25T07:30:23.227261Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T07:30:23.235736Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T07:30:23.238334Z","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-25T07:30:23.243199Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"76a9cbc7029401f7","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T07:30:23.244257Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"76a9cbc7029401f7","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T07:30:23.244258Z","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-25T07:30:23.244373Z","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-25T07:30:23.244397Z","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-25T07:30:23.245954Z","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-25T07:30:23.24618Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"76a9cbc7029401f7","initial-advertise-peer-urls":["https://199.19.213.145:2380"],"listen-peer-urls":["https://199.19.213.145:2380"],"advertise-client-urls":["https://199.19.213.145:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.145:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T07:30:23.24621Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T07:30:23.246338Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.145:2380"} {"level":"info","ts":"2026-03-25T07:30:23.246354Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.145:2380"} {"level":"info","ts":"2026-03-25T07:30:23.251422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 switched to configuration voters=(8550589423141782007)"} {"level":"info","ts":"2026-03-25T07:30:23.251651Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ebd7ebb015925037","local-member-id":"76a9cbc7029401f7","added-peer-id":"76a9cbc7029401f7","added-peer-peer-urls":["https://199.19.213.145:2380"]} {"level":"info","ts":"2026-03-25T07:30:24.118889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T07:30:24.11895Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T07:30:24.118991Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 received MsgPreVoteResp from 76a9cbc7029401f7 at term 1"} {"level":"info","ts":"2026-03-25T07:30:24.119005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became candidate at term 2"} {"level":"info","ts":"2026-03-25T07:30:24.119013Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 received MsgVoteResp from 76a9cbc7029401f7 at term 2"} {"level":"info","ts":"2026-03-25T07:30:24.119024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"76a9cbc7029401f7 became leader at term 2"} {"level":"info","ts":"2026-03-25T07:30:24.119033Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 76a9cbc7029401f7 elected leader 76a9cbc7029401f7 at term 2"} {"level":"info","ts":"2026-03-25T07:30:24.119762Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T07:30:24.120571Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T07:30:24.120553Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T07:30:24.12055Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"76a9cbc7029401f7","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.145:2379]}","request-path":"/0/members/76a9cbc7029401f7/attributes","cluster-id":"ebd7ebb015925037","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T07:30:24.12088Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T07:30:24.120927Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T07:30:24.121132Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ebd7ebb015925037","local-member-id":"76a9cbc7029401f7","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T07:30:24.121217Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T07:30:24.121251Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T07:30:24.122373Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-25T07:30:24.125128Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.145:2379"} {"level":"info","ts":"2026-03-25T07:30:25.764734Z","caller":"traceutil/trace.go:171","msg":"trace[1468284760] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:13; }","duration":"214.452733ms","start":"2026-03-25T07:30:25.550259Z","end":"2026-03-25T07:30:25.764712Z","steps":["trace[1468284760] 'read index received' (duration: 136.371298ms)","trace[1468284760] 'applied index is now lower than readState.Index' (duration: 78.080565ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:30:25.764872Z","caller":"traceutil/trace.go:171","msg":"trace[2127052024] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"268.441936ms","start":"2026-03-25T07:30:25.496356Z","end":"2026-03-25T07:30:25.764798Z","steps":["trace[2127052024] 'process raft request' (duration: 268.316083ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:25.765037Z","caller":"traceutil/trace.go:171","msg":"trace[1389068850] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"269.997982ms","start":"2026-03-25T07:30:25.494929Z","end":"2026-03-25T07:30:25.764927Z","steps":["trace[1389068850] 'process raft request' (duration: 191.685512ms)","trace[1389068850] 'compare' (duration: 77.919151ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:30:25.765123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.29715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-25T07:30:25.765183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.439121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T07:30:25.76522Z","caller":"traceutil/trace.go:171","msg":"trace[1561130484] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"270.489143ms","start":"2026-03-25T07:30:25.494713Z","end":"2026-03-25T07:30:25.765202Z","steps":["trace[1561130484] 'agreement among raft nodes before linearized reading' (duration: 270.296519ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:25.76527Z","caller":"traceutil/trace.go:171","msg":"trace[1545225145] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:12; }","duration":"232.757679ms","start":"2026-03-25T07:30:25.532502Z","end":"2026-03-25T07:30:25.76526Z","steps":["trace[1545225145] 'agreement among raft nodes before linearized reading' (duration: 232.39133ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:25.771221Z","caller":"traceutil/trace.go:171","msg":"trace[1723784828] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"215.495267ms","start":"2026-03-25T07:30:25.555708Z","end":"2026-03-25T07:30:25.771204Z","steps":["trace[1723784828] 'process raft request' (duration: 215.162999ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:25.771342Z","caller":"traceutil/trace.go:171","msg":"trace[774038046] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"215.344544ms","start":"2026-03-25T07:30:25.555946Z","end":"2026-03-25T07:30:25.77129Z","steps":["trace[774038046] 'process raft request' (duration: 214.994655ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:25.771405Z","caller":"traceutil/trace.go:171","msg":"trace[1443412744] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"215.394036ms","start":"2026-03-25T07:30:25.556Z","end":"2026-03-25T07:30:25.771394Z","steps":["trace[1443412744] 'process raft request' (duration: 215.205721ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:25.771388Z","caller":"traceutil/trace.go:171","msg":"trace[2074473113] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"215.360703ms","start":"2026-03-25T07:30:25.555965Z","end":"2026-03-25T07:30:25.771326Z","steps":["trace[2074473113] 'process raft request' (duration: 215.2112ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:25.771471Z","caller":"traceutil/trace.go:171","msg":"trace[619982699] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"210.115981ms","start":"2026-03-25T07:30:25.561308Z","end":"2026-03-25T07:30:25.771424Z","steps":["trace[619982699] 'process raft request' (duration: 209.975776ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:25.771614Z","caller":"traceutil/trace.go:171","msg":"trace[539536357] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"214.656458ms","start":"2026-03-25T07:30:25.55695Z","end":"2026-03-25T07:30:25.771606Z","steps":["trace[539536357] 'process raft request' (duration: 214.296599ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:25.77171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.567506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T07:30:25.771757Z","caller":"traceutil/trace.go:171","msg":"trace[1380409630] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:19; }","duration":"102.667678ms","start":"2026-03-25T07:30:25.669076Z","end":"2026-03-25T07:30:25.771744Z","steps":["trace[1380409630] 'agreement among raft nodes before linearized reading' (duration: 102.432293ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:25.771365Z","caller":"traceutil/trace.go:171","msg":"trace[790598336] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"215.403486ms","start":"2026-03-25T07:30:25.555944Z","end":"2026-03-25T07:30:25.771347Z","steps":["trace[790598336] 'process raft request' (duration: 215.025186ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:26.495402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.627634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-03-25T07:30:26.495551Z","caller":"traceutil/trace.go:171","msg":"trace[1448891689] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"303.869102ms","start":"2026-03-25T07:30:26.191658Z","end":"2026-03-25T07:30:26.495527Z","steps":["trace[1448891689] 'process raft request' (duration: 99.071971ms)","trace[1448891689] 'compare' (duration: 202.651511ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:30:26.495641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:26.19164Z","time spent":"303.956365ms","remote":"127.0.0.1:33474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1059,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:30:26.50362Z","caller":"traceutil/trace.go:171","msg":"trace[1886741510] linearizableReadLoop","detail":"{readStateIndex:53; appliedIndex:52; }","duration":"123.098648ms","start":"2026-03-25T07:30:26.380477Z","end":"2026-03-25T07:30:26.503576Z","steps":["trace[1886741510] 'read index received' (duration: 123.079147ms)","trace[1886741510] 'applied index is now lower than readState.Index' (duration: 16.511µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:30:26.503692Z","caller":"traceutil/trace.go:171","msg":"trace[1301965859] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"280.756158ms","start":"2026-03-25T07:30:26.222928Z","end":"2026-03-25T07:30:26.503684Z","steps":["trace[1301965859] 'process raft request' (duration: 280.570694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:26.503758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.276022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T07:30:26.503824Z","caller":"traceutil/trace.go:171","msg":"trace[1909935492] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:49; }","duration":"123.367494ms","start":"2026-03-25T07:30:26.380425Z","end":"2026-03-25T07:30:26.503793Z","steps":["trace[1909935492] 'agreement among raft nodes before linearized reading' (duration: 123.258831ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:26.508293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.4179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T07:30:26.508345Z","caller":"traceutil/trace.go:171","msg":"trace[413848647] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:50; }","duration":"127.501832ms","start":"2026-03-25T07:30:26.380831Z","end":"2026-03-25T07:30:26.508333Z","steps":["trace[413848647] 'agreement among raft nodes before linearized reading' (duration: 127.40388ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:29.354299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.256987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:30:29.354389Z","caller":"traceutil/trace.go:171","msg":"trace[401883864] linearizableReadLoop","detail":"{readStateIndex:232; appliedIndex:231; }","duration":"590.615259ms","start":"2026-03-25T07:30:28.763759Z","end":"2026-03-25T07:30:29.354375Z","steps":["trace[401883864] 'read index received' (duration: 40.85µs)","trace[401883864] 'applied index is now lower than readState.Index' (duration: 590.573639ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:30:29.354467Z","caller":"traceutil/trace.go:171","msg":"trace[1406620667] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"834.457848ms","start":"2026-03-25T07:30:28.519998Z","end":"2026-03-25T07:30:29.354456Z","steps":["trace[1406620667] 'process raft request' (duration: 68.986825ms)","trace[1406620667] 'compare' (duration: 765.118334ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:30:29.354507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:28.519967Z","time spent":"834.52009ms","remote":"127.0.0.1:33258","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":209,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:30:29.354654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.813929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-03-25T07:30:29.354753Z","caller":"traceutil/trace.go:171","msg":"trace[320161808] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:226; }","duration":"387.932662ms","start":"2026-03-25T07:30:28.966804Z","end":"2026-03-25T07:30:29.354737Z","steps":["trace[320161808] 'agreement among raft nodes before linearized reading' (duration: 387.718887ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:29.354686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.917416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T07:30:29.354786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:28.966753Z","time spent":"388.025544ms","remote":"127.0.0.1:33232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3732,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-03-25T07:30:29.354787Z","caller":"traceutil/trace.go:171","msg":"trace[716780510] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:226; }","duration":"591.050249ms","start":"2026-03-25T07:30:28.763728Z","end":"2026-03-25T07:30:29.354778Z","steps":["trace[716780510] 'agreement among raft nodes before linearized reading' (duration: 590.875805ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:29.354825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:28.763712Z","time spent":"591.103791ms","remote":"127.0.0.1:33310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-25T07:30:35.004591Z","caller":"traceutil/trace.go:171","msg":"trace[710760599] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"102.291247ms","start":"2026-03-25T07:30:34.902282Z","end":"2026-03-25T07:30:35.004573Z","steps":["trace[710760599] 'process raft request' (duration: 102.172144ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:35.98841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.250942ms","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-25T07:30:35.9885Z","caller":"traceutil/trace.go:171","msg":"trace[728616285] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:354; }","duration":"222.390155ms","start":"2026-03-25T07:30:35.766087Z","end":"2026-03-25T07:30:35.988478Z","steps":["trace[728616285] 'range keys from in-memory index tree' (duration: 222.077678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:35.988692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.609531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:30:35.98877Z","caller":"traceutil/trace.go:171","msg":"trace[1193965562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:354; }","duration":"419.728493ms","start":"2026-03-25T07:30:35.569023Z","end":"2026-03-25T07:30:35.988751Z","steps":["trace[1193965562] 'range keys from in-memory index tree' (duration: 419.532609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:35.98881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:35.569005Z","time spent":"419.793145ms","remote":"127.0.0.1:33088","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-25T07:30:36.120929Z","caller":"traceutil/trace.go:171","msg":"trace[1152111239] linearizableReadLoop","detail":"{readStateIndex:368; appliedIndex:367; }","duration":"128.004677ms","start":"2026-03-25T07:30:35.992891Z","end":"2026-03-25T07:30:36.120896Z","steps":["trace[1152111239] 'read index received' (duration: 127.887345ms)","trace[1152111239] 'applied index is now lower than readState.Index' (duration: 116.222µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:30:36.120942Z","caller":"traceutil/trace.go:171","msg":"trace[403320269] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"128.55559ms","start":"2026-03-25T07:30:35.992357Z","end":"2026-03-25T07:30:36.120912Z","steps":["trace[403320269] 'process raft request' (duration: 128.360426ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:36.121133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.220272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-03-25T07:30:36.121175Z","caller":"traceutil/trace.go:171","msg":"trace[818419837] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:355; }","duration":"128.280464ms","start":"2026-03-25T07:30:35.992883Z","end":"2026-03-25T07:30:36.121163Z","steps":["trace[818419837] 'agreement among raft nodes before linearized reading' (duration: 128.143981ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:41.687499Z","caller":"traceutil/trace.go:171","msg":"trace[1403240741] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"195.830432ms","start":"2026-03-25T07:30:41.491644Z","end":"2026-03-25T07:30:41.687474Z","steps":["trace[1403240741] 'process raft request' (duration: 195.647228ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:44.57133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.829206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:30:44.571435Z","caller":"traceutil/trace.go:171","msg":"trace[1927152268] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"200.516063ms","start":"2026-03-25T07:30:44.370905Z","end":"2026-03-25T07:30:44.571421Z","steps":["trace[1927152268] 'process raft request' (duration: 85.576064ms)","trace[1927152268] 'compare' (duration: 114.696353ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:30:44.61997Z","caller":"traceutil/trace.go:171","msg":"trace[326124008] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"245.389792ms","start":"2026-03-25T07:30:44.374556Z","end":"2026-03-25T07:30:44.619946Z","steps":["trace[326124008] 'process raft request' (duration: 245.351951ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:44.620266Z","caller":"traceutil/trace.go:171","msg":"trace[364972742] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"246.251889ms","start":"2026-03-25T07:30:44.374001Z","end":"2026-03-25T07:30:44.620253Z","steps":["trace[364972742] 'process raft request' (duration: 245.869801ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:44.619943Z","caller":"traceutil/trace.go:171","msg":"trace[2049288631] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"248.924203ms","start":"2026-03-25T07:30:44.370975Z","end":"2026-03-25T07:30:44.619899Z","steps":["trace[2049288631] 'process raft request' (duration: 248.718909ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:44.620486Z","caller":"traceutil/trace.go:171","msg":"trace[1759054912] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"247.651867ms","start":"2026-03-25T07:30:44.372644Z","end":"2026-03-25T07:30:44.620296Z","steps":["trace[1759054912] 'process raft request' (duration: 247.184267ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:44.752603Z","caller":"traceutil/trace.go:171","msg":"trace[1079277774] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"127.935351ms","start":"2026-03-25T07:30:44.624607Z","end":"2026-03-25T07:30:44.752542Z","steps":["trace[1079277774] 'process raft request' (duration: 121.928359ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:44.752622Z","caller":"traceutil/trace.go:171","msg":"trace[1052777439] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"126.124555ms","start":"2026-03-25T07:30:44.626474Z","end":"2026-03-25T07:30:44.752599Z","steps":["trace[1052777439] 'process raft request' (duration: 125.974622ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:44.752751Z","caller":"traceutil/trace.go:171","msg":"trace[1980216721] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"125.071783ms","start":"2026-03-25T07:30:44.62764Z","end":"2026-03-25T07:30:44.752712Z","steps":["trace[1980216721] 'process raft request' (duration: 124.870439ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:44.752811Z","caller":"traceutil/trace.go:171","msg":"trace[1011564960] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"123.790947ms","start":"2026-03-25T07:30:44.628998Z","end":"2026-03-25T07:30:44.752789Z","steps":["trace[1011564960] 'process raft request' (duration: 123.552253ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:46.925121Z","caller":"traceutil/trace.go:171","msg":"trace[1777467883] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:435; }","duration":"279.887323ms","start":"2026-03-25T07:30:46.645209Z","end":"2026-03-25T07:30:46.925096Z","steps":["trace[1777467883] 'read index received' (duration: 279.640148ms)","trace[1777467883] 'applied index is now lower than readState.Index' (duration: 245.925µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:30:46.925253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.027176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:30:46.925302Z","caller":"traceutil/trace.go:171","msg":"trace[982626149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"280.131418ms","start":"2026-03-25T07:30:46.64515Z","end":"2026-03-25T07:30:46.925281Z","steps":["trace[982626149] 'agreement among raft nodes before linearized reading' (duration: 280.043376ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:46.925298Z","caller":"traceutil/trace.go:171","msg":"trace[877328483] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"298.674148ms","start":"2026-03-25T07:30:46.626569Z","end":"2026-03-25T07:30:46.925243Z","steps":["trace[877328483] 'process raft request' (duration: 298.363222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:50.699079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.310418ms","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-25T07:30:50.699237Z","caller":"traceutil/trace.go:171","msg":"trace[243741237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:427; }","duration":"509.533093ms","start":"2026-03-25T07:30:50.189688Z","end":"2026-03-25T07:30:50.699221Z","steps":["trace[243741237] 'range keys from in-memory index tree' (duration: 509.226897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:50.699286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:50.189601Z","time spent":"509.659516ms","remote":"127.0.0.1:33310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T07:30:50.699079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.076909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-25T07:30:50.699396Z","caller":"traceutil/trace.go:171","msg":"trace[946091744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:427; }","duration":"516.506677ms","start":"2026-03-25T07:30:50.182882Z","end":"2026-03-25T07:30:50.699389Z","steps":["trace[946091744] 'range keys from in-memory index tree' (duration: 515.956807ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:50.699418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:50.182861Z","time spent":"516.550528ms","remote":"127.0.0.1:33310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-25T07:30:50.920245Z","caller":"traceutil/trace.go:171","msg":"trace[840301797] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"216.201845ms","start":"2026-03-25T07:30:50.704019Z","end":"2026-03-25T07:30:50.920221Z","steps":["trace[840301797] 'process raft request' (duration: 216.023281ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:50.94021Z","caller":"traceutil/trace.go:171","msg":"trace[959716108] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"235.726423ms","start":"2026-03-25T07:30:50.70445Z","end":"2026-03-25T07:30:50.940176Z","steps":["trace[959716108] 'process raft request' (duration: 235.507719ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:53.088752Z","caller":"traceutil/trace.go:171","msg":"trace[491028522] linearizableReadLoop","detail":"{readStateIndex:450; appliedIndex:449; }","duration":"145.088566ms","start":"2026-03-25T07:30:52.943638Z","end":"2026-03-25T07:30:53.088727Z","steps":["trace[491028522] 'read index received' (duration: 144.860091ms)","trace[491028522] 'applied index is now lower than readState.Index' (duration: 227.305µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:30:53.089052Z","caller":"traceutil/trace.go:171","msg":"trace[961327158] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"160.381327ms","start":"2026-03-25T07:30:52.928661Z","end":"2026-03-25T07:30:53.089042Z","steps":["trace[961327158] 'process raft request' (duration: 159.939018ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:53.089216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.573064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-25T07:30:53.089254Z","caller":"traceutil/trace.go:171","msg":"trace[1564579078] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:432; }","duration":"145.691936ms","start":"2026-03-25T07:30:52.943549Z","end":"2026-03-25T07:30:53.089241Z","steps":["trace[1564579078] 'agreement among raft nodes before linearized reading' (duration: 145.622505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:53.089401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.714423ms","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-25T07:30:53.089434Z","caller":"traceutil/trace.go:171","msg":"trace[697084859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:432; }","duration":"117.787225ms","start":"2026-03-25T07:30:52.971639Z","end":"2026-03-25T07:30:53.089426Z","steps":["trace[697084859] 'agreement among raft nodes before linearized reading' (duration: 117.731694ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:53.379549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.450231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:30:53.379665Z","caller":"traceutil/trace.go:171","msg":"trace[17272393] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"286.555736ms","start":"2026-03-25T07:30:53.093092Z","end":"2026-03-25T07:30:53.379648Z","steps":["trace[17272393] 'process raft request' (duration: 80.936682ms)","trace[17272393] 'compare' (duration: 205.329808ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:30:53.380165Z","caller":"traceutil/trace.go:171","msg":"trace[335276368] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"286.694268ms","start":"2026-03-25T07:30:53.093454Z","end":"2026-03-25T07:30:53.380148Z","steps":["trace[335276368] 'process raft request' (duration: 286.618617ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:56.427072Z","caller":"traceutil/trace.go:171","msg":"trace[755684679] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"101.95741ms","start":"2026-03-25T07:30:56.325074Z","end":"2026-03-25T07:30:56.427032Z","steps":["trace[755684679] 'process raft request' (duration: 101.741876ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:57.581853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.187868ms","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-25T07:30:57.58208Z","caller":"traceutil/trace.go:171","msg":"trace[334138530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"123.444142ms","start":"2026-03-25T07:30:57.458613Z","end":"2026-03-25T07:30:57.582057Z","steps":["trace[334138530] 'range keys from in-memory index tree' (duration: 123.094206ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:57.581924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.776647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.145\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-25T07:30:57.582249Z","caller":"traceutil/trace.go:171","msg":"trace[2041920936] range","detail":"{range_begin:/registry/masterleases/199.19.213.145; range_end:; response_count:1; response_revision:475; }","duration":"125.151914ms","start":"2026-03-25T07:30:57.457083Z","end":"2026-03-25T07:30:57.582234Z","steps":["trace[2041920936] 'range keys from in-memory index tree' (duration: 124.672755ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:57.581977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.407942ms","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-25T07:30:57.582402Z","caller":"traceutil/trace.go:171","msg":"trace[74691674] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:475; }","duration":"110.83982ms","start":"2026-03-25T07:30:57.471543Z","end":"2026-03-25T07:30:57.582383Z","steps":["trace[74691674] 'range keys from in-memory index tree' (duration: 110.3294ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:59.772652Z","caller":"traceutil/trace.go:171","msg":"trace[1047944855] linearizableReadLoop","detail":"{readStateIndex:499; appliedIndex:498; }","duration":"173.765195ms","start":"2026-03-25T07:30:59.598869Z","end":"2026-03-25T07:30:59.772634Z","steps":["trace[1047944855] 'read index received' (duration: 173.691923ms)","trace[1047944855] 'applied index is now lower than readState.Index' (duration: 70.742µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:30:59.772913Z","caller":"traceutil/trace.go:171","msg":"trace[957722593] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"578.704788ms","start":"2026-03-25T07:30:59.194196Z","end":"2026-03-25T07:30:59.772901Z","steps":["trace[957722593] 'process raft request' (duration: 578.290921ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:59.773004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:59.194176Z","time spent":"578.769149ms","remote":"127.0.0.1:33310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:30:59.77306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-25T07:30:59.773143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.274227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:30:59.773149Z","caller":"traceutil/trace.go:171","msg":"trace[1921351638] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:480; }","duration":"172.002242ms","start":"2026-03-25T07:30:59.601125Z","end":"2026-03-25T07:30:59.773127Z","steps":["trace[1921351638] 'agreement among raft nodes before linearized reading' (duration: 171.83508ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:59.773184Z","caller":"traceutil/trace.go:171","msg":"trace[2024368331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:480; }","duration":"129.365428ms","start":"2026-03-25T07:30:59.643803Z","end":"2026-03-25T07:30:59.773169Z","steps":["trace[2024368331] 'agreement among raft nodes before linearized reading' (duration: 129.319917ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:59.773072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.179332ms","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-25T07:30:59.773223Z","caller":"traceutil/trace.go:171","msg":"trace[551598728] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:480; }","duration":"174.383126ms","start":"2026-03-25T07:30:59.598829Z","end":"2026-03-25T07:30:59.773213Z","steps":["trace[551598728] 'agreement among raft nodes before linearized reading' (duration: 174.138432ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:31:07.855757Z","caller":"traceutil/trace.go:171","msg":"trace[968462337] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"398.581261ms","start":"2026-03-25T07:31:07.457151Z","end":"2026-03-25T07:31:07.855732Z","steps":["trace[968462337] 'read index received' (duration: 398.454499ms)","trace[968462337] 'applied index is now lower than readState.Index' (duration: 125.962µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:31:07.85585Z","caller":"traceutil/trace.go:171","msg":"trace[325624238] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"862.310147ms","start":"2026-03-25T07:31:06.993524Z","end":"2026-03-25T07:31:07.855834Z","steps":["trace[325624238] 'process raft request' (duration: 862.097564ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:07.855925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.727145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.145\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-25T07:31:07.855951Z","caller":"traceutil/trace.go:171","msg":"trace[165636554] range","detail":"{range_begin:/registry/masterleases/199.19.213.145; range_end:; response_count:1; response_revision:524; }","duration":"398.853226ms","start":"2026-03-25T07:31:07.457089Z","end":"2026-03-25T07:31:07.855943Z","steps":["trace[165636554] 'agreement among raft nodes before linearized reading' (duration: 398.748954ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:07.855971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:31:07.457069Z","time spent":"398.897067ms","remote":"127.0.0.1:33120","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.145\" "} {"level":"warn","ts":"2026-03-25T07:31:07.855969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:31:06.993489Z","time spent":"862.4298ms","remote":"127.0.0.1:33238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:31:07.85607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.923469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:31:07.856102Z","caller":"traceutil/trace.go:171","msg":"trace[969141016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:524; }","duration":"214.984361ms","start":"2026-03-25T07:31:07.64111Z","end":"2026-03-25T07:31:07.856095Z","steps":["trace[969141016] 'agreement among raft nodes before linearized reading' (duration: 214.93426ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:08.359984Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":141754690807494052,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:31:08.780219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.355515ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:31:08.780317Z","caller":"traceutil/trace.go:171","msg":"trace[188405694] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:524; }","duration":"377.462297ms","start":"2026-03-25T07:31:08.40284Z","end":"2026-03-25T07:31:08.780303Z","steps":["trace[188405694] 'range keys from in-memory index tree' (duration: 377.341565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:08.78078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.257944ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-03-25T07:31:08.781017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:31:07.857276Z","time spent":"923.736819ms","remote":"127.0.0.1:33120","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-25T07:31:08.781163Z","caller":"traceutil/trace.go:171","msg":"trace[559098301] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:544; }","duration":"922.138322ms","start":"2026-03-25T07:31:07.858997Z","end":"2026-03-25T07:31:08.781136Z","steps":["trace[559098301] 'read index received' (duration: 592.641595ms)","trace[559098301] 'applied index is now lower than readState.Index' (duration: 329.495517ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:31:08.781337Z","caller":"traceutil/trace.go:171","msg":"trace[1673334680] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"920.125837ms","start":"2026-03-25T07:31:07.861192Z","end":"2026-03-25T07:31:08.781318Z","steps":["trace[1673334680] 'process raft request' (duration: 919.887303ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:08.78142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"904.261744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-25T07:31:08.781499Z","caller":"traceutil/trace.go:171","msg":"trace[1421436567] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"904.348146ms","start":"2026-03-25T07:31:07.877116Z","end":"2026-03-25T07:31:08.781464Z","steps":["trace[1421436567] 'agreement among raft nodes before linearized reading' (duration: 904.264235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:08.781335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.322785ms","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-25T07:31:08.78156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:31:07.877098Z","time spent":"904.447997ms","remote":"127.0.0.1:33310","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-25T07:31:08.781415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.625288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:31:08.781614Z","caller":"traceutil/trace.go:171","msg":"trace[638192946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"922.635851ms","start":"2026-03-25T07:31:07.858953Z","end":"2026-03-25T07:31:08.781589Z","steps":["trace[638192946] 'agreement among raft nodes before linearized reading' (duration: 922.318966ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:08.781687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:31:07.858926Z","time spent":"922.741863ms","remote":"127.0.0.1:33310","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-25T07:31:08.781685Z","caller":"traceutil/trace.go:171","msg":"trace[681074467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"136.890942ms","start":"2026-03-25T07:31:08.644749Z","end":"2026-03-25T07:31:08.78164Z","steps":["trace[681074467] 'agreement among raft nodes before linearized reading' (duration: 136.638698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:08.781841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:31:07.861175Z","time spent":"920.271621ms","remote":"127.0.0.1:33310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:31:08.78117Z","caller":"traceutil/trace.go:171","msg":"trace[61703109] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"921.615803ms","start":"2026-03-25T07:31:07.85953Z","end":"2026-03-25T07:31:08.781146Z","steps":["trace[61703109] 'process raft request' (duration: 921.388149ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:08.782873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:31:07.859506Z","time spent":"923.21808ms","remote":"127.0.0.1:33310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:31:09.326682Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":141754690807494062,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-25T07:31:09.494612Z","caller":"traceutil/trace.go:171","msg":"trace[1134797795] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"709.661918ms","start":"2026-03-25T07:31:08.784924Z","end":"2026-03-25T07:31:09.494586Z","steps":["trace[1134797795] 'process raft request' (duration: 667.130595ms)","trace[1134797795] 'compare' (duration: 42.343659ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:31:09.494761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:31:08.784906Z","time spent":"709.78209ms","remote":"127.0.0.1:33310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:31:09.494749Z","caller":"traceutil/trace.go:171","msg":"trace[141458927] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"709.030897ms","start":"2026-03-25T07:31:08.785683Z","end":"2026-03-25T07:31:09.494714Z","steps":["trace[141458927] 'process raft request' (duration: 708.818773ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:09.494933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:31:08.785675Z","time spent":"709.16079ms","remote":"127.0.0.1:33310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:31:09.495165Z","caller":"traceutil/trace.go:171","msg":"trace[246216995] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:548; }","duration":"669.011793ms","start":"2026-03-25T07:31:08.826136Z","end":"2026-03-25T07:31:09.495147Z","steps":["trace[246216995] 'read index received' (duration: 625.873859ms)","trace[246216995] 'applied index is now lower than readState.Index' (duration: 43.136204ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:31:09.495475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"669.339678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-03-25T07:31:09.495515Z","caller":"traceutil/trace.go:171","msg":"trace[676310780] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:529; }","duration":"669.401839ms","start":"2026-03-25T07:31:08.826102Z","end":"2026-03-25T07:31:09.495504Z","steps":["trace[676310780] 'agreement among raft nodes before linearized reading' (duration: 669.133084ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:09.49555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:31:08.826043Z","time spent":"669.500341ms","remote":"127.0.0.1:33226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"info","ts":"2026-03-25T07:31:12.190187Z","caller":"traceutil/trace.go:171","msg":"trace[75340976] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"104.84511ms","start":"2026-03-25T07:31:12.085273Z","end":"2026-03-25T07:31:12.190118Z","steps":["trace[75340976] 'compare' (duration: 95.480243ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:31:21.412253Z","caller":"traceutil/trace.go:171","msg":"trace[2144690433] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"201.915107ms","start":"2026-03-25T07:31:21.210323Z","end":"2026-03-25T07:31:21.412238Z","steps":["trace[2144690433] 'process raft request' (duration: 201.828316ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:31:21.41771Z","caller":"traceutil/trace.go:171","msg":"trace[1284252998] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"203.93986ms","start":"2026-03-25T07:31:21.213745Z","end":"2026-03-25T07:31:21.417685Z","steps":["trace[1284252998] 'process raft request' (duration: 203.826947ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:31:37.242546Z","caller":"traceutil/trace.go:171","msg":"trace[499571820] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"104.714619ms","start":"2026-03-25T07:31:37.137804Z","end":"2026-03-25T07:31:37.242519Z","steps":["trace[499571820] 'process raft request' (duration: 57.574891ms)","trace[499571820] 'compare' (duration: 47.008515ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:32:14.080295Z","caller":"traceutil/trace.go:171","msg":"trace[585560921] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"124.41529ms","start":"2026-03-25T07:32:13.955859Z","end":"2026-03-25T07:32:14.080274Z","steps":["trace[585560921] 'process raft request' (duration: 124.282147ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:15.390081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.704014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-25T07:32:15.390173Z","caller":"traceutil/trace.go:171","msg":"trace[1578783964] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:862; }","duration":"101.848377ms","start":"2026-03-25T07:32:15.288307Z","end":"2026-03-25T07:32:15.390155Z","steps":["trace[1578783964] 'range keys from in-memory index tree' (duration: 101.510142ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:32:16.300187Z","caller":"traceutil/trace.go:171","msg":"trace[1006567293] linearizableReadLoop","detail":"{readStateIndex:899; appliedIndex:898; }","duration":"159.265877ms","start":"2026-03-25T07:32:16.140891Z","end":"2026-03-25T07:32:16.300157Z","steps":["trace[1006567293] 'read index received' (duration: 159.035394ms)","trace[1006567293] 'applied index is now lower than readState.Index' (duration: 229.183µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:32:16.300577Z","caller":"traceutil/trace.go:171","msg":"trace[771726138] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"211.88114ms","start":"2026-03-25T07:32:16.088681Z","end":"2026-03-25T07:32:16.300562Z","steps":["trace[771726138] 'process raft request' (duration: 211.323563ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:16.300951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.983466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-03-25T07:32:16.30105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.240569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:32:16.301065Z","caller":"traceutil/trace.go:171","msg":"trace[721453383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:864; }","duration":"160.120777ms","start":"2026-03-25T07:32:16.140919Z","end":"2026-03-25T07:32:16.301039Z","steps":["trace[721453383] 'agreement among raft nodes before linearized reading' (duration: 159.672712ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:32:16.301123Z","caller":"traceutil/trace.go:171","msg":"trace[114671979] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:864; }","duration":"122.354551ms","start":"2026-03-25T07:32:16.178748Z","end":"2026-03-25T07:32:16.301102Z","steps":["trace[114671979] 'agreement among raft nodes before linearized reading' (duration: 122.23014ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:16.300958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.041636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-25T07:32:16.301392Z","caller":"traceutil/trace.go:171","msg":"trace[398615439] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:864; }","duration":"160.517732ms","start":"2026-03-25T07:32:16.140862Z","end":"2026-03-25T07:32:16.30138Z","steps":["trace[398615439] 'agreement among raft nodes before linearized reading' (duration: 159.867765ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:32:43.642755Z","caller":"traceutil/trace.go:171","msg":"trace[1494229014] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"292.014101ms","start":"2026-03-25T07:32:43.35071Z","end":"2026-03-25T07:32:43.642724Z","steps":["trace[1494229014] 'process raft request' (duration: 291.835669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:51.905466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.732495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver\" ","response":"range_response_count:1 size:682"} {"level":"info","ts":"2026-03-25T07:32:51.905541Z","caller":"traceutil/trace.go:171","msg":"trace[674649156] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kubelet-serving-approver; range_end:; response_count:1; response_revision:1007; }","duration":"116.855626ms","start":"2026-03-25T07:32:51.788671Z","end":"2026-03-25T07:32:51.905527Z","steps":["trace[674649156] 'range keys from in-memory index tree' (duration: 116.672674ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:51.905585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.588076ms","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-25T07:32:51.905673Z","caller":"traceutil/trace.go:171","msg":"trace[669791202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1007; }","duration":"118.681997ms","start":"2026-03-25T07:32:51.786962Z","end":"2026-03-25T07:32:51.905644Z","steps":["trace[669791202] 'range keys from in-memory index tree' (duration: 118.426744ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:32:57.956463Z","caller":"traceutil/trace.go:171","msg":"trace[922928669] linearizableReadLoop","detail":"{readStateIndex:1070; appliedIndex:1069; }","duration":"156.250604ms","start":"2026-03-25T07:32:57.80019Z","end":"2026-03-25T07:32:57.956441Z","steps":["trace[922928669] 'read index received' (duration: 156.154373ms)","trace[922928669] 'applied index is now lower than readState.Index' (duration: 95.031µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:32:57.956609Z","caller":"traceutil/trace.go:171","msg":"trace[631498049] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"171.928793ms","start":"2026-03-25T07:32:57.784656Z","end":"2026-03-25T07:32:57.956585Z","steps":["trace[631498049] 'process raft request' (duration: 171.608189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:57.956676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.464247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-scheduler\" ","response":"range_response_count:1 size:1381"} {"level":"warn","ts":"2026-03-25T07:32:57.956805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.06239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pvc-protection-controller\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-03-25T07:32:57.956871Z","caller":"traceutil/trace.go:171","msg":"trace[1994440568] range","detail":"{range_begin:/registry/clusterroles/system:kube-scheduler; range_end:; response_count:1; response_revision:1024; }","duration":"156.696379ms","start":"2026-03-25T07:32:57.800157Z","end":"2026-03-25T07:32:57.956853Z","steps":["trace[1994440568] 'agreement among raft nodes before linearized reading' (duration: 156.443736ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:32:57.956915Z","caller":"traceutil/trace.go:171","msg":"trace[890562977] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pvc-protection-controller; range_end:; response_count:1; response_revision:1024; }","duration":"148.205211ms","start":"2026-03-25T07:32:57.808686Z","end":"2026-03-25T07:32:57.956891Z","steps":["trace[890562977] 'agreement among raft nodes before linearized reading' (duration: 147.965899ms)"],"step_count":1}