{"level":"warn","ts":"2026-03-01T13:53:27.541798Z","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-01T13:53:27.541868Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.195:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.195:2380","--initial-cluster=instance=https://162.253.55.195:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.195:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.195: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-01T13:53:27.541922Z","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-01T13:53:27.541933Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-03-01T13:53:27.541952Z","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-01T13:53:27.542537Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"]} {"level":"info","ts":"2026-03-01T13:53:27.542609Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.195: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-01T13:53:27.766605Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"223.797675ms"} {"level":"info","ts":"2026-03-01T13:53:27.992201Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b66d12fd59101739","cluster-id":"a64a525934ce8379"} {"level":"info","ts":"2026-03-01T13:53:27.992356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=()"} {"level":"info","ts":"2026-03-01T13:53:27.992397Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 0"} {"level":"info","ts":"2026-03-01T13:53:27.992423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b66d12fd59101739 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-01T13:53:27.992437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became follower at term 1"} {"level":"info","ts":"2026-03-01T13:53:27.992519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"warn","ts":"2026-03-01T13:53:28.055326Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-01T13:53:28.128759Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-01T13:53:28.13993Z","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-01T13:53:28.160582Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b66d12fd59101739","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-01T13:53:28.161Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b66d12fd59101739","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-01T13:53:28.161048Z","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-01T13:53:28.161306Z","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-01T13:53:28.161436Z","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-01T13:53:28.166968Z","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-01T13:53:28.167095Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-03-01T13:53:28.167221Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.195:2380"} {"level":"info","ts":"2026-03-01T13:53:28.16737Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b66d12fd59101739","initial-advertise-peer-urls":["https://162.253.55.195:2380"],"listen-peer-urls":["https://162.253.55.195:2380"],"advertise-client-urls":["https://162.253.55.195:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.195:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-01T13:53:28.167408Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-01T13:53:28.195736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 switched to configuration voters=(13145183766694598457)"} {"level":"info","ts":"2026-03-01T13:53:28.195961Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","added-peer-id":"b66d12fd59101739","added-peer-peer-urls":["https://162.253.55.195:2380"]} {"level":"info","ts":"2026-03-01T13:53:28.396572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 is starting a new election at term 1"} {"level":"info","ts":"2026-03-01T13:53:28.396639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-01T13:53:28.396661Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgPreVoteResp from b66d12fd59101739 at term 1"} {"level":"info","ts":"2026-03-01T13:53:28.396679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became candidate at term 2"} {"level":"info","ts":"2026-03-01T13:53:28.396693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 received MsgVoteResp from b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-03-01T13:53:28.396712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b66d12fd59101739 became leader at term 2"} {"level":"info","ts":"2026-03-01T13:53:28.396732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b66d12fd59101739 elected leader b66d12fd59101739 at term 2"} {"level":"info","ts":"2026-03-01T13:53:28.402055Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T13:53:28.442058Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-01T13:53:28.44207Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b66d12fd59101739","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.195:2379]}","request-path":"/0/members/b66d12fd59101739/attributes","cluster-id":"a64a525934ce8379","publish-timeout":"7s"} {"level":"info","ts":"2026-03-01T13:53:28.442101Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-01T13:53:28.442537Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-01T13:53:28.442585Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-01T13:53:28.44561Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-01T13:53:28.446184Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a64a525934ce8379","local-member-id":"b66d12fd59101739","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T13:53:28.446316Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T13:53:28.446351Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-01T13:53:28.446742Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.195:2379"} {"level":"info","ts":"2026-03-01T13:53:29.999337Z","caller":"traceutil/trace.go:171","msg":"trace[1923261687] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"136.278594ms","start":"2026-03-01T13:53:29.863019Z","end":"2026-03-01T13:53:29.999298Z","steps":["trace[1923261687] 'process raft request' (duration: 136.146433ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:29.999379Z","caller":"traceutil/trace.go:171","msg":"trace[2114057406] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"136.405566ms","start":"2026-03-01T13:53:29.862939Z","end":"2026-03-01T13:53:29.999345Z","steps":["trace[2114057406] 'process raft request' (duration: 136.028382ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:29.999472Z","caller":"traceutil/trace.go:171","msg":"trace[460522050] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"123.822172ms","start":"2026-03-01T13:53:29.875597Z","end":"2026-03-01T13:53:29.999419Z","steps":["trace[460522050] 'process raft request' (duration: 123.726431ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:29.999425Z","caller":"traceutil/trace.go:171","msg":"trace[478081262] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"135.76166ms","start":"2026-03-01T13:53:29.863643Z","end":"2026-03-01T13:53:29.999405Z","steps":["trace[478081262] 'process raft request' (duration: 135.550227ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:29.99937Z","caller":"traceutil/trace.go:171","msg":"trace[273355251] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"136.317415ms","start":"2026-03-01T13:53:29.863019Z","end":"2026-03-01T13:53:29.999336Z","steps":["trace[273355251] 'process raft request' (duration: 136.075603ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:29.999648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.77655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.195\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-01T13:53:29.999734Z","caller":"traceutil/trace.go:171","msg":"trace[52932142] range","detail":"{range_begin:/registry/masterleases/162.253.55.195; range_end:; response_count:0; response_revision:11; }","duration":"138.903242ms","start":"2026-03-01T13:53:29.860817Z","end":"2026-03-01T13:53:29.99972Z","steps":["trace[52932142] 'agreement among raft nodes before linearized reading' (duration: 138.69022ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:29.999763Z","caller":"traceutil/trace.go:171","msg":"trace[739620066] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"127.051974ms","start":"2026-03-01T13:53:29.872694Z","end":"2026-03-01T13:53:29.999746Z","steps":["trace[739620066] 'process raft request' (duration: 126.601359ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:29.999815Z","caller":"traceutil/trace.go:171","msg":"trace[1508613183] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"136.021172ms","start":"2026-03-01T13:53:29.863787Z","end":"2026-03-01T13:53:29.999808Z","steps":["trace[1508613183] 'process raft request' (duration: 135.475037ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:29.999915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.302975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-01T13:53:29.999956Z","caller":"traceutil/trace.go:171","msg":"trace[82000565] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"130.358186ms","start":"2026-03-01T13:53:29.869588Z","end":"2026-03-01T13:53:29.999947Z","steps":["trace[82000565] 'agreement among raft nodes before linearized reading' (duration: 130.296355ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:29.999996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.958872ms","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-01T13:53:30.000071Z","caller":"traceutil/trace.go:171","msg":"trace[739792718] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"138.083025ms","start":"2026-03-01T13:53:29.861971Z","end":"2026-03-01T13:53:30.000054Z","steps":["trace[739792718] 'agreement among raft nodes before linearized reading' (duration: 137.958713ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:29.999773Z","caller":"traceutil/trace.go:171","msg":"trace[1059081311] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"136.027063ms","start":"2026-03-01T13:53:29.863732Z","end":"2026-03-01T13:53:29.999759Z","steps":["trace[1059081311] 'process raft request' (duration: 135.505358ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.18043Z","caller":"traceutil/trace.go:171","msg":"trace[1942573838] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:14; }","duration":"177.105167ms","start":"2026-03-01T13:53:30.003296Z","end":"2026-03-01T13:53:30.180401Z","steps":["trace[1942573838] 'read index received' (duration: 166.935477ms)","trace[1942573838] 'applied index is now lower than readState.Index' (duration: 10.16842ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:30.180559Z","caller":"traceutil/trace.go:171","msg":"trace[777538987] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"178.2483ms","start":"2026-03-01T13:53:30.002297Z","end":"2026-03-01T13:53:30.180545Z","steps":["trace[777538987] 'process raft request' (duration: 167.921418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:30.180669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.37165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-01T13:53:30.180727Z","caller":"traceutil/trace.go:171","msg":"trace[20450893] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12; }","duration":"177.725353ms","start":"2026-03-01T13:53:30.002986Z","end":"2026-03-01T13:53:30.180712Z","steps":["trace[20450893] 'agreement among raft nodes before linearized reading' (duration: 177.572792ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.197187Z","caller":"traceutil/trace.go:171","msg":"trace[779801531] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"193.321348ms","start":"2026-03-01T13:53:30.003846Z","end":"2026-03-01T13:53:30.197167Z","steps":["trace[779801531] 'process raft request' (duration: 193.126786ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.197286Z","caller":"traceutil/trace.go:171","msg":"trace[1861912466] transaction","detail":"{read_only:false; number_of_response:0; response_revision:13; }","duration":"191.894294ms","start":"2026-03-01T13:53:30.005375Z","end":"2026-03-01T13:53:30.197269Z","steps":["trace[1861912466] 'process raft request' (duration: 191.712392ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.197398Z","caller":"traceutil/trace.go:171","msg":"trace[1038102242] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"190.58389ms","start":"2026-03-01T13:53:30.006763Z","end":"2026-03-01T13:53:30.197347Z","steps":["trace[1038102242] 'process raft request' (duration: 190.349098ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.197417Z","caller":"traceutil/trace.go:171","msg":"trace[696247671] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"190.51306ms","start":"2026-03-01T13:53:30.006882Z","end":"2026-03-01T13:53:30.197395Z","steps":["trace[696247671] 'process raft request' (duration: 190.330948ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.197427Z","caller":"traceutil/trace.go:171","msg":"trace[2081052042] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"190.47944ms","start":"2026-03-01T13:53:30.006829Z","end":"2026-03-01T13:53:30.197309Z","steps":["trace[2081052042] 'process raft request' (duration: 190.346119ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.197462Z","caller":"traceutil/trace.go:171","msg":"trace[1802604204] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"190.5446ms","start":"2026-03-01T13:53:30.006909Z","end":"2026-03-01T13:53:30.197454Z","steps":["trace[1802604204] 'process raft request' (duration: 190.330948ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.197574Z","caller":"traceutil/trace.go:171","msg":"trace[1177787827] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"181.45795ms","start":"2026-03-01T13:53:30.01611Z","end":"2026-03-01T13:53:30.197568Z","steps":["trace[1177787827] 'process raft request' (duration: 181.327239ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.19761Z","caller":"traceutil/trace.go:171","msg":"trace[918103700] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"187.898524ms","start":"2026-03-01T13:53:30.009689Z","end":"2026-03-01T13:53:30.197588Z","steps":["trace[918103700] 'process raft request' (duration: 187.582781ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.197619Z","caller":"traceutil/trace.go:171","msg":"trace[1476403044] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"178.645283ms","start":"2026-03-01T13:53:30.018945Z","end":"2026-03-01T13:53:30.197591Z","steps":["trace[1476403044] 'process raft request' (duration: 178.542072ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:30.197694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.979652ms","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-01T13:53:30.197719Z","caller":"traceutil/trace.go:171","msg":"trace[395956047] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:20; }","duration":"136.018852ms","start":"2026-03-01T13:53:30.061694Z","end":"2026-03-01T13:53:30.197713Z","steps":["trace[395956047] 'agreement among raft nodes before linearized reading' (duration: 135.979871ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:30.197658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.294528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-5v6jp\" ","response":"range_response_count:1 size:857"} {"level":"warn","ts":"2026-03-01T13:53:30.197792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.032972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-01T13:53:30.197847Z","caller":"traceutil/trace.go:171","msg":"trace[435366141] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:20; }","duration":"137.126913ms","start":"2026-03-01T13:53:30.060707Z","end":"2026-03-01T13:53:30.197834Z","steps":["trace[435366141] 'agreement among raft nodes before linearized reading' (duration: 137.030342ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.197806Z","caller":"traceutil/trace.go:171","msg":"trace[1511530923] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-5v6jp; range_end:; response_count:1; response_revision:20; }","duration":"192.46227ms","start":"2026-03-01T13:53:30.005334Z","end":"2026-03-01T13:53:30.197796Z","steps":["trace[1511530923] 'agreement among raft nodes before linearized reading' (duration: 192.284858ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.356198Z","caller":"traceutil/trace.go:171","msg":"trace[292191761] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"153.791258ms","start":"2026-03-01T13:53:30.202378Z","end":"2026-03-01T13:53:30.356169Z","steps":["trace[292191761] 'process raft request' (duration: 90.440322ms)","trace[292191761] 'compare' (duration: 63.107674ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:30.356292Z","caller":"traceutil/trace.go:171","msg":"trace[660493503] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"153.383764ms","start":"2026-03-01T13:53:30.202891Z","end":"2026-03-01T13:53:30.356275Z","steps":["trace[660493503] 'process raft request' (duration: 153.235873ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.356262Z","caller":"traceutil/trace.go:171","msg":"trace[721932440] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"153.742158ms","start":"2026-03-01T13:53:30.202474Z","end":"2026-03-01T13:53:30.356216Z","steps":["trace[721932440] 'process raft request' (duration: 153.612096ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.356397Z","caller":"traceutil/trace.go:171","msg":"trace[1988593515] linearizableReadLoop","detail":"{readStateIndex:32; appliedIndex:24; }","duration":"152.459965ms","start":"2026-03-01T13:53:30.203914Z","end":"2026-03-01T13:53:30.356374Z","steps":["trace[1988593515] 'read index received' (duration: 88.918638ms)","trace[1988593515] 'applied index is now lower than readState.Index' (duration: 63.540247ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:30.356508Z","caller":"traceutil/trace.go:171","msg":"trace[1851422260] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"153.233822ms","start":"2026-03-01T13:53:30.203253Z","end":"2026-03-01T13:53:30.356487Z","steps":["trace[1851422260] 'process raft request' (duration: 152.949039ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.356556Z","caller":"traceutil/trace.go:171","msg":"trace[1769465949] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"153.051741ms","start":"2026-03-01T13:53:30.203498Z","end":"2026-03-01T13:53:30.356549Z","steps":["trace[1769465949] 'process raft request' (duration: 152.811348ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.356665Z","caller":"traceutil/trace.go:171","msg":"trace[942298254] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"153.022961ms","start":"2026-03-01T13:53:30.203614Z","end":"2026-03-01T13:53:30.356637Z","steps":["trace[942298254] 'process raft request' (duration: 152.720628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:30.356772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.98999ms","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-01T13:53:30.356816Z","caller":"traceutil/trace.go:171","msg":"trace[1525995446] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:28; }","duration":"149.067431ms","start":"2026-03-01T13:53:30.207738Z","end":"2026-03-01T13:53:30.356806Z","steps":["trace[1525995446] 'agreement among raft nodes before linearized reading' (duration: 148.95009ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:30.356838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.957727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-01T13:53:30.356905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.96595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-01T13:53:30.357092Z","caller":"traceutil/trace.go:171","msg":"trace[1883741038] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:28; }","duration":"153.175492ms","start":"2026-03-01T13:53:30.203883Z","end":"2026-03-01T13:53:30.357058Z","steps":["trace[1883741038] 'agreement among raft nodes before linearized reading' (duration: 152.746228ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.356871Z","caller":"traceutil/trace.go:171","msg":"trace[877504329] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"153.895579ms","start":"2026-03-01T13:53:30.202957Z","end":"2026-03-01T13:53:30.356853Z","steps":["trace[877504329] 'process raft request' (duration: 153.207892ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.356996Z","caller":"traceutil/trace.go:171","msg":"trace[2029297154] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:28; }","duration":"102.143459ms","start":"2026-03-01T13:53:30.254799Z","end":"2026-03-01T13:53:30.356942Z","steps":["trace[2029297154] 'agreement among raft nodes before linearized reading' (duration: 101.939097ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.356528Z","caller":"traceutil/trace.go:171","msg":"trace[2139557622] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"153.265102ms","start":"2026-03-01T13:53:30.203246Z","end":"2026-03-01T13:53:30.356511Z","steps":["trace[2139557622] 'process raft request' (duration: 153.03123ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:30.610987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.995495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-01T13:53:30.61156Z","caller":"traceutil/trace.go:171","msg":"trace[797681866] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"213.478556ms","start":"2026-03-01T13:53:30.398064Z","end":"2026-03-01T13:53:30.611542Z","steps":["trace[797681866] 'process raft request' (duration: 213.446546ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.611561Z","caller":"traceutil/trace.go:171","msg":"trace[121985220] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"249.24375ms","start":"2026-03-01T13:53:30.362289Z","end":"2026-03-01T13:53:30.611532Z","steps":["trace[121985220] 'process raft request' (duration: 248.909397ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.611555Z","caller":"traceutil/trace.go:171","msg":"trace[492369884] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"249.665804ms","start":"2026-03-01T13:53:30.36164Z","end":"2026-03-01T13:53:30.611306Z","steps":["trace[492369884] 'process raft request' (duration: 139.85176ms)","trace[492369884] 'compare' (duration: 108.844254ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:30.611763Z","caller":"traceutil/trace.go:171","msg":"trace[1540121913] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"249.502162ms","start":"2026-03-01T13:53:30.362251Z","end":"2026-03-01T13:53:30.611753Z","steps":["trace[1540121913] 'process raft request' (duration: 248.900067ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.611818Z","caller":"traceutil/trace.go:171","msg":"trace[409224576] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"249.329381ms","start":"2026-03-01T13:53:30.36248Z","end":"2026-03-01T13:53:30.61181Z","steps":["trace[409224576] 'process raft request' (duration: 248.819266ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.61182Z","caller":"traceutil/trace.go:171","msg":"trace[285259963] linearizableReadLoop","detail":"{readStateIndex:35; appliedIndex:32; }","duration":"249.829065ms","start":"2026-03-01T13:53:30.36198Z","end":"2026-03-01T13:53:30.611809Z","steps":["trace[285259963] 'read index received' (duration: 32.52836ms)","trace[285259963] 'applied index is now lower than readState.Index' (duration: 217.299615ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:30.61179Z","caller":"traceutil/trace.go:171","msg":"trace[614805700] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"249.815655ms","start":"2026-03-01T13:53:30.36194Z","end":"2026-03-01T13:53:30.611756Z","steps":["trace[614805700] 'process raft request' (duration: 249.136989ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.611845Z","caller":"traceutil/trace.go:171","msg":"trace[1846710910] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"249.444512ms","start":"2026-03-01T13:53:30.362394Z","end":"2026-03-01T13:53:30.611838Z","steps":["trace[1846710910] 'process raft request' (duration: 248.826426ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:30.611896Z","caller":"traceutil/trace.go:171","msg":"trace[700572497] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"249.472732ms","start":"2026-03-01T13:53:30.362417Z","end":"2026-03-01T13:53:30.611889Z","steps":["trace[700572497] 'process raft request' (duration: 248.843736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:30.611927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.937487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-01T13:53:30.61341Z","caller":"traceutil/trace.go:171","msg":"trace[1632623315] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:37; }","duration":"251.437991ms","start":"2026-03-01T13:53:30.361939Z","end":"2026-03-01T13:53:30.613377Z","steps":["trace[1632623315] 'agreement among raft nodes before linearized reading' (duration: 249.904626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:30.613629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.61687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-01T13:53:30.613787Z","caller":"traceutil/trace.go:171","msg":"trace[1580520575] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:37; }","duration":"201.7103ms","start":"2026-03-01T13:53:30.41196Z","end":"2026-03-01T13:53:30.613671Z","steps":["trace[1580520575] 'agreement among raft nodes before linearized reading' (duration: 201.599329ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:30.880744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.709326ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-01T13:53:30.881007Z","caller":"traceutil/trace.go:171","msg":"trace[1460247074] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"241.715695ms","start":"2026-03-01T13:53:30.63925Z","end":"2026-03-01T13:53:30.880965Z","steps":["trace[1460247074] 'process raft request' (duration: 77.717016ms)","trace[1460247074] 'compare' (duration: 163.571824ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:30.88106Z","caller":"traceutil/trace.go:171","msg":"trace[1484149946] linearizableReadLoop","detail":"{readStateIndex:46; appliedIndex:44; }","duration":"119.369768ms","start":"2026-03-01T13:53:30.76165Z","end":"2026-03-01T13:53:30.88102Z","steps":["trace[1484149946] 'read index received' (duration: 42.12µs)","trace[1484149946] 'applied index is now lower than readState.Index' (duration: 119.326648ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:53:30.881194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.54287ms","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-01T13:53:30.88125Z","caller":"traceutil/trace.go:171","msg":"trace[1204699109] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:41; }","duration":"119.62177ms","start":"2026-03-01T13:53:30.761601Z","end":"2026-03-01T13:53:30.881222Z","steps":["trace[1204699109] 'agreement among raft nodes before linearized reading' (duration: 119.534679ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:30.881423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.477169ms","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-01T13:53:30.882151Z","caller":"traceutil/trace.go:171","msg":"trace[1349051638] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:41; }","duration":"119.609401ms","start":"2026-03-01T13:53:30.761875Z","end":"2026-03-01T13:53:30.881485Z","steps":["trace[1349051638] 'agreement among raft nodes before linearized reading' (duration: 119.440279ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:31.295785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.13552ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-01T13:53:31.296214Z","caller":"traceutil/trace.go:171","msg":"trace[808732674] linearizableReadLoop","detail":"{readStateIndex:48; appliedIndex:46; }","duration":"411.522258ms","start":"2026-03-01T13:53:30.88467Z","end":"2026-03-01T13:53:31.296192Z","steps":["trace[808732674] 'read index received' (duration: 164.893634ms)","trace[808732674] 'applied index is now lower than readState.Index' (duration: 246.627524ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:31.297215Z","caller":"traceutil/trace.go:171","msg":"trace[1019314546] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"412.7101ms","start":"2026-03-01T13:53:30.884264Z","end":"2026-03-01T13:53:31.296974Z","steps":["trace[1019314546] 'process raft request' (duration: 411.799031ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:31.296228Z","caller":"traceutil/trace.go:171","msg":"trace[996500016] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"413.246535ms","start":"2026-03-01T13:53:30.882913Z","end":"2026-03-01T13:53:31.29616Z","steps":["trace[996500016] 'process raft request' (duration: 166.667511ms)","trace[996500016] 'compare' (duration: 245.969498ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:53:31.297374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:30.884238Z","time spent":"413.063064ms","remote":"127.0.0.1:54960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-01T13:53:31.297661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:30.882893Z","time spent":"414.636509ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-01T13:53:31.299715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.010048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-01T13:53:31.299726Z","caller":"traceutil/trace.go:171","msg":"trace[1875168899] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"414.168505ms","start":"2026-03-01T13:53:30.885188Z","end":"2026-03-01T13:53:31.299357Z","steps":["trace[1875168899] 'process raft request' (duration: 410.962023ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:31.299806Z","caller":"traceutil/trace.go:171","msg":"trace[1224342931] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:44; }","duration":"155.124938ms","start":"2026-03-01T13:53:31.144646Z","end":"2026-03-01T13:53:31.299771Z","steps":["trace[1224342931] 'agreement among raft nodes before linearized reading' (duration: 153.349302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:31.299953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:30.885161Z","time spent":"414.70584ms","remote":"127.0.0.1:55048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":647,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-01T13:53:31.300737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.055383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-01T13:53:31.300841Z","caller":"traceutil/trace.go:171","msg":"trace[30565696] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:44; }","duration":"416.174805ms","start":"2026-03-01T13:53:30.884618Z","end":"2026-03-01T13:53:31.300792Z","steps":["trace[30565696] 'agreement among raft nodes before linearized reading' (duration: 415.980333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:31.30094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:30.884599Z","time spent":"416.293356ms","remote":"127.0.0.1:54946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-03-01T13:53:31.301678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.659121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-01T13:53:31.301851Z","caller":"traceutil/trace.go:171","msg":"trace[1600265369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:44; }","duration":"217.805543ms","start":"2026-03-01T13:53:31.083965Z","end":"2026-03-01T13:53:31.301771Z","steps":["trace[1600265369] 'agreement among raft nodes before linearized reading' (duration: 214.033777ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:31.30322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.648374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-01T13:53:31.30328Z","caller":"traceutil/trace.go:171","msg":"trace[1095351820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:44; }","duration":"158.712674ms","start":"2026-03-01T13:53:31.144549Z","end":"2026-03-01T13:53:31.303261Z","steps":["trace[1095351820] 'agreement among raft nodes before linearized reading' (duration: 154.1918ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:31.459489Z","caller":"traceutil/trace.go:171","msg":"trace[532850748] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"153.503143ms","start":"2026-03-01T13:53:31.305938Z","end":"2026-03-01T13:53:31.459441Z","steps":["trace[532850748] 'process raft request' (duration: 150.611754ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:31.836561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.102059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-03-01T13:53:31.836813Z","caller":"traceutil/trace.go:171","msg":"trace[869942584] transaction","detail":"{read_only:false; response_revision:46; number_of_response:1; }","duration":"529.621295ms","start":"2026-03-01T13:53:31.307159Z","end":"2026-03-01T13:53:31.83678Z","steps":["trace[869942584] 'process raft request' (duration: 377.090552ms)","trace[869942584] 'compare' (duration: 151.962208ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:31.83683Z","caller":"traceutil/trace.go:171","msg":"trace[1025399898] transaction","detail":"{read_only:false; response_revision:47; number_of_response:1; }","duration":"529.513834ms","start":"2026-03-01T13:53:31.307276Z","end":"2026-03-01T13:53:31.836789Z","steps":["trace[1025399898] 'process raft request' (duration: 529.377873ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:31.836935Z","caller":"traceutil/trace.go:171","msg":"trace[745881705] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"372.432327ms","start":"2026-03-01T13:53:31.464483Z","end":"2026-03-01T13:53:31.836916Z","steps":["trace[745881705] 'process raft request' (duration: 372.393106ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:31.836928Z","caller":"traceutil/trace.go:171","msg":"trace[445840108] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"528.430724ms","start":"2026-03-01T13:53:31.308474Z","end":"2026-03-01T13:53:31.836905Z","steps":["trace[445840108] 'process raft request' (duration: 528.303592ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:31.836928Z","caller":"traceutil/trace.go:171","msg":"trace[2095249969] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"529.201261ms","start":"2026-03-01T13:53:31.30767Z","end":"2026-03-01T13:53:31.836872Z","steps":["trace[2095249969] 'process raft request' (duration: 529.08029ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:31.837009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:31.464453Z","time spent":"372.529307ms","remote":"127.0.0.1:55048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":632,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-01T13:53:31.837029Z","caller":"traceutil/trace.go:171","msg":"trace[698781571] linearizableReadLoop","detail":"{readStateIndex:53; appliedIndex:49; }","duration":"529.638606ms","start":"2026-03-01T13:53:31.307382Z","end":"2026-03-01T13:53:31.83702Z","steps":["trace[698781571] 'read index received' (duration: 149.244571ms)","trace[698781571] 'applied index is now lower than readState.Index' (duration: 380.393105ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:31.837033Z","caller":"traceutil/trace.go:171","msg":"trace[1633276639] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"529.674916ms","start":"2026-03-01T13:53:31.307342Z","end":"2026-03-01T13:53:31.837017Z","steps":["trace[1633276639] 'process raft request' (duration: 529.376203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:31.837053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:31.308465Z","time spent":"528.527284ms","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-01T13:53:31.837073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:31.307335Z","time spent":"529.719786ms","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-01T13:53:31.837134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"529.747627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-01T13:53:31.837103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:31.307654Z","time spent":"529.362473ms","remote":"127.0.0.1:54654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-01T13:53:31.837164Z","caller":"traceutil/trace.go:171","msg":"trace[172210119] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:51; }","duration":"529.784807ms","start":"2026-03-01T13:53:31.307371Z","end":"2026-03-01T13:53:31.837156Z","steps":["trace[172210119] 'agreement among raft nodes before linearized reading' (duration: 529.704436ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:31.836943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:31.307141Z","time spent":"529.730926ms","remote":"127.0.0.1:55048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1058,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T13:53:31.837191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:31.307367Z","time spent":"529.818997ms","remote":"127.0.0.1:54944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/view\" "} {"level":"warn","ts":"2026-03-01T13:53:31.837241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:31.307264Z","time spent":"529.740076ms","remote":"127.0.0.1:54960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-01T13:53:32.048775Z","caller":"traceutil/trace.go:171","msg":"trace[2004941913] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"113.956232ms","start":"2026-03-01T13:53:31.934795Z","end":"2026-03-01T13:53:32.048752Z","steps":["trace[2004941913] 'process raft request' (duration: 113.917992ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:32.048986Z","caller":"traceutil/trace.go:171","msg":"trace[1513370891] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"114.328396ms","start":"2026-03-01T13:53:31.934648Z","end":"2026-03-01T13:53:32.048976Z","steps":["trace[1513370891] 'process raft request' (duration: 114.021683ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:32.049333Z","caller":"traceutil/trace.go:171","msg":"trace[372593474] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"120.409935ms","start":"2026-03-01T13:53:31.928893Z","end":"2026-03-01T13:53:32.049303Z","steps":["trace[372593474] 'process raft request' (duration: 70.984985ms)","trace[372593474] 'compare' (duration: 48.513702ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:32.04942Z","caller":"traceutil/trace.go:171","msg":"trace[982512043] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"120.386705ms","start":"2026-03-01T13:53:31.928971Z","end":"2026-03-01T13:53:32.049357Z","steps":["trace[982512043] 'process raft request' (duration: 119.600077ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:32.049473Z","caller":"traceutil/trace.go:171","msg":"trace[44028731] linearizableReadLoop","detail":"{readStateIndex:61; appliedIndex:59; }","duration":"117.961761ms","start":"2026-03-01T13:53:31.931492Z","end":"2026-03-01T13:53:32.049454Z","steps":["trace[44028731] 'read index received' (duration: 68.374179ms)","trace[44028731] 'applied index is now lower than readState.Index' (duration: 49.586522ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:53:32.049545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.037131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-01T13:53:32.049573Z","caller":"traceutil/trace.go:171","msg":"trace[1077885712] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:58; }","duration":"118.113603ms","start":"2026-03-01T13:53:31.931451Z","end":"2026-03-01T13:53:32.049565Z","steps":["trace[1077885712] 'agreement among raft nodes before linearized reading' (duration: 118.051872ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:32.184948Z","caller":"traceutil/trace.go:171","msg":"trace[1429745188] linearizableReadLoop","detail":"{readStateIndex:65; appliedIndex:64; }","duration":"110.850067ms","start":"2026-03-01T13:53:32.07408Z","end":"2026-03-01T13:53:32.18493Z","steps":["trace[1429745188] 'read index received' (duration: 76.261871ms)","trace[1429745188] 'applied index is now lower than readState.Index' (duration: 34.587586ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:32.185044Z","caller":"traceutil/trace.go:171","msg":"trace[371735339] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"130.226395ms","start":"2026-03-01T13:53:32.05481Z","end":"2026-03-01T13:53:32.185036Z","steps":["trace[371735339] 'process raft request' (duration: 95.518918ms)","trace[371735339] 'compare' (duration: 34.484094ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:32.185248Z","caller":"traceutil/trace.go:171","msg":"trace[1377153466] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"108.395873ms","start":"2026-03-01T13:53:32.076832Z","end":"2026-03-01T13:53:32.185228Z","steps":["trace[1377153466] 'process raft request' (duration: 108.063099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:32.185302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.964953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-01T13:53:32.185391Z","caller":"traceutil/trace.go:171","msg":"trace[907378152] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:0; response_revision:61; }","duration":"131.009603ms","start":"2026-03-01T13:53:32.054317Z","end":"2026-03-01T13:53:32.185327Z","steps":["trace[907378152] 'agreement among raft nodes before linearized reading' (duration: 130.873882ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:32.185565Z","caller":"traceutil/trace.go:171","msg":"trace[673651465] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"107.723617ms","start":"2026-03-01T13:53:32.077811Z","end":"2026-03-01T13:53:32.185535Z","steps":["trace[673651465] 'process raft request' (duration: 107.343184ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:32.464007Z","caller":"traceutil/trace.go:171","msg":"trace[102339220] transaction","detail":"{read_only:false; response_revision:65; number_of_response:1; }","duration":"273.642679ms","start":"2026-03-01T13:53:32.190329Z","end":"2026-03-01T13:53:32.463972Z","steps":["trace[102339220] 'process raft request' (duration: 273.344086ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:32.464237Z","caller":"traceutil/trace.go:171","msg":"trace[1120027774] transaction","detail":"{read_only:false; response_revision:66; number_of_response:1; }","duration":"273.115763ms","start":"2026-03-01T13:53:32.191102Z","end":"2026-03-01T13:53:32.464218Z","steps":["trace[1120027774] 'process raft request' (duration: 272.638819ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:32.464001Z","caller":"traceutil/trace.go:171","msg":"trace[517676090] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"273.79302ms","start":"2026-03-01T13:53:32.190181Z","end":"2026-03-01T13:53:32.463974Z","steps":["trace[517676090] 'process raft request' (duration: 185.988808ms)","trace[517676090] 'compare' (duration: 87.355258ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:32.464863Z","caller":"traceutil/trace.go:171","msg":"trace[970723783] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"236.967861ms","start":"2026-03-01T13:53:32.227882Z","end":"2026-03-01T13:53:32.46485Z","steps":["trace[970723783] 'process raft request' (duration: 236.189444ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:32.680455Z","caller":"traceutil/trace.go:171","msg":"trace[1230457941] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"145.207161ms","start":"2026-03-01T13:53:32.535205Z","end":"2026-03-01T13:53:32.680413Z","steps":["trace[1230457941] 'process raft request' (duration: 109.929989ms)","trace[1230457941] 'compare' (duration: 34.998959ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:32.680751Z","caller":"traceutil/trace.go:171","msg":"trace[1885313760] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"145.18927ms","start":"2026-03-01T13:53:32.535542Z","end":"2026-03-01T13:53:32.680732Z","steps":["trace[1885313760] 'process raft request' (duration: 144.795106ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:32.681366Z","caller":"traceutil/trace.go:171","msg":"trace[1943052808] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"134.693988ms","start":"2026-03-01T13:53:32.546654Z","end":"2026-03-01T13:53:32.681348Z","steps":["trace[1943052808] 'process raft request' (duration: 134.615838ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:32.681371Z","caller":"traceutil/trace.go:171","msg":"trace[1681423493] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"134.819529ms","start":"2026-03-01T13:53:32.546514Z","end":"2026-03-01T13:53:32.681333Z","steps":["trace[1681423493] 'process raft request' (duration: 133.90423ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:32.859429Z","caller":"traceutil/trace.go:171","msg":"trace[1926494144] linearizableReadLoop","detail":"{readStateIndex:87; appliedIndex:86; }","duration":"130.668169ms","start":"2026-03-01T13:53:32.728724Z","end":"2026-03-01T13:53:32.859392Z","steps":["trace[1926494144] 'read index received' (duration: 93.215055ms)","trace[1926494144] 'applied index is now lower than readState.Index' (duration: 37.450054ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:32.859441Z","caller":"traceutil/trace.go:171","msg":"trace[934183322] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"141.219042ms","start":"2026-03-01T13:53:32.718188Z","end":"2026-03-01T13:53:32.859407Z","steps":["trace[934183322] 'process raft request' (duration: 103.654237ms)","trace[934183322] 'compare' (duration: 37.364773ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:32.859853Z","caller":"traceutil/trace.go:171","msg":"trace[1615517867] transaction","detail":"{read_only:false; response_revision:84; number_of_response:1; }","duration":"130.660939ms","start":"2026-03-01T13:53:32.729177Z","end":"2026-03-01T13:53:32.859838Z","steps":["trace[1615517867] 'process raft request' (duration: 130.582388ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:32.859877Z","caller":"traceutil/trace.go:171","msg":"trace[1743668666] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"131.052483ms","start":"2026-03-01T13:53:32.728796Z","end":"2026-03-01T13:53:32.859848Z","steps":["trace[1743668666] 'process raft request' (duration: 130.551498ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:32.859914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.156303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.1898bc099981353e\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-03-01T13:53:32.860103Z","caller":"traceutil/trace.go:171","msg":"trace[1798441196] range","detail":"{range_begin:/registry/events/default/instance.1898bc099981353e; range_end:; response_count:1; response_revision:83; }","duration":"131.385476ms","start":"2026-03-01T13:53:32.728694Z","end":"2026-03-01T13:53:32.860079Z","steps":["trace[1798441196] 'agreement among raft nodes before linearized reading' (duration: 130.803541ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:33.120735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.709123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-03-01T13:53:33.120899Z","caller":"traceutil/trace.go:171","msg":"trace[1051274282] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"255.999019ms","start":"2026-03-01T13:53:32.864882Z","end":"2026-03-01T13:53:33.120881Z","steps":["trace[1051274282] 'process raft request' (duration: 97.063633ms)","trace[1051274282] 'compare' (duration: 158.572292ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:33.121113Z","caller":"traceutil/trace.go:171","msg":"trace[218833373] linearizableReadLoop","detail":"{readStateIndex:92; appliedIndex:90; }","duration":"252.734177ms","start":"2026-03-01T13:53:32.868363Z","end":"2026-03-01T13:53:33.121097Z","steps":["trace[218833373] 'read index received' (duration: 93.59721ms)","trace[218833373] 'applied index is now lower than readState.Index' (duration: 159.135707ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:53:33.121276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.905538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.1898bc0999814614\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-03-01T13:53:33.121392Z","caller":"traceutil/trace.go:171","msg":"trace[1881855069] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"253.607776ms","start":"2026-03-01T13:53:32.867775Z","end":"2026-03-01T13:53:33.121383Z","steps":["trace[1881855069] 'process raft request' (duration: 253.06155ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:33.121475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.819751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-01T13:53:33.1215Z","caller":"traceutil/trace.go:171","msg":"trace[900658265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:87; }","duration":"237.866462ms","start":"2026-03-01T13:53:32.883626Z","end":"2026-03-01T13:53:33.121493Z","steps":["trace[900658265] 'agreement among raft nodes before linearized reading' (duration: 237.827941ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:33.121573Z","caller":"traceutil/trace.go:171","msg":"trace[1745423199] range","detail":"{range_begin:/registry/events/default/instance.1898bc0999814614; range_end:; response_count:1; response_revision:87; }","duration":"252.96982ms","start":"2026-03-01T13:53:32.86834Z","end":"2026-03-01T13:53:33.12131Z","steps":["trace[1745423199] 'agreement among raft nodes before linearized reading' (duration: 252.875199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:33.121611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.586314ms","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:4"} {"level":"info","ts":"2026-03-01T13:53:33.121815Z","caller":"traceutil/trace.go:171","msg":"trace[349498458] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:87; }","duration":"186.838706ms","start":"2026-03-01T13:53:32.934942Z","end":"2026-03-01T13:53:33.121781Z","steps":["trace[349498458] 'agreement among raft nodes before linearized reading' (duration: 186.545814ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:33.332433Z","caller":"traceutil/trace.go:171","msg":"trace[366338848] linearizableReadLoop","detail":"{readStateIndex:101; appliedIndex:99; }","duration":"130.902042ms","start":"2026-03-01T13:53:33.201506Z","end":"2026-03-01T13:53:33.332408Z","steps":["trace[366338848] 'read index received' (duration: 68.684682ms)","trace[366338848] 'applied index is now lower than readState.Index' (duration: 62.2164ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:33.332634Z","caller":"traceutil/trace.go:171","msg":"trace[659727667] transaction","detail":"{read_only:false; response_revision:95; number_of_response:1; }","duration":"141.946249ms","start":"2026-03-01T13:53:33.190659Z","end":"2026-03-01T13:53:33.332606Z","steps":["trace[659727667] 'process raft request' (duration: 79.614218ms)","trace[659727667] 'compare' (duration: 61.746225ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:33.332718Z","caller":"traceutil/trace.go:171","msg":"trace[802559121] transaction","detail":"{read_only:false; response_revision:96; number_of_response:1; }","duration":"140.550035ms","start":"2026-03-01T13:53:33.192154Z","end":"2026-03-01T13:53:33.332704Z","steps":["trace[802559121] 'process raft request' (duration: 140.03169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:33.332929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.411266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.1898bc099981353e\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-03-01T13:53:33.332967Z","caller":"traceutil/trace.go:171","msg":"trace[180489368] range","detail":"{range_begin:/registry/events/default/instance.1898bc099981353e; range_end:; response_count:1; response_revision:96; }","duration":"131.505258ms","start":"2026-03-01T13:53:33.201452Z","end":"2026-03-01T13:53:33.332957Z","steps":["trace[180489368] 'agreement among raft nodes before linearized reading' (duration: 131.428777ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:33.544678Z","caller":"traceutil/trace.go:171","msg":"trace[2024089078] transaction","detail":"{read_only:false; response_revision:102; number_of_response:1; }","duration":"129.992834ms","start":"2026-03-01T13:53:33.414661Z","end":"2026-03-01T13:53:33.544654Z","steps":["trace[2024089078] 'process raft request' (duration: 129.924703ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:33.544828Z","caller":"traceutil/trace.go:171","msg":"trace[1004696121] transaction","detail":"{read_only:false; response_revision:101; number_of_response:1; }","duration":"131.894951ms","start":"2026-03-01T13:53:33.412835Z","end":"2026-03-01T13:53:33.54473Z","steps":["trace[1004696121] 'process raft request' (duration: 106.84395ms)","trace[1004696121] 'compare' (duration: 24.752169ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:33.975109Z","caller":"traceutil/trace.go:171","msg":"trace[728128019] linearizableReadLoop","detail":"{readStateIndex:116; appliedIndex:115; }","duration":"146.070618ms","start":"2026-03-01T13:53:33.829005Z","end":"2026-03-01T13:53:33.975076Z","steps":["trace[728128019] 'read index received' (duration: 70.465259ms)","trace[728128019] 'applied index is now lower than readState.Index' (duration: 75.604229ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:33.975176Z","caller":"traceutil/trace.go:171","msg":"trace[1722930912] transaction","detail":"{read_only:false; response_revision:111; number_of_response:1; }","duration":"200.982147ms","start":"2026-03-01T13:53:33.77412Z","end":"2026-03-01T13:53:33.975102Z","steps":["trace[1722930912] 'process raft request' (duration: 125.511151ms)","trace[1722930912] 'compare' (duration: 75.180434ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:53:33.975334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.310751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.1898bc0999814614\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-03-01T13:53:33.975479Z","caller":"traceutil/trace.go:171","msg":"trace[845743416] range","detail":"{range_begin:/registry/events/default/instance.1898bc0999814614; range_end:; response_count:1; response_revision:111; }","duration":"146.456603ms","start":"2026-03-01T13:53:33.828974Z","end":"2026-03-01T13:53:33.97543Z","steps":["trace[845743416] 'agreement among raft nodes before linearized reading' (duration: 146.256082ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:34.03699Z","caller":"traceutil/trace.go:171","msg":"trace[602920776] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"109.480704ms","start":"2026-03-01T13:53:33.927484Z","end":"2026-03-01T13:53:34.036965Z","steps":["trace[602920776] 'process raft request' (duration: 109.271063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:34.037068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.879582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-03-01T13:53:34.037113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.719839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-01T13:53:34.037191Z","caller":"traceutil/trace.go:171","msg":"trace[849432524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:112; }","duration":"153.048834ms","start":"2026-03-01T13:53:33.884121Z","end":"2026-03-01T13:53:34.03717Z","steps":["trace[849432524] 'agreement among raft nodes before linearized reading' (duration: 152.809722ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:34.03723Z","caller":"traceutil/trace.go:171","msg":"trace[1604588121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:112; }","duration":"101.888091ms","start":"2026-03-01T13:53:33.935318Z","end":"2026-03-01T13:53:34.037206Z","steps":["trace[1604588121] 'agreement among raft nodes before linearized reading' (duration: 101.615408ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:34.121466Z","caller":"traceutil/trace.go:171","msg":"trace[73785263] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"131.598021ms","start":"2026-03-01T13:53:33.989842Z","end":"2026-03-01T13:53:34.12144Z","steps":["trace[73785263] 'process raft request' (duration: 97.854988ms)","trace[73785263] 'compare' (duration: 33.578501ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:53:34.121961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.55864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-bootstrapper\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-01T13:53:34.122046Z","caller":"traceutil/trace.go:171","msg":"trace[993751781] range","detail":"{range_begin:/registry/clusterroles/system:node-bootstrapper; range_end:; response_count:0; response_revision:114; }","duration":"131.678081ms","start":"2026-03-01T13:53:33.990339Z","end":"2026-03-01T13:53:34.122017Z","steps":["trace[993751781] 'agreement among raft nodes before linearized reading' (duration: 131.57518ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:34.269714Z","caller":"traceutil/trace.go:171","msg":"trace[1055717611] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"107.936303ms","start":"2026-03-01T13:53:34.161685Z","end":"2026-03-01T13:53:34.269621Z","steps":["trace[1055717611] 'process raft request' (duration: 65.889811ms)","trace[1055717611] 'compare' (duration: 41.717339ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:53:34.579887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.351574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:53:34.580078Z","caller":"traceutil/trace.go:171","msg":"trace[57205743] linearizableReadLoop","detail":"{readStateIndex:137; appliedIndex:135; }","duration":"196.193037ms","start":"2026-03-01T13:53:34.383869Z","end":"2026-03-01T13:53:34.580062Z","steps":["trace[57205743] 'read index received' (duration: 35.4795ms)","trace[57205743] 'applied index is now lower than readState.Index' (duration: 160.712677ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:53:34.580166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.289808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:53:34.580198Z","caller":"traceutil/trace.go:171","msg":"trace[1179893223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:132; }","duration":"196.360409ms","start":"2026-03-01T13:53:34.383828Z","end":"2026-03-01T13:53:34.580189Z","steps":["trace[1179893223] 'agreement among raft nodes before linearized reading' (duration: 196.286349ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:34.580204Z","caller":"traceutil/trace.go:171","msg":"trace[1521818505] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"215.37767ms","start":"2026-03-01T13:53:34.364794Z","end":"2026-03-01T13:53:34.580171Z","steps":["trace[1521818505] 'process raft request' (duration: 54.678013ms)","trace[1521818505] 'compare' (duration: 160.068591ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:34.580447Z","caller":"traceutil/trace.go:171","msg":"trace[1795095129] transaction","detail":"{read_only:false; response_revision:132; number_of_response:1; }","duration":"213.020308ms","start":"2026-03-01T13:53:34.367391Z","end":"2026-03-01T13:53:34.580411Z","steps":["trace[1795095129] 'process raft request' (duration: 212.595433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:34.893902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.698315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:53:34.894005Z","caller":"traceutil/trace.go:171","msg":"trace[924595775] linearizableReadLoop","detail":"{readStateIndex:140; appliedIndex:139; }","duration":"225.605739ms","start":"2026-03-01T13:53:34.668383Z","end":"2026-03-01T13:53:34.893989Z","steps":["trace[924595775] 'read index received' (duration: 35.732852ms)","trace[924595775] 'applied index is now lower than readState.Index' (duration: 189.871827ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:34.894077Z","caller":"traceutil/trace.go:171","msg":"trace[743955988] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"232.412594ms","start":"2026-03-01T13:53:34.661654Z","end":"2026-03-01T13:53:34.894067Z","steps":["trace[743955988] 'process raft request' (duration: 42.479196ms)","trace[743955988] 'compare' (duration: 189.428173ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:53:34.894207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.808261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:53:34.894309Z","caller":"traceutil/trace.go:171","msg":"trace[643689063] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:0; response_revision:135; }","duration":"225.950063ms","start":"2026-03-01T13:53:34.668331Z","end":"2026-03-01T13:53:34.894281Z","steps":["trace[643689063] 'agreement among raft nodes before linearized reading' (duration: 225.772252ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:34.914842Z","caller":"traceutil/trace.go:171","msg":"trace[1219147763] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"234.0513ms","start":"2026-03-01T13:53:34.680756Z","end":"2026-03-01T13:53:34.914807Z","steps":["trace[1219147763] 'process raft request' (duration: 233.910519ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:35.098373Z","caller":"traceutil/trace.go:171","msg":"trace[1491535551] linearizableReadLoop","detail":"{readStateIndex:146; appliedIndex:145; }","duration":"147.043251ms","start":"2026-03-01T13:53:34.951303Z","end":"2026-03-01T13:53:35.098346Z","steps":["trace[1491535551] 'read index received' (duration: 54.683193ms)","trace[1491535551] 'applied index is now lower than readState.Index' (duration: 92.358978ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:35.09841Z","caller":"traceutil/trace.go:171","msg":"trace[710295360] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"153.435691ms","start":"2026-03-01T13:53:34.944941Z","end":"2026-03-01T13:53:35.098376Z","steps":["trace[710295360] 'process raft request' (duration: 61.088044ms)","trace[710295360] 'compare' (duration: 92.196256ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:53:35.098558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.233303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:53:35.098605Z","caller":"traceutil/trace.go:171","msg":"trace[2007413414] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-approver; range_end:; response_count:0; response_revision:141; }","duration":"147.327744ms","start":"2026-03-01T13:53:34.951264Z","end":"2026-03-01T13:53:35.098591Z","steps":["trace[2007413414] 'agreement among raft nodes before linearized reading' (duration: 147.173482ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:36.120204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.116505ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:53:36.120555Z","caller":"traceutil/trace.go:171","msg":"trace[1271208707] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"248.641645ms","start":"2026-03-01T13:53:35.871891Z","end":"2026-03-01T13:53:36.120532Z","steps":["trace[1271208707] 'process raft request' (duration: 51.130006ms)","trace[1271208707] 'compare' (duration: 196.966074ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:53:36.120888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.803985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:53:36.120942Z","caller":"traceutil/trace.go:171","msg":"trace[837142119] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:181; }","duration":"171.895545ms","start":"2026-03-01T13:53:35.949033Z","end":"2026-03-01T13:53:36.120929Z","steps":["trace[837142119] 'agreement among raft nodes before linearized reading' (duration: 171.774324ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:36.12104Z","caller":"traceutil/trace.go:171","msg":"trace[962673272] linearizableReadLoop","detail":"{readStateIndex:186; appliedIndex:185; }","duration":"171.404611ms","start":"2026-03-01T13:53:35.94907Z","end":"2026-03-01T13:53:36.120474Z","steps":["trace[962673272] 'read index received' (duration: 42.67µs)","trace[962673272] 'applied index is now lower than readState.Index' (duration: 171.359511ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:36.327765Z","caller":"traceutil/trace.go:171","msg":"trace[314522869] transaction","detail":"{read_only:false; response_revision:184; number_of_response:1; }","duration":"115.935043ms","start":"2026-03-01T13:53:36.211749Z","end":"2026-03-01T13:53:36.327684Z","steps":["trace[314522869] 'process raft request' (duration: 115.751432ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:36.33429Z","caller":"traceutil/trace.go:171","msg":"trace[686991087] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"121.282115ms","start":"2026-03-01T13:53:36.212978Z","end":"2026-03-01T13:53:36.33426Z","steps":["trace[686991087] 'process raft request' (duration: 121.108663ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:37.08613Z","caller":"traceutil/trace.go:171","msg":"trace[77086139] linearizableReadLoop","detail":"{readStateIndex:220; appliedIndex:219; }","duration":"136.14449ms","start":"2026-03-01T13:53:36.94996Z","end":"2026-03-01T13:53:37.086105Z","steps":["trace[77086139] 'read index received' (duration: 85.447675ms)","trace[77086139] 'applied index is now lower than readState.Index' (duration: 50.695915ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:37.08629Z","caller":"traceutil/trace.go:171","msg":"trace[1550974651] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"139.567682ms","start":"2026-03-01T13:53:36.946693Z","end":"2026-03-01T13:53:37.08626Z","steps":["trace[1550974651] 'process raft request' (duration: 88.675285ms)","trace[1550974651] 'compare' (duration: 50.566374ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:53:37.086636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.325021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:53:37.086685Z","caller":"traceutil/trace.go:171","msg":"trace[919515252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:215; }","duration":"136.727465ms","start":"2026-03-01T13:53:36.949945Z","end":"2026-03-01T13:53:37.086672Z","steps":["trace[919515252] 'agreement among raft nodes before linearized reading' (duration: 136.260491ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:37.350811Z","caller":"traceutil/trace.go:171","msg":"trace[1866900602] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"101.858304ms","start":"2026-03-01T13:53:37.248927Z","end":"2026-03-01T13:53:37.350785Z","steps":["trace[1866900602] 'process raft request' (duration: 82.613144ms)","trace[1866900602] 'compare' (duration: 19.109218ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:53:37.682659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.714024ms","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-01T13:53:37.682761Z","caller":"traceutil/trace.go:171","msg":"trace[1336886119] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:236; }","duration":"100.858824ms","start":"2026-03-01T13:53:37.581881Z","end":"2026-03-01T13:53:37.68274Z","steps":["trace[1336886119] 'range keys from in-memory index tree' (duration: 100.508942ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:37.817151Z","caller":"traceutil/trace.go:171","msg":"trace[1372133907] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"131.099539ms","start":"2026-03-01T13:53:37.686012Z","end":"2026-03-01T13:53:37.817111Z","steps":["trace[1372133907] 'process raft request' (duration: 130.904197ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:38.004124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.405177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:53:38.00447Z","caller":"traceutil/trace.go:171","msg":"trace[185686051] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"122.191195ms","start":"2026-03-01T13:53:37.882249Z","end":"2026-03-01T13:53:38.00444Z","steps":["trace[185686051] 'process raft request' (duration: 14.363254ms)","trace[185686051] 'compare' (duration: 106.975654ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:53:39.211804Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540989109386563,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-01T13:53:39.526137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.179051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-03-01T13:53:39.526224Z","caller":"traceutil/trace.go:171","msg":"trace[836565517] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:253; }","duration":"922.316753ms","start":"2026-03-01T13:53:38.603888Z","end":"2026-03-01T13:53:39.526205Z","steps":["trace[836565517] 'range keys from in-memory index tree' (duration: 922.060341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:39.526271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:38.603869Z","time spent":"922.387194ms","remote":"127.0.0.1:54784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3490,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-03-01T13:53:39.527739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.305418ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:53:39.52786Z","caller":"traceutil/trace.go:171","msg":"trace[1645394968] linearizableReadLoop","detail":"{readStateIndex:260; appliedIndex:259; }","duration":"816.65963ms","start":"2026-03-01T13:53:38.711183Z","end":"2026-03-01T13:53:39.527843Z","steps":["trace[1645394968] 'read index received' (duration: 637.097189ms)","trace[1645394968] 'applied index is now lower than readState.Index' (duration: 179.560611ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:39.527952Z","caller":"traceutil/trace.go:171","msg":"trace[356441170] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"922.656696ms","start":"2026-03-01T13:53:38.605262Z","end":"2026-03-01T13:53:39.527919Z","steps":["trace[356441170] 'process raft request' (duration: 743.084106ms)","trace[356441170] 'compare' (duration: 177.713123ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:53:39.528063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:38.605253Z","time spent":"922.752387ms","remote":"127.0.0.1:54784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-01T13:53:39.528042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.834032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T13:53:39.52806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.684055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3492"} {"level":"info","ts":"2026-03-01T13:53:39.528125Z","caller":"traceutil/trace.go:171","msg":"trace[306177686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:254; }","duration":"816.994483ms","start":"2026-03-01T13:53:38.711105Z","end":"2026-03-01T13:53:39.528099Z","steps":["trace[306177686] 'agreement among raft nodes before linearized reading' (duration: 816.831471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:39.528179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:38.711081Z","time spent":"817.089064ms","remote":"127.0.0.1:54866","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":"info","ts":"2026-03-01T13:53:39.528185Z","caller":"traceutil/trace.go:171","msg":"trace[350033286] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:254; }","duration":"494.804686ms","start":"2026-03-01T13:53:39.033358Z","end":"2026-03-01T13:53:39.528163Z","steps":["trace[350033286] 'agreement among raft nodes before linearized reading' (duration: 494.599175ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:39.528453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:39.033293Z","time spent":"495.142159ms","remote":"127.0.0.1:54770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3516,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-03-01T13:53:44.755416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.141052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:53:44.755523Z","caller":"traceutil/trace.go:171","msg":"trace[1494808679] linearizableReadLoop","detail":"{readStateIndex:373; appliedIndex:372; }","duration":"268.150306ms","start":"2026-03-01T13:53:44.487358Z","end":"2026-03-01T13:53:44.755509Z","steps":["trace[1494808679] 'read index received' (duration: 15.841133ms)","trace[1494808679] 'applied index is now lower than readState.Index' (duration: 252.307923ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:53:44.755832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.46125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-03-01T13:53:44.75588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.583491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T13:53:44.755888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.824924ms","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-01T13:53:44.755908Z","caller":"traceutil/trace.go:171","msg":"trace[34863611] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:360; }","duration":"149.654513ms","start":"2026-03-01T13:53:44.606244Z","end":"2026-03-01T13:53:44.755898Z","steps":["trace[34863611] 'agreement among raft nodes before linearized reading' (duration: 149.607742ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:44.75592Z","caller":"traceutil/trace.go:171","msg":"trace[1974708465] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:360; }","duration":"168.883715ms","start":"2026-03-01T13:53:44.587025Z","end":"2026-03-01T13:53:44.755909Z","steps":["trace[1974708465] 'agreement among raft nodes before linearized reading' (duration: 168.815444ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:44.755922Z","caller":"traceutil/trace.go:171","msg":"trace[2019447340] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"310.155363ms","start":"2026-03-01T13:53:44.445738Z","end":"2026-03-01T13:53:44.755893Z","steps":["trace[2019447340] 'process raft request' (duration: 57.468016ms)","trace[2019447340] 'compare' (duration: 252.036901ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:44.755893Z","caller":"traceutil/trace.go:171","msg":"trace[2043242627] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:360; }","duration":"218.5524ms","start":"2026-03-01T13:53:44.537327Z","end":"2026-03-01T13:53:44.75588Z","steps":["trace[2043242627] 'agreement among raft nodes before linearized reading' (duration: 218.4418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:44.756034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.897045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"warn","ts":"2026-03-01T13:53:44.756049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:44.445716Z","time spent":"310.266074ms","remote":"127.0.0.1:54686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2163,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T13:53:44.756072Z","caller":"traceutil/trace.go:171","msg":"trace[1722770794] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:360; }","duration":"169.961815ms","start":"2026-03-01T13:53:44.586093Z","end":"2026-03-01T13:53:44.756055Z","steps":["trace[1722770794] 'agreement among raft nodes before linearized reading' (duration: 169.915075ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:44.756118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.564443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-01T13:53:44.756237Z","caller":"traceutil/trace.go:171","msg":"trace[965172640] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:360; }","duration":"118.950046ms","start":"2026-03-01T13:53:44.637274Z","end":"2026-03-01T13:53:44.756224Z","steps":["trace[965172640] 'agreement among raft nodes before linearized reading' (duration: 118.551573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:44.755715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.340378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-01T13:53:44.756331Z","caller":"traceutil/trace.go:171","msg":"trace[1647640709] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:360; }","duration":"268.991183ms","start":"2026-03-01T13:53:44.487328Z","end":"2026-03-01T13:53:44.756319Z","steps":["trace[1647640709] 'agreement among raft nodes before linearized reading' (duration: 268.327788ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:45.294344Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540989109386822,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-01T13:53:45.582443Z","caller":"traceutil/trace.go:171","msg":"trace[1834865651] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"822.42554ms","start":"2026-03-01T13:53:44.759999Z","end":"2026-03-01T13:53:45.582424Z","steps":["trace[1834865651] 'process raft request' (duration: 822.302659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:45.582544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:44.759981Z","time spent":"822.51462ms","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-01T13:53:45.589222Z","caller":"traceutil/trace.go:171","msg":"trace[1323900274] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"827.748106ms","start":"2026-03-01T13:53:44.761452Z","end":"2026-03-01T13:53:45.589201Z","steps":["trace[1323900274] 'process raft request' (duration: 827.556514ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:45.590577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:44.761431Z","time spent":"829.071009ms","remote":"127.0.0.1:54784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T13:53:45.589305Z","caller":"traceutil/trace.go:171","msg":"trace[945665429] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"824.509318ms","start":"2026-03-01T13:53:44.764779Z","end":"2026-03-01T13:53:45.589288Z","steps":["trace[945665429] 'process raft request' (duration: 824.327756ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:45.589441Z","caller":"traceutil/trace.go:171","msg":"trace[1617874815] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"824.101123ms","start":"2026-03-01T13:53:44.765288Z","end":"2026-03-01T13:53:45.58939Z","steps":["trace[1617874815] 'process raft request' (duration: 823.941862ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:45.589514Z","caller":"traceutil/trace.go:171","msg":"trace[1314593927] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:373; }","duration":"795.376606ms","start":"2026-03-01T13:53:44.794122Z","end":"2026-03-01T13:53:45.589498Z","steps":["trace[1314593927] 'read index received' (duration: 788.262952ms)","trace[1314593927] 'applied index is now lower than readState.Index' (duration: 7.113054ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:45.58952Z","caller":"traceutil/trace.go:171","msg":"trace[1159493239] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"823.744491ms","start":"2026-03-01T13:53:44.765766Z","end":"2026-03-01T13:53:45.589511Z","steps":["trace[1159493239] 'process raft request' (duration: 823.494088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:45.589574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.448147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-03-01T13:53:45.589601Z","caller":"traceutil/trace.go:171","msg":"trace[65150257] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"819.756455ms","start":"2026-03-01T13:53:44.769838Z","end":"2026-03-01T13:53:45.589594Z","steps":["trace[65150257] 'process raft request' (duration: 819.627044ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:53:45.589601Z","caller":"traceutil/trace.go:171","msg":"trace[802045727] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"821.613812ms","start":"2026-03-01T13:53:44.767978Z","end":"2026-03-01T13:53:45.589592Z","steps":["trace[802045727] 'process raft request' (duration: 821.414359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:45.590796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:44.764749Z","time spent":"826.014711ms","remote":"127.0.0.1:55100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3642,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-01T13:53:45.591019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:44.765267Z","time spent":"825.702588ms","remote":"127.0.0.1:55102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2150,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-01T13:53:45.593971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:44.765754Z","time spent":"828.14462ms","remote":"127.0.0.1:54806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-01T13:53:45.594169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:44.769827Z","time spent":"824.253364ms","remote":"127.0.0.1:54858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1247,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T13:53:45.594197Z","caller":"traceutil/trace.go:171","msg":"trace[682836525] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:367; }","duration":"800.081167ms","start":"2026-03-01T13:53:44.794072Z","end":"2026-03-01T13:53:45.594153Z","steps":["trace[682836525] 'agreement among raft nodes before linearized reading' (duration: 795.464877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:45.594274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:44.794044Z","time spent":"800.213109ms","remote":"127.0.0.1:54806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-03-01T13:53:45.594323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:44.767961Z","time spent":"826.281313ms","remote":"127.0.0.1:54770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4355,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T13:53:51.360694Z","caller":"traceutil/trace.go:171","msg":"trace[279376474] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"559.61431ms","start":"2026-03-01T13:53:50.801054Z","end":"2026-03-01T13:53:51.360668Z","steps":["trace[279376474] 'process raft request' (duration: 559.452049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:51.36088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:50.801031Z","time spent":"559.751892ms","remote":"127.0.0.1:54866","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-01T13:53:52.058504Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540989109386927,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-01T13:53:52.276507Z","caller":"traceutil/trace.go:171","msg":"trace[238389909] linearizableReadLoop","detail":"{readStateIndex:442; appliedIndex:441; }","duration":"718.955381ms","start":"2026-03-01T13:53:51.557517Z","end":"2026-03-01T13:53:52.276472Z","steps":["trace[238389909] 'read index received' (duration: 718.143363ms)","trace[238389909] 'applied index is now lower than readState.Index' (duration: 811.258µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:53:52.276614Z","caller":"traceutil/trace.go:171","msg":"trace[688925298] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"1.466963001s","start":"2026-03-01T13:53:50.809642Z","end":"2026-03-01T13:53:52.276605Z","steps":["trace[688925298] 'process raft request' (duration: 1.466061662s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:52.276863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.135174ms","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-01T13:53:52.276968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:50.809611Z","time spent":"1.467068681s","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T13:53:52.276979Z","caller":"traceutil/trace.go:171","msg":"trace[2074975178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:427; }","duration":"650.358226ms","start":"2026-03-01T13:53:51.626596Z","end":"2026-03-01T13:53:52.276955Z","steps":["trace[2074975178] 'agreement among raft nodes before linearized reading' (duration: 650.127305ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:52.277037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:51.626576Z","time spent":"650.445947ms","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-01T13:53:52.277196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.672756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T13:53:52.277204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.479707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-01T13:53:52.27724Z","caller":"traceutil/trace.go:171","msg":"trace[1567524063] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"719.751797ms","start":"2026-03-01T13:53:51.557474Z","end":"2026-03-01T13:53:52.277226Z","steps":["trace[1567524063] 'agreement among raft nodes before linearized reading' (duration: 719.683686ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:52.277285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:51.557455Z","time spent":"719.819397ms","remote":"127.0.0.1:54574","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-01T13:53:52.277321Z","caller":"traceutil/trace.go:171","msg":"trace[1091910774] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:427; }","duration":"650.621118ms","start":"2026-03-01T13:53:51.626639Z","end":"2026-03-01T13:53:52.277261Z","steps":["trace[1091910774] 'agreement among raft nodes before linearized reading' (duration: 650.451957ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:53:52.277459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:53:51.626619Z","time spent":"650.82509ms","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-01T13:53:53.966042Z","caller":"traceutil/trace.go:171","msg":"trace[2022115187] transaction","detail":"{read_only:false; response_revision:448; number_of_response:1; }","duration":"247.918037ms","start":"2026-03-01T13:53:53.71811Z","end":"2026-03-01T13:53:53.966028Z","steps":["trace[2022115187] 'process raft request' (duration: 236.084286ms)","trace[2022115187] 'compare' (duration: 11.77714ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:54:06.485847Z","caller":"traceutil/trace.go:171","msg":"trace[1512997098] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"131.17589ms","start":"2026-03-01T13:54:06.354637Z","end":"2026-03-01T13:54:06.485813Z","steps":["trace[1512997098] 'process raft request' (duration: 130.902298ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:54:06.485939Z","caller":"traceutil/trace.go:171","msg":"trace[1385325397] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:542; }","duration":"123.41018ms","start":"2026-03-01T13:54:06.36231Z","end":"2026-03-01T13:54:06.48572Z","steps":["trace[1385325397] 'read index received' (duration: 123.18062ms)","trace[1385325397] 'applied index is now lower than readState.Index' (duration: 228.57µs)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:54:06.485945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.613528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-01T13:54:06.486054Z","caller":"traceutil/trace.go:171","msg":"trace[1651302616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:524; }","duration":"123.767654ms","start":"2026-03-01T13:54:06.362267Z","end":"2026-03-01T13:54:06.486035Z","steps":["trace[1651302616] 'agreement among raft nodes before linearized reading' (duration: 123.583076ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:54:14.681234Z","caller":"traceutil/trace.go:171","msg":"trace[1001968141] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"124.757775ms","start":"2026-03-01T13:54:14.556455Z","end":"2026-03-01T13:54:14.681213Z","steps":["trace[1001968141] 'read index received' (duration: 124.570188ms)","trace[1001968141] 'applied index is now lower than readState.Index' (duration: 186.667µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:54:14.681372Z","caller":"traceutil/trace.go:171","msg":"trace[1776031130] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"163.085158ms","start":"2026-03-01T13:54:14.518243Z","end":"2026-03-01T13:54:14.681328Z","steps":["trace[1776031130] 'process raft request' (duration: 162.838448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:14.681749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.282945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:54:14.681821Z","caller":"traceutil/trace.go:171","msg":"trace[371989830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"125.394951ms","start":"2026-03-01T13:54:14.556408Z","end":"2026-03-01T13:54:14.681803Z","steps":["trace[371989830] 'agreement among raft nodes before linearized reading' (duration: 125.282006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:16.026147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.880166ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-01T13:54:16.026448Z","caller":"traceutil/trace.go:171","msg":"trace[1715799597] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"1.339612518s","start":"2026-03-01T13:54:14.686612Z","end":"2026-03-01T13:54:16.026224Z","steps":["trace[1715799597] 'process raft request' (duration: 814.570686ms)","trace[1715799597] 'compare' (duration: 524.343965ms)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:54:16.02678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:14.686593Z","time spent":"1.339904458s","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T13:54:16.059084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540989109387220,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-01T13:54:16.074653Z","caller":"traceutil/trace.go:171","msg":"trace[1720699280] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.387804232s","start":"2026-03-01T13:54:14.686787Z","end":"2026-03-01T13:54:16.074591Z","steps":["trace[1720699280] 'process raft request' (duration: 1.387635725s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:16.074803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:14.686762Z","time spent":"1.387961678s","remote":"127.0.0.1:54866","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-01T13:54:16.075169Z","caller":"traceutil/trace.go:171","msg":"trace[1562738006] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"1.388355043s","start":"2026-03-01T13:54:14.686795Z","end":"2026-03-01T13:54:16.07515Z","steps":["trace[1562738006] 'process raft request' (duration: 1.387758989s)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:54:16.075203Z","caller":"traceutil/trace.go:171","msg":"trace[24685705] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:591; }","duration":"516.865389ms","start":"2026-03-01T13:54:15.55832Z","end":"2026-03-01T13:54:16.075186Z","steps":["trace[24685705] 'read index received' (duration: 516.058128ms)","trace[24685705] 'applied index is now lower than readState.Index' (duration: 805.681µs)"],"step_count":2} {"level":"warn","ts":"2026-03-01T13:54:16.07527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:14.686783Z","time spent":"1.388435736s","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T13:54:16.075306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.021244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:54:16.075344Z","caller":"traceutil/trace.go:171","msg":"trace[1352145586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:573; }","duration":"517.137179ms","start":"2026-03-01T13:54:15.558195Z","end":"2026-03-01T13:54:16.075332Z","steps":["trace[1352145586] 'agreement among raft nodes before linearized reading' (duration: 517.050595ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:16.075378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:15.558165Z","time spent":"517.204932ms","remote":"127.0.0.1:54574","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-01T13:54:16.075563Z","caller":"traceutil/trace.go:171","msg":"trace[1175842557] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"1.377706897s","start":"2026-03-01T13:54:14.697825Z","end":"2026-03-01T13:54:16.075532Z","steps":["trace[1175842557] 'process raft request' (duration: 1.377068323s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:16.075699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:14.697815Z","time spent":"1.377809041s","remote":"127.0.0.1:54784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T13:54:16.97878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.956782ms","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-01T13:54:16.978884Z","caller":"traceutil/trace.go:171","msg":"trace[1095319849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"293.373577ms","start":"2026-03-01T13:54:16.685486Z","end":"2026-03-01T13:54:16.97886Z","steps":["trace[1095319849] 'range keys from in-memory index tree' (duration: 292.857708ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:54:18.49224Z","caller":"traceutil/trace.go:171","msg":"trace[1438229336] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"412.131721ms","start":"2026-03-01T13:54:18.08008Z","end":"2026-03-01T13:54:18.492212Z","steps":["trace[1438229336] 'read index received' (duration: 411.902933ms)","trace[1438229336] 'applied index is now lower than readState.Index' (duration: 227.498µs)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:54:18.492269Z","caller":"traceutil/trace.go:171","msg":"trace[726533371] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"459.288794ms","start":"2026-03-01T13:54:18.032949Z","end":"2026-03-01T13:54:18.492238Z","steps":["trace[726533371] 'process raft request' (duration: 459.093347ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:18.492547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.953134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-01T13:54:18.492552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.295911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.195\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-01T13:54:18.492594Z","caller":"traceutil/trace.go:171","msg":"trace[351487128] range","detail":"{range_begin:/registry/masterleases/162.253.55.195; range_end:; response_count:1; response_revision:575; }","duration":"405.352553ms","start":"2026-03-01T13:54:18.08723Z","end":"2026-03-01T13:54:18.492583Z","steps":["trace[351487128] 'agreement among raft nodes before linearized reading' (duration: 405.189626ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:54:18.492592Z","caller":"traceutil/trace.go:171","msg":"trace[644596375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:575; }","duration":"412.028247ms","start":"2026-03-01T13:54:18.08055Z","end":"2026-03-01T13:54:18.492578Z","steps":["trace[644596375] 'agreement among raft nodes before linearized reading' (duration: 411.820149ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:18.49258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.470494ms","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-01T13:54:18.492622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:18.087223Z","time spent":"405.392494ms","remote":"127.0.0.1:54618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.195\" "} {"level":"warn","ts":"2026-03-01T13:54:18.492638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:18.080544Z","time spent":"412.082459ms","remote":"127.0.0.1:54866","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":"info","ts":"2026-03-01T13:54:18.492655Z","caller":"traceutil/trace.go:171","msg":"trace[1514026984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:575; }","duration":"412.589939ms","start":"2026-03-01T13:54:18.080046Z","end":"2026-03-01T13:54:18.492636Z","steps":["trace[1514026984] 'agreement among raft nodes before linearized reading' (duration: 412.317088ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:18.492699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:18.08003Z","time spent":"412.660442ms","remote":"127.0.0.1:54866","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":"warn","ts":"2026-03-01T13:54:18.492996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:18.032933Z","time spent":"459.41596ms","remote":"127.0.0.1:54866","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-01T13:54:19.464977Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1673540989109387234,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-01T13:54:19.87565Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.068804674s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-01T13:54:20.002328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.23911ms","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-01T13:54:20.00242Z","caller":"traceutil/trace.go:171","msg":"trace[2002598224] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:575; }","duration":"115.329633ms","start":"2026-03-01T13:54:19.887069Z","end":"2026-03-01T13:54:20.002399Z","steps":["trace[2002598224] 'range keys from in-memory index tree' (duration: 115.218249ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:20.003403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196566158s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-03-01T13:54:20.003913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:18.494415Z","time spent":"1.509490615s","remote":"127.0.0.1:54618","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-01T13:54:20.004155Z","caller":"traceutil/trace.go:171","msg":"trace[474075] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:596; }","duration":"1.039905045s","start":"2026-03-01T13:54:18.964215Z","end":"2026-03-01T13:54:20.00412Z","steps":["trace[474075] 'read index received' (duration: 911.5298ms)","trace[474075] 'applied index is now lower than readState.Index' (duration: 128.167348ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:54:20.004472Z","caller":"traceutil/trace.go:171","msg":"trace[1386844980] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"1.506477379s","start":"2026-03-01T13:54:18.49798Z","end":"2026-03-01T13:54:20.004457Z","steps":["trace[1386844980] 'process raft request' (duration: 1.505737851s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:20.004527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:18.497954Z","time spent":"1.506536001s","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T13:54:20.004518Z","caller":"traceutil/trace.go:171","msg":"trace[1991441599] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"1.506949786s","start":"2026-03-01T13:54:18.497518Z","end":"2026-03-01T13:54:20.004468Z","steps":["trace[1991441599] 'process raft request' (duration: 1.505941939s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:20.005161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.04079269s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:54:20.005191Z","caller":"traceutil/trace.go:171","msg":"trace[264666824] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"1.041013287s","start":"2026-03-01T13:54:18.964167Z","end":"2026-03-01T13:54:20.00518Z","steps":["trace[264666824] 'agreement among raft nodes before linearized reading' (duration: 1.04053512s)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:20.005215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:18.964146Z","time spent":"1.041058969s","remote":"127.0.0.1:54574","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-01T13:54:20.004708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:18.497492Z","time spent":"1.507095092s","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-01T13:54:20.005294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.170678ms","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-01T13:54:20.005306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.504815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-01T13:54:20.005368Z","caller":"traceutil/trace.go:171","msg":"trace[1804249299] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:577; }","duration":"944.287542ms","start":"2026-03-01T13:54:19.06106Z","end":"2026-03-01T13:54:20.005347Z","steps":["trace[1804249299] 'agreement among raft nodes before linearized reading' (duration: 944.082544ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:54:20.00539Z","caller":"traceutil/trace.go:171","msg":"trace[554327732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:577; }","duration":"449.606608ms","start":"2026-03-01T13:54:19.555763Z","end":"2026-03-01T13:54:20.005369Z","steps":["trace[554327732] 'agreement among raft nodes before linearized reading' (duration: 449.493254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:20.005407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:19.061041Z","time spent":"944.357264ms","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-01T13:54:20.005435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:19.555749Z","time spent":"449.675231ms","remote":"127.0.0.1:54566","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-01T13:54:22.186491Z","caller":"traceutil/trace.go:171","msg":"trace[1117804786] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"375.840772ms","start":"2026-03-01T13:54:21.810628Z","end":"2026-03-01T13:54:22.186468Z","steps":["trace[1117804786] 'process raft request' (duration: 375.693766ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:22.186761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:21.810605Z","time spent":"375.957656ms","remote":"127.0.0.1:54866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-01T13:54:22.197316Z","caller":"traceutil/trace.go:171","msg":"trace[1364618594] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"188.609548ms","start":"2026-03-01T13:54:22.008692Z","end":"2026-03-01T13:54:22.197302Z","steps":["trace[1364618594] 'read index received' (duration: 177.759701ms)","trace[1364618594] 'applied index is now lower than readState.Index' (duration: 10.849257ms)"],"step_count":2} {"level":"info","ts":"2026-03-01T13:54:22.197359Z","caller":"traceutil/trace.go:171","msg":"trace[1522824652] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"346.923358ms","start":"2026-03-01T13:54:21.85041Z","end":"2026-03-01T13:54:22.197333Z","steps":["trace[1522824652] 'process raft request' (duration: 346.764712ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:54:22.197398Z","caller":"traceutil/trace.go:171","msg":"trace[1812079808] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"146.436063ms","start":"2026-03-01T13:54:22.050947Z","end":"2026-03-01T13:54:22.197383Z","steps":["trace[1812079808] 'process raft request' (duration: 146.33393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:22.197449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.740023ms","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-01T13:54:22.197473Z","caller":"traceutil/trace.go:171","msg":"trace[874592218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"188.813475ms","start":"2026-03-01T13:54:22.008651Z","end":"2026-03-01T13:54:22.197465Z","steps":["trace[874592218] 'agreement among raft nodes before linearized reading' (duration: 188.732912ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:22.197498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-01T13:54:21.850391Z","time spent":"347.047141ms","remote":"127.0.0.1:54634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4580,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-01T13:54:22.197544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.118547ms","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-01T13:54:22.197576Z","caller":"traceutil/trace.go:171","msg":"trace[1635689756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"186.171848ms","start":"2026-03-01T13:54:22.011395Z","end":"2026-03-01T13:54:22.197567Z","steps":["trace[1635689756] 'agreement among raft nodes before linearized reading' (duration: 186.101765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:54:22.197644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.854231ms","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-01T13:54:22.197659Z","caller":"traceutil/trace.go:171","msg":"trace[1427499798] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"163.870181ms","start":"2026-03-01T13:54:22.033784Z","end":"2026-03-01T13:54:22.197654Z","steps":["trace[1427499798] 'agreement among raft nodes before linearized reading' (duration: 163.84117ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:54:25.061729Z","caller":"traceutil/trace.go:171","msg":"trace[413230930] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"185.686757ms","start":"2026-03-01T13:54:24.876007Z","end":"2026-03-01T13:54:25.061694Z","steps":["trace[413230930] 'process raft request' (duration: 185.375226ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:55:50.184905Z","caller":"traceutil/trace.go:171","msg":"trace[590987034] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"244.465412ms","start":"2026-03-01T13:55:49.940412Z","end":"2026-03-01T13:55:50.184877Z","steps":["trace[590987034] 'process raft request' (duration: 244.291129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:55:50.857376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.946598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-01T13:55:50.857461Z","caller":"traceutil/trace.go:171","msg":"trace[188041617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1026; }","duration":"179.087881ms","start":"2026-03-01T13:55:50.678354Z","end":"2026-03-01T13:55:50.857442Z","steps":["trace[188041617] 'range keys from in-memory index tree' (duration: 178.742505ms)"],"step_count":1} {"level":"warn","ts":"2026-03-01T13:55:50.85785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.561989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-03-01T13:55:50.857866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.607839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-01T13:55:50.857885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.134072ms","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-01T13:55:50.857931Z","caller":"traceutil/trace.go:171","msg":"trace[849340073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1026; }","duration":"179.663081ms","start":"2026-03-01T13:55:50.678245Z","end":"2026-03-01T13:55:50.857908Z","steps":["trace[849340073] 'range keys from in-memory index tree' (duration: 179.399476ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:55:50.857943Z","caller":"traceutil/trace.go:171","msg":"trace[1255697602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1026; }","duration":"184.254214ms","start":"2026-03-01T13:55:50.673672Z","end":"2026-03-01T13:55:50.857926Z","steps":["trace[1255697602] 'range keys from in-memory index tree' (duration: 183.854767ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:55:50.858584Z","caller":"traceutil/trace.go:171","msg":"trace[1738157744] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1026; }","duration":"298.718651ms","start":"2026-03-01T13:55:50.559201Z","end":"2026-03-01T13:55:50.857919Z","steps":["trace[1738157744] 'range keys from in-memory index tree' (duration: 298.492137ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:55:50.983493Z","caller":"traceutil/trace.go:171","msg":"trace[1530475370] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"120.043449ms","start":"2026-03-01T13:55:50.863421Z","end":"2026-03-01T13:55:50.983465Z","steps":["trace[1530475370] 'process raft request' (duration: 110.66412ms)"],"step_count":1} {"level":"info","ts":"2026-03-01T13:55:50.984005Z","caller":"traceutil/trace.go:171","msg":"trace[1237833246] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"119.359226ms","start":"2026-03-01T13:55:50.864628Z","end":"2026-03-01T13:55:50.983987Z","steps":["trace[1237833246] 'process raft request' (duration: 118.773876ms)"],"step_count":1}