{"level":"warn","ts":"2026-03-25T07:30:10.870976Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T07:30:10.871124Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.83:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.83:2380","--initial-cluster=instance=https://199.19.213.83:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.83:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.83:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-25T07:30:10.871244Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-25T07:30:10.871265Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.83:2380"]} {"level":"info","ts":"2026-03-25T07:30:10.871295Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-25T07:30:10.872321Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"]} {"level":"info","ts":"2026-03-25T07:30:10.872449Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.83:2380"],"listen-peer-urls":["https://199.19.213.83:2380"],"advertise-client-urls":["https://199.19.213.83:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.83:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-25T07:30:10.880181Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.446409ms"} {"level":"info","ts":"2026-03-25T07:30:10.900201Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5887d25060a34f1b","cluster-id":"8c4d9ec591b76d84"} {"level":"info","ts":"2026-03-25T07:30:10.900284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=()"} {"level":"info","ts":"2026-03-25T07:30:10.900304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became follower at term 0"} {"level":"info","ts":"2026-03-25T07:30:10.900313Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5887d25060a34f1b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-25T07:30:10.900325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became follower at term 1"} {"level":"info","ts":"2026-03-25T07:30:10.900359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=(6379298639854128923)"} {"level":"warn","ts":"2026-03-25T07:30:10.91249Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-25T07:30:10.91488Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-25T07:30:10.916317Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-25T07:30:10.920308Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5887d25060a34f1b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-25T07:30:10.920614Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5887d25060a34f1b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-25T07:30:10.920782Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-25T07:30:10.920897Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-25T07:30:10.920912Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-25T07:30:10.923489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=(6379298639854128923)"} {"level":"info","ts":"2026-03-25T07:30:10.92372Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8c4d9ec591b76d84","local-member-id":"5887d25060a34f1b","added-peer-id":"5887d25060a34f1b","added-peer-peer-urls":["https://199.19.213.83:2380"]} {"level":"info","ts":"2026-03-25T07:30:10.926286Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-25T07:30:10.926521Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.83:2380"} {"level":"info","ts":"2026-03-25T07:30:10.926574Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.83:2380"} {"level":"info","ts":"2026-03-25T07:30:10.926596Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5887d25060a34f1b","initial-advertise-peer-urls":["https://199.19.213.83:2380"],"listen-peer-urls":["https://199.19.213.83:2380"],"advertise-client-urls":["https://199.19.213.83:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-25T07:30:10.926675Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-25T07:30:11.301175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b is starting a new election at term 1"} {"level":"info","ts":"2026-03-25T07:30:11.30124Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became pre-candidate at term 1"} {"level":"info","ts":"2026-03-25T07:30:11.301276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b received MsgPreVoteResp from 5887d25060a34f1b at term 1"} {"level":"info","ts":"2026-03-25T07:30:11.30129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became candidate at term 2"} {"level":"info","ts":"2026-03-25T07:30:11.301297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b received MsgVoteResp from 5887d25060a34f1b at term 2"} {"level":"info","ts":"2026-03-25T07:30:11.301306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became leader at term 2"} {"level":"info","ts":"2026-03-25T07:30:11.301318Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5887d25060a34f1b elected leader 5887d25060a34f1b at term 2"} {"level":"info","ts":"2026-03-25T07:30:11.316102Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5887d25060a34f1b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.83:2379]}","request-path":"/0/members/5887d25060a34f1b/attributes","cluster-id":"8c4d9ec591b76d84","publish-timeout":"7s"} {"level":"info","ts":"2026-03-25T07:30:11.316376Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T07:30:11.316452Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-25T07:30:11.316696Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T07:30:11.318036Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-25T07:30:11.318067Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-25T07:30:11.319281Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.83:2379"} {"level":"info","ts":"2026-03-25T07:30:11.320291Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-25T07:30:11.356461Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8c4d9ec591b76d84","local-member-id":"5887d25060a34f1b","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T07:30:11.356602Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T07:30:11.356636Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-25T07:30:12.79324Z","caller":"traceutil/trace.go:171","msg":"trace[1376016337] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"109.420977ms","start":"2026-03-25T07:30:12.683785Z","end":"2026-03-25T07:30:12.793206Z","steps":["trace[1376016337] 'process raft request' (duration: 109.182073ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:12.79339Z","caller":"traceutil/trace.go:171","msg":"trace[1748418537] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"109.122513ms","start":"2026-03-25T07:30:12.684255Z","end":"2026-03-25T07:30:12.793378Z","steps":["trace[1748418537] 'process raft request' (duration: 108.874359ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:12.793494Z","caller":"traceutil/trace.go:171","msg":"trace[1865144400] linearizableReadLoop","detail":"{readStateIndex:8; appliedIndex:6; }","duration":"109.395287ms","start":"2026-03-25T07:30:12.684079Z","end":"2026-03-25T07:30:12.793475Z","steps":["trace[1865144400] 'read index received' (duration: 84.923111ms)","trace[1865144400] 'applied index is now lower than readState.Index' (duration: 24.471216ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:30:12.793258Z","caller":"traceutil/trace.go:171","msg":"trace[90770447] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"109.062211ms","start":"2026-03-25T07:30:12.684168Z","end":"2026-03-25T07:30:12.79323Z","steps":["trace[90770447] 'process raft request' (duration: 108.914259ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:12.793586Z","caller":"traceutil/trace.go:171","msg":"trace[15462866] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"101.852788ms","start":"2026-03-25T07:30:12.691722Z","end":"2026-03-25T07:30:12.793574Z","steps":["trace[15462866] 'process raft request' (duration: 101.655185ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:12.79335Z","caller":"traceutil/trace.go:171","msg":"trace[48639800] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"109.020021ms","start":"2026-03-25T07:30:12.684318Z","end":"2026-03-25T07:30:12.793338Z","steps":["trace[48639800] 'process raft request' (duration: 108.854759ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:12.793721Z","caller":"traceutil/trace.go:171","msg":"trace[1377288821] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"109.263055ms","start":"2026-03-25T07:30:12.684448Z","end":"2026-03-25T07:30:12.793712Z","steps":["trace[1377288821] 'process raft request' (duration: 108.754337ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:12.793734Z","caller":"traceutil/trace.go:171","msg":"trace[1116211602] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"101.419401ms","start":"2026-03-25T07:30:12.692269Z","end":"2026-03-25T07:30:12.793688Z","steps":["trace[1116211602] 'process raft request' (duration: 101.168697ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:12.793795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.686992ms","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-25T07:30:12.793905Z","caller":"traceutil/trace.go:171","msg":"trace[1078051865] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:12; }","duration":"109.814374ms","start":"2026-03-25T07:30:12.684075Z","end":"2026-03-25T07:30:12.79389Z","steps":["trace[1078051865] 'agreement among raft nodes before linearized reading' (duration: 109.61001ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:12.793901Z","caller":"traceutil/trace.go:171","msg":"trace[2042225773] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"109.366947ms","start":"2026-03-25T07:30:12.684477Z","end":"2026-03-25T07:30:12.793844Z","steps":["trace[2042225773] 'process raft request' (duration: 108.778868ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:13.198952Z","caller":"traceutil/trace.go:171","msg":"trace[2014362255] linearizableReadLoop","detail":"{readStateIndex:62; appliedIndex:60; }","duration":"249.182812ms","start":"2026-03-25T07:30:12.94972Z","end":"2026-03-25T07:30:13.198903Z","steps":["trace[2014362255] 'read index received' (duration: 25.209327ms)","trace[2014362255] 'applied index is now lower than readState.Index' (duration: 223.970425ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:30:13.198984Z","caller":"traceutil/trace.go:171","msg":"trace[1699378813] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"263.456088ms","start":"2026-03-25T07:30:12.9355Z","end":"2026-03-25T07:30:13.198956Z","steps":["trace[1699378813] 'process raft request' (duration: 212.618211ms)","trace[1699378813] 'compare' (duration: 50.620853ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:30:13.199065Z","caller":"traceutil/trace.go:171","msg":"trace[1015579448] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"220.262096ms","start":"2026-03-25T07:30:12.978775Z","end":"2026-03-25T07:30:13.199037Z","steps":["trace[1015579448] 'process raft request' (duration: 220.075513ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:13.199155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.418326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-25T07:30:13.199207Z","caller":"traceutil/trace.go:171","msg":"trace[72423861] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:60; }","duration":"249.508387ms","start":"2026-03-25T07:30:12.949679Z","end":"2026-03-25T07:30:13.199187Z","steps":["trace[72423861] 'agreement among raft nodes before linearized reading' (duration: 249.353625ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:14.022993Z","caller":"traceutil/trace.go:171","msg":"trace[26242294] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"316.61643ms","start":"2026-03-25T07:30:13.706353Z","end":"2026-03-25T07:30:14.022969Z","steps":["trace[26242294] 'process raft request' (duration: 289.941584ms)","trace[26242294] 'compare' (duration: 26.549044ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:30:14.02339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:13.706318Z","time spent":"316.748502ms","remote":"127.0.0.1:37846","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2012,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:30:15.107833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.911627ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:30:15.108063Z","caller":"traceutil/trace.go:171","msg":"trace[543962991] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"113.746428ms","start":"2026-03-25T07:30:14.994287Z","end":"2026-03-25T07:30:15.108034Z","steps":["trace[543962991] 'compare' (duration: 107.648853ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:15.26954Z","caller":"traceutil/trace.go:171","msg":"trace[1562729293] transaction","detail":"{read_only:false; number_of_response:0; response_revision:207; }","duration":"180.855454ms","start":"2026-03-25T07:30:15.088658Z","end":"2026-03-25T07:30:15.269513Z","steps":["trace[1562729293] 'process raft request' (duration: 180.762143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:15.269744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.736087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:30:15.269558Z","caller":"traceutil/trace.go:171","msg":"trace[154795139] linearizableReadLoop","detail":"{readStateIndex:213; appliedIndex:213; }","duration":"157.550894ms","start":"2026-03-25T07:30:15.111986Z","end":"2026-03-25T07:30:15.269536Z","steps":["trace[154795139] 'read index received' (duration: 157.541204ms)","trace[154795139] 'applied index is now lower than readState.Index' (duration: 8.18µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:30:15.269881Z","caller":"traceutil/trace.go:171","msg":"trace[901024479] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:0; response_revision:207; }","duration":"157.947861ms","start":"2026-03-25T07:30:15.111879Z","end":"2026-03-25T07:30:15.269827Z","steps":["trace[901024479] 'agreement among raft nodes before linearized reading' (duration: 157.722017ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:15.278019Z","caller":"traceutil/trace.go:171","msg":"trace[1165075357] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"147.995067ms","start":"2026-03-25T07:30:15.130005Z","end":"2026-03-25T07:30:15.278Z","steps":["trace[1165075357] 'process raft request' (duration: 147.875415ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:21.501418Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700322530886375221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:30:22.002411Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700322530886375221,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:30:22.24463Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.309076092s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T07:30:22.245821Z","caller":"traceutil/trace.go:171","msg":"trace[1788927726] linearizableReadLoop","detail":"{readStateIndex:360; appliedIndex:359; }","duration":"1.245467231s","start":"2026-03-25T07:30:21.000329Z","end":"2026-03-25T07:30:22.245797Z","steps":["trace[1788927726] 'read index received' (duration: 1.2453367s)","trace[1788927726] 'applied index is now lower than readState.Index' (duration: 129.381µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:30:22.246196Z","caller":"traceutil/trace.go:171","msg":"trace[1950168047] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"1.310742707s","start":"2026-03-25T07:30:20.93544Z","end":"2026-03-25T07:30:22.246183Z","steps":["trace[1950168047] 'process raft request' (duration: 1.310237489s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:22.246561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246235473s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-25T07:30:22.246664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:20.935427Z","time spent":"1.310809448s","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:30:22.246713Z","caller":"traceutil/trace.go:171","msg":"trace[527241784] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:349; }","duration":"1.246363825s","start":"2026-03-25T07:30:21.000248Z","end":"2026-03-25T07:30:22.246612Z","steps":["trace[527241784] 'agreement among raft nodes before linearized reading' (duration: 1.246242113s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:22.246756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:21.000215Z","time spent":"1.246530578s","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-25T07:30:22.246808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.683224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-25T07:30:22.246868Z","caller":"traceutil/trace.go:171","msg":"trace[2116755379] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:349; }","duration":"777.773485ms","start":"2026-03-25T07:30:21.469078Z","end":"2026-03-25T07:30:22.246851Z","steps":["trace[2116755379] 'agreement among raft nodes before linearized reading' (duration: 777.663273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:22.246906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:21.469063Z","time spent":"777.833206ms","remote":"127.0.0.1:37764","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-25T07:30:22.246934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.026588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-25T07:30:22.247005Z","caller":"traceutil/trace.go:171","msg":"trace[366807859] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:349; }","duration":"780.182751ms","start":"2026-03-25T07:30:21.466803Z","end":"2026-03-25T07:30:22.246985Z","steps":["trace[366807859] 'agreement among raft nodes before linearized reading' (duration: 779.963767ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:22.24705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:21.466786Z","time spent":"780.255331ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-25T07:30:22.247394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086793978s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-03-25T07:30:22.247461Z","caller":"traceutil/trace.go:171","msg":"trace[525083965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:349; }","duration":"1.08693753s","start":"2026-03-25T07:30:21.160509Z","end":"2026-03-25T07:30:22.247446Z","steps":["trace[525083965] 'agreement among raft nodes before linearized reading' (duration: 1.08620887s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:22.247496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:21.160478Z","time spent":"1.087010982s","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T07:30:22.835061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.733203ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:30:22.835182Z","caller":"traceutil/trace.go:171","msg":"trace[512962550] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"582.925078ms","start":"2026-03-25T07:30:22.252241Z","end":"2026-03-25T07:30:22.835166Z","steps":["trace[512962550] 'process raft request' (duration: 212.027333ms)","trace[512962550] 'compare' (duration: 370.392578ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:30:22.835229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:22.252084Z","time spent":"583.124911ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:30:22.835398Z","caller":"traceutil/trace.go:171","msg":"trace[1380889735] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"582.601993ms","start":"2026-03-25T07:30:22.252773Z","end":"2026-03-25T07:30:22.835375Z","steps":["trace[1380889735] 'process raft request' (duration: 582.35703ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:22.835523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:22.252765Z","time spent":"582.694094ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:30:22.835653Z","caller":"traceutil/trace.go:171","msg":"trace[881195254] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"581.872363ms","start":"2026-03-25T07:30:22.253756Z","end":"2026-03-25T07:30:22.835628Z","steps":["trace[881195254] 'process raft request' (duration: 581.544678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:22.835856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:22.253749Z","time spent":"581.965164ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:30:22.836698Z","caller":"traceutil/trace.go:171","msg":"trace[1421062958] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:360; }","duration":"583.686559ms","start":"2026-03-25T07:30:22.252999Z","end":"2026-03-25T07:30:22.836686Z","steps":["trace[1421062958] 'read index received' (duration: 211.415504ms)","trace[1421062958] 'applied index is now lower than readState.Index' (duration: 372.270055ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:30:22.836805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.802361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"info","ts":"2026-03-25T07:30:22.836811Z","caller":"traceutil/trace.go:171","msg":"trace[1526412469] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"582.903037ms","start":"2026-03-25T07:30:22.253888Z","end":"2026-03-25T07:30:22.836791Z","steps":["trace[1526412469] 'process raft request' (duration: 581.65725ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:22.836829Z","caller":"traceutil/trace.go:171","msg":"trace[1996708526] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:353; }","duration":"583.849071ms","start":"2026-03-25T07:30:22.252973Z","end":"2026-03-25T07:30:22.836822Z","steps":["trace[1996708526] 'agreement among raft nodes before linearized reading' (duration: 583.7559ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:22.836866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:22.252906Z","time spent":"583.948022ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5349,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-03-25T07:30:22.836908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:22.253866Z","time spent":"582.988279ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:30:29.370034Z","caller":"traceutil/trace.go:171","msg":"trace[157237206] linearizableReadLoop","detail":"{readStateIndex:385; appliedIndex:384; }","duration":"501.204133ms","start":"2026-03-25T07:30:28.868807Z","end":"2026-03-25T07:30:29.370011Z","steps":["trace[157237206] 'read index received' (duration: 500.937348ms)","trace[157237206] 'applied index is now lower than readState.Index' (duration: 265.915µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:30:29.370238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.408436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-25T07:30:29.370303Z","caller":"traceutil/trace.go:171","msg":"trace[547365443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:372; }","duration":"501.523228ms","start":"2026-03-25T07:30:28.868763Z","end":"2026-03-25T07:30:29.370287Z","steps":["trace[547365443] 'agreement among raft nodes before linearized reading' (duration: 501.391036ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:29.370103Z","caller":"traceutil/trace.go:171","msg":"trace[1003928819] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"622.723726ms","start":"2026-03-25T07:30:28.747355Z","end":"2026-03-25T07:30:29.370078Z","steps":["trace[1003928819] 'process raft request' (duration: 622.492282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:29.370479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.260614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-25T07:30:29.370491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.465286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-25T07:30:29.370542Z","caller":"traceutil/trace.go:171","msg":"trace[1892790414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:372; }","duration":"501.517117ms","start":"2026-03-25T07:30:28.869015Z","end":"2026-03-25T07:30:29.370532Z","steps":["trace[1892790414] 'agreement among raft nodes before linearized reading' (duration: 501.433586ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:29.370547Z","caller":"traceutil/trace.go:171","msg":"trace[25952322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:372; }","duration":"501.349645ms","start":"2026-03-25T07:30:28.869179Z","end":"2026-03-25T07:30:29.370528Z","steps":["trace[25952322] 'agreement among raft nodes before linearized reading' (duration: 501.246994ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:29.370534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.518327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-25T07:30:29.370577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:28.868922Z","time spent":"501.64701ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-25T07:30:29.370595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:28.869169Z","time spent":"501.413606ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-25T07:30:29.370609Z","caller":"traceutil/trace.go:171","msg":"trace[878493038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:372; }","duration":"501.640849ms","start":"2026-03-25T07:30:28.868952Z","end":"2026-03-25T07:30:29.370592Z","steps":["trace[878493038] 'agreement among raft nodes before linearized reading' (duration: 501.470557ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:29.37068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:28.747341Z","time spent":"623.134331ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7279,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:30:29.370408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:28.868744Z","time spent":"501.649879ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T07:30:29.370854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:28.86894Z","time spent":"501.69898ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-25T07:30:35.643536Z","caller":"traceutil/trace.go:171","msg":"trace[1455466295] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:436; }","duration":"153.937802ms","start":"2026-03-25T07:30:35.489553Z","end":"2026-03-25T07:30:35.64349Z","steps":["trace[1455466295] 'read index received' (duration: 152.894588ms)","trace[1455466295] 'applied index is now lower than readState.Index' (duration: 1.041624ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:30:35.643587Z","caller":"traceutil/trace.go:171","msg":"trace[1771840946] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"184.396896ms","start":"2026-03-25T07:30:35.459142Z","end":"2026-03-25T07:30:35.643538Z","steps":["trace[1771840946] 'process raft request' (duration: 183.244361ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:35.643687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.110734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:30:35.643743Z","caller":"traceutil/trace.go:171","msg":"trace[989489750] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"154.219756ms","start":"2026-03-25T07:30:35.489514Z","end":"2026-03-25T07:30:35.643734Z","steps":["trace[989489750] 'agreement among raft nodes before linearized reading' (duration: 154.101314ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:35.643585Z","caller":"traceutil/trace.go:171","msg":"trace[1694800295] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"182.933266ms","start":"2026-03-25T07:30:35.460629Z","end":"2026-03-25T07:30:35.643562Z","steps":["trace[1694800295] 'process raft request' (duration: 182.791944ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:45.693419Z","caller":"traceutil/trace.go:171","msg":"trace[1616653890] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:494; }","duration":"139.301191ms","start":"2026-03-25T07:30:45.554101Z","end":"2026-03-25T07:30:45.693402Z","steps":["trace[1616653890] 'read index received' (duration: 139.120618ms)","trace[1616653890] 'applied index is now lower than readState.Index' (duration: 179.923µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:30:45.693481Z","caller":"traceutil/trace.go:171","msg":"trace[492069424] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"174.300552ms","start":"2026-03-25T07:30:45.519145Z","end":"2026-03-25T07:30:45.693445Z","steps":["trace[492069424] 'process raft request' (duration: 174.145079ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:45.693553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.437513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-25T07:30:45.693586Z","caller":"traceutil/trace.go:171","msg":"trace[498513710] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:477; }","duration":"139.512193ms","start":"2026-03-25T07:30:45.554063Z","end":"2026-03-25T07:30:45.693575Z","steps":["trace[498513710] 'agreement among raft nodes before linearized reading' (duration: 139.439922ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:52.989499Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700322530886375554,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:30:53.490357Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700322530886375554,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-25T07:30:53.602945Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.576933287s","expected-duration":"1s"} {"level":"info","ts":"2026-03-25T07:30:53.603295Z","caller":"traceutil/trace.go:171","msg":"trace[83266940] linearizableReadLoop","detail":"{readStateIndex:539; appliedIndex:538; }","duration":"1.113900973s","start":"2026-03-25T07:30:52.489374Z","end":"2026-03-25T07:30:53.603275Z","steps":["trace[83266940] 'read index received' (duration: 1.113709281s)","trace[83266940] 'applied index is now lower than readState.Index' (duration: 190.882µs)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:30:53.603394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113998144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:30:53.603433Z","caller":"traceutil/trace.go:171","msg":"trace[260600712] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:520; }","duration":"1.114077116s","start":"2026-03-25T07:30:52.489345Z","end":"2026-03-25T07:30:53.603422Z","steps":["trace[260600712] 'agreement among raft nodes before linearized reading' (duration: 1.113990085s)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:53.603431Z","caller":"traceutil/trace.go:171","msg":"trace[1579546244] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.577571624s","start":"2026-03-25T07:30:52.02583Z","end":"2026-03-25T07:30:53.603402Z","steps":["trace[1579546244] 'process raft request' (duration: 1.577305221s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:53.603467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:52.489327Z","time spent":"1.114132486s","remote":"127.0.0.1:37528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-25T07:30:53.603577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:52.025803Z","time spent":"1.577698557s","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:30:54.346825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.875548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-25T07:30:54.346863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.562154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-25T07:30:54.346876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.644506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-25T07:30:54.346905Z","caller":"traceutil/trace.go:171","msg":"trace[82880345] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"605.0337ms","start":"2026-03-25T07:30:53.741851Z","end":"2026-03-25T07:30:54.346885Z","steps":["trace[82880345] 'range keys from in-memory index tree' (duration: 604.723735ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:54.346928Z","caller":"traceutil/trace.go:171","msg":"trace[1376051647] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:520; }","duration":"608.683395ms","start":"2026-03-25T07:30:53.738228Z","end":"2026-03-25T07:30:54.346911Z","steps":["trace[1376051647] 'range keys from in-memory index tree' (duration: 608.28908ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:54.346936Z","caller":"traceutil/trace.go:171","msg":"trace[808731002] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:520; }","duration":"596.725917ms","start":"2026-03-25T07:30:53.750194Z","end":"2026-03-25T07:30:54.34692Z","steps":["trace[808731002] 'range keys from in-memory index tree' (duration: 596.458644ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:54.346951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:53.741843Z","time spent":"605.09892ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-25T07:30:54.346965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:53.738208Z","time spent":"608.749867ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T07:30:54.346976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:53.750185Z","time spent":"596.780548ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-25T07:30:54.347152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.924436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-25T07:30:54.34718Z","caller":"traceutil/trace.go:171","msg":"trace[530358223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"573.981206ms","start":"2026-03-25T07:30:53.773192Z","end":"2026-03-25T07:30:54.347173Z","steps":["trace[530358223] 'range keys from in-memory index tree' (duration: 573.792254ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:54.3472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:53.773176Z","time spent":"574.019257ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-25T07:30:54.381219Z","caller":"traceutil/trace.go:171","msg":"trace[1139730102] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"314.605985ms","start":"2026-03-25T07:30:54.066589Z","end":"2026-03-25T07:30:54.381195Z","steps":["trace[1139730102] 'process raft request' (duration: 314.432993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:54.381385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:54.066556Z","time spent":"314.746477ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:30:54.710041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.606582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:30:54.710424Z","caller":"traceutil/trace.go:171","msg":"trace[1637855814] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"359.301024ms","start":"2026-03-25T07:30:54.351092Z","end":"2026-03-25T07:30:54.710393Z","steps":["trace[1637855814] 'process raft request' (duration: 359.088501ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:54.71051Z","caller":"traceutil/trace.go:171","msg":"trace[1662251179] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"359.301094ms","start":"2026-03-25T07:30:54.351189Z","end":"2026-03-25T07:30:54.71049Z","steps":["trace[1662251179] 'process raft request' (duration: 359.092311ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:30:54.710533Z","caller":"traceutil/trace.go:171","msg":"trace[1067834536] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"359.994542ms","start":"2026-03-25T07:30:54.350496Z","end":"2026-03-25T07:30:54.71049Z","steps":["trace[1067834536] 'process raft request' (duration: 207.803123ms)","trace[1067834536] 'compare' (duration: 151.41272ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:30:54.710608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:54.351156Z","time spent":"359.405685ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:30:54.710653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:54.35108Z","time spent":"359.456036ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:30:54.710684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:54.350474Z","time spent":"360.137424ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-25T07:30:54.710785Z","caller":"traceutil/trace.go:171","msg":"trace[982736334] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"359.251233ms","start":"2026-03-25T07:30:54.351178Z","end":"2026-03-25T07:30:54.710429Z","steps":["trace[982736334] 'process raft request' (duration: 359.067101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:54.710921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:54.351155Z","time spent":"359.660438ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-25T07:30:56.681193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189342705s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:30:56.68127Z","caller":"traceutil/trace.go:171","msg":"trace[1704161850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"1.189462446s","start":"2026-03-25T07:30:55.491791Z","end":"2026-03-25T07:30:56.681253Z","steps":["trace[1704161850] 'range keys from in-memory index tree' (duration: 1.189264794s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:56.681304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:55.491773Z","time spent":"1.189521697s","remote":"127.0.0.1:37514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-25T07:30:56.681327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.792997583s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.83\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-25T07:30:56.68145Z","caller":"traceutil/trace.go:171","msg":"trace[1582701221] range","detail":"{range_begin:/registry/masterleases/199.19.213.83; range_end:; response_count:1; response_revision:525; }","duration":"1.793136294s","start":"2026-03-25T07:30:54.888266Z","end":"2026-03-25T07:30:56.681402Z","steps":["trace[1582701221] 'range keys from in-memory index tree' (duration: 1.792904771s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:56.681503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:54.888252Z","time spent":"1.793241345s","remote":"127.0.0.1:37552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.83\" "} {"level":"warn","ts":"2026-03-25T07:30:59.717637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273282104s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:30:59.717729Z","caller":"traceutil/trace.go:171","msg":"trace[1636909735] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:559; }","duration":"1.227206619s","start":"2026-03-25T07:30:58.490511Z","end":"2026-03-25T07:30:59.717718Z","steps":["trace[1636909735] 'read index received' (duration: 46.021µs)","trace[1636909735] 'applied index is now lower than readState.Index' (duration: 1.227159508s)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:30:59.717784Z","caller":"traceutil/trace.go:171","msg":"trace[2007091892] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"1.423462595s","start":"2026-03-25T07:30:58.294313Z","end":"2026-03-25T07:30:59.717776Z","steps":["trace[2007091892] 'process raft request' (duration: 149.987978ms)","trace[2007091892] 'compare' (duration: 1.273006391s)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:30:59.717811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:58.294295Z","time spent":"1.423502646s","remote":"127.0.0.1:37592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":856,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:30:59.71795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.227444212s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:30:59.717963Z","caller":"traceutil/trace.go:171","msg":"trace[1180394301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:540; }","duration":"1.227501072s","start":"2026-03-25T07:30:58.490458Z","end":"2026-03-25T07:30:59.717959Z","steps":["trace[1180394301] 'agreement among raft nodes before linearized reading' (duration: 1.227450291s)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:59.717977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:58.490437Z","time spent":"1.227537682s","remote":"127.0.0.1:37514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-25T07:30:59.718107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.705396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-25T07:30:59.718121Z","caller":"traceutil/trace.go:171","msg":"trace[1862442572] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:540; }","duration":"980.753287ms","start":"2026-03-25T07:30:58.737364Z","end":"2026-03-25T07:30:59.718117Z","steps":["trace[1862442572] 'agreement among raft nodes before linearized reading' (duration: 980.683266ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:59.718132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:58.737344Z","time spent":"980.785597ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-25T07:30:59.718484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.771901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-25T07:30:59.71851Z","caller":"traceutil/trace.go:171","msg":"trace[110778888] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:540; }","duration":"962.830921ms","start":"2026-03-25T07:30:58.755674Z","end":"2026-03-25T07:30:59.718504Z","steps":["trace[110778888] 'agreement among raft nodes before linearized reading' (duration: 962.76722ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:59.718524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:58.755658Z","time spent":"962.862671ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-25T07:30:59.718519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.943694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-25T07:30:59.718618Z","caller":"traceutil/trace.go:171","msg":"trace[1329538000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:540; }","duration":"964.084716ms","start":"2026-03-25T07:30:58.754509Z","end":"2026-03-25T07:30:59.718593Z","steps":["trace[1329538000] 'agreement among raft nodes before linearized reading' (duration: 963.732162ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:59.718639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.378382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5sddh\" ","response":"range_response_count:1 size:17520"} {"level":"warn","ts":"2026-03-25T07:30:59.718679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:58.754489Z","time spent":"964.178607ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-25T07:30:59.718742Z","caller":"traceutil/trace.go:171","msg":"trace[886378784] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5sddh; range_end:; response_count:1; response_revision:540; }","duration":"458.535185ms","start":"2026-03-25T07:30:59.26018Z","end":"2026-03-25T07:30:59.718716Z","steps":["trace[886378784] 'agreement among raft nodes before linearized reading' (duration: 458.276081ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:59.718804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:59.260162Z","time spent":"458.628055ms","remote":"127.0.0.1:37684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":17543,"request content":"key:\"/registry/pods/kube-system/cilium-5sddh\" "} {"level":"warn","ts":"2026-03-25T07:30:59.718647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"962.825351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-25T07:30:59.71891Z","caller":"traceutil/trace.go:171","msg":"trace[1380350027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:540; }","duration":"963.136615ms","start":"2026-03-25T07:30:58.755751Z","end":"2026-03-25T07:30:59.718888Z","steps":["trace[1380350027] 'agreement among raft nodes before linearized reading' (duration: 962.689189ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:30:59.719005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:30:58.755731Z","time spent":"963.243366ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-25T07:31:05.98842Z","caller":"traceutil/trace.go:171","msg":"trace[352746547] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"110.126537ms","start":"2026-03-25T07:31:05.878265Z","end":"2026-03-25T07:31:05.988392Z","steps":["trace[352746547] 'process raft request' (duration: 109.947125ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:08.148915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.755107ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-25T07:31:08.148958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.27546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-25T07:31:08.149027Z","caller":"traceutil/trace.go:171","msg":"trace[1547156021] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:644; }","duration":"116.875189ms","start":"2026-03-25T07:31:08.032133Z","end":"2026-03-25T07:31:08.149008Z","steps":["trace[1547156021] 'range keys from in-memory index tree' (duration: 116.744997ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:31:08.149043Z","caller":"traceutil/trace.go:171","msg":"trace[50748689] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:644; }","duration":"335.454203ms","start":"2026-03-25T07:31:07.813571Z","end":"2026-03-25T07:31:08.149025Z","steps":["trace[50748689] 'range keys from in-memory index tree' (duration: 335.098868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:08.149081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:31:07.813552Z","time spent":"335.519833ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-25T07:31:08.1895Z","caller":"traceutil/trace.go:171","msg":"trace[66530993] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"368.236461ms","start":"2026-03-25T07:31:07.821247Z","end":"2026-03-25T07:31:08.189484Z","steps":["trace[66530993] 'read index received' (duration: 368.17116ms)","trace[66530993] 'applied index is now lower than readState.Index' (duration: 64.711µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:31:08.189586Z","caller":"traceutil/trace.go:171","msg":"trace[1610377963] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"368.438764ms","start":"2026-03-25T07:31:07.821139Z","end":"2026-03-25T07:31:08.189578Z","steps":["trace[1610377963] 'process raft request' (duration: 368.246111ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:08.18965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:31:07.821119Z","time spent":"368.487434ms","remote":"127.0.0.1:42690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2259,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-25T07:31:08.189811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.557294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-25T07:31:08.189841Z","caller":"traceutil/trace.go:171","msg":"trace[652122161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"368.606584ms","start":"2026-03-25T07:31:07.82123Z","end":"2026-03-25T07:31:08.189836Z","steps":["trace[652122161] 'agreement among raft nodes before linearized reading' (duration: 368.498343ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:08.18986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:31:07.821222Z","time spent":"368.634765ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-25T07:31:08.379076Z","caller":"traceutil/trace.go:171","msg":"trace[1708017220] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"189.504259ms","start":"2026-03-25T07:31:08.189553Z","end":"2026-03-25T07:31:08.379058Z","steps":["trace[1708017220] 'read index received' (duration: 138.328197ms)","trace[1708017220] 'applied index is now lower than readState.Index' (duration: 51.175302ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:31:08.379268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.487806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-25T07:31:08.379309Z","caller":"traceutil/trace.go:171","msg":"trace[589349935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:647; }","duration":"557.547657ms","start":"2026-03-25T07:31:07.821752Z","end":"2026-03-25T07:31:08.379299Z","steps":["trace[589349935] 'agreement among raft nodes before linearized reading' (duration: 557.380095ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:08.379341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:31:07.821721Z","time spent":"557.611767ms","remote":"127.0.0.1:37764","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-25T07:31:08.379661Z","caller":"traceutil/trace.go:171","msg":"trace[408806604] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"359.741905ms","start":"2026-03-25T07:31:08.019909Z","end":"2026-03-25T07:31:08.379651Z","steps":["trace[408806604] 'process raft request' (duration: 307.998666ms)","trace[408806604] 'compare' (duration: 50.918819ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:31:08.37974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:31:08.019888Z","time spent":"359.812136ms","remote":"127.0.0.1:42728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1671,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-25T07:31:08.379937Z","caller":"traceutil/trace.go:171","msg":"trace[470380747] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"227.451467ms","start":"2026-03-25T07:31:08.152473Z","end":"2026-03-25T07:31:08.379925Z","steps":["trace[470380747] 'process raft request' (duration: 226.520326ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:08.380103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.228232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5sddh\" ","response":"range_response_count:1 size:18709"} {"level":"info","ts":"2026-03-25T07:31:08.380132Z","caller":"traceutil/trace.go:171","msg":"trace[1924583117] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5sddh; range_end:; response_count:1; response_revision:647; }","duration":"213.258802ms","start":"2026-03-25T07:31:08.166867Z","end":"2026-03-25T07:31:08.380126Z","steps":["trace[1924583117] 'agreement among raft nodes before linearized reading' (duration: 213.177651ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:08.380596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.711189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-25T07:31:08.380673Z","caller":"traceutil/trace.go:171","msg":"trace[1046164298] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:647; }","duration":"557.80328ms","start":"2026-03-25T07:31:07.822852Z","end":"2026-03-25T07:31:08.380655Z","steps":["trace[1046164298] 'agreement among raft nodes before linearized reading' (duration: 557.635518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:08.38072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-25T07:31:07.822793Z","time spent":"557.918ms","remote":"127.0.0.1:37764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-25T07:31:08.381041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.973487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-03-25T07:31:08.381128Z","caller":"traceutil/trace.go:171","msg":"trace[84745273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:647; }","duration":"244.094409ms","start":"2026-03-25T07:31:08.137019Z","end":"2026-03-25T07:31:08.381114Z","steps":["trace[84745273] 'agreement among raft nodes before linearized reading' (duration: 243.29323ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:31:08.390732Z","caller":"traceutil/trace.go:171","msg":"trace[448809156] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"196.174935ms","start":"2026-03-25T07:31:08.194531Z","end":"2026-03-25T07:31:08.390706Z","steps":["trace[448809156] 'process raft request' (duration: 195.901432ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:31:08.390607Z","caller":"traceutil/trace.go:171","msg":"trace[218932205] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"139.941216ms","start":"2026-03-25T07:31:08.250649Z","end":"2026-03-25T07:31:08.39059Z","steps":["trace[218932205] 'process raft request' (duration: 139.899835ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:31:08.576958Z","caller":"traceutil/trace.go:171","msg":"trace[15457235] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"183.450388ms","start":"2026-03-25T07:31:08.393483Z","end":"2026-03-25T07:31:08.576933Z","steps":["trace[15457235] 'process raft request' (duration: 95.483193ms)","trace[15457235] 'compare' (duration: 87.861884ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:31:08.591086Z","caller":"traceutil/trace.go:171","msg":"trace[827870966] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"171.103475ms","start":"2026-03-25T07:31:08.419952Z","end":"2026-03-25T07:31:08.591056Z","steps":["trace[827870966] 'process raft request' (duration: 170.918563ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:31:18.561453Z","caller":"traceutil/trace.go:171","msg":"trace[1910713842] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"139.716702ms","start":"2026-03-25T07:31:18.421701Z","end":"2026-03-25T07:31:18.561418Z","steps":["trace[1910713842] 'process raft request' (duration: 139.591021ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:31:18.681356Z","caller":"traceutil/trace.go:171","msg":"trace[1987987854] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"243.987571ms","start":"2026-03-25T07:31:18.437342Z","end":"2026-03-25T07:31:18.68133Z","steps":["trace[1987987854] 'process raft request' (duration: 243.787418ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:31:18.681517Z","caller":"traceutil/trace.go:171","msg":"trace[1533855340] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"202.43853ms","start":"2026-03-25T07:31:18.479071Z","end":"2026-03-25T07:31:18.68151Z","steps":["trace[1533855340] 'process raft request' (duration: 202.196077ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:31:18.682013Z","caller":"traceutil/trace.go:171","msg":"trace[86538363] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:760; }","duration":"243.394805ms","start":"2026-03-25T07:31:18.438051Z","end":"2026-03-25T07:31:18.681446Z","steps":["trace[86538363] 'read index received' (duration: 123.26744ms)","trace[86538363] 'applied index is now lower than readState.Index' (duration: 120.125795ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:31:18.682462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.209832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-25T07:31:18.682492Z","caller":"traceutil/trace.go:171","msg":"trace[1636497243] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:738; }","duration":"234.271663ms","start":"2026-03-25T07:31:18.448209Z","end":"2026-03-25T07:31:18.68248Z","steps":["trace[1636497243] 'agreement among raft nodes before linearized reading' (duration: 234.055891ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:18.682551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.320667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-25T07:31:18.682606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.123353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-25T07:31:18.682641Z","caller":"traceutil/trace.go:171","msg":"trace[1606331396] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:738; }","duration":"237.429968ms","start":"2026-03-25T07:31:18.445191Z","end":"2026-03-25T07:31:18.682621Z","steps":["trace[1606331396] 'agreement among raft nodes before linearized reading' (duration: 237.129845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:18.682706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.230327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:31:18.682656Z","caller":"traceutil/trace.go:171","msg":"trace[780318742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:738; }","duration":"235.194504ms","start":"2026-03-25T07:31:18.44745Z","end":"2026-03-25T07:31:18.682644Z","steps":["trace[780318742] 'agreement among raft nodes before linearized reading' (duration: 235.026452ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:31:18.682733Z","caller":"traceutil/trace.go:171","msg":"trace[963042818] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:738; }","duration":"193.284247ms","start":"2026-03-25T07:31:18.489434Z","end":"2026-03-25T07:31:18.682718Z","steps":["trace[963042818] 'agreement among raft nodes before linearized reading' (duration: 193.170886ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:31:18.683233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.194035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-25T07:31:18.683264Z","caller":"traceutil/trace.go:171","msg":"trace[835392488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:738; }","duration":"245.257725ms","start":"2026-03-25T07:31:18.438Z","end":"2026-03-25T07:31:18.683257Z","steps":["trace[835392488] 'agreement among raft nodes before linearized reading' (duration: 245.179054ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:32:14.22283Z","caller":"traceutil/trace.go:171","msg":"trace[395900337] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"118.154335ms","start":"2026-03-25T07:32:14.104619Z","end":"2026-03-25T07:32:14.222774Z","steps":["trace[395900337] 'process raft request' (duration: 117.937053ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:32:14.402273Z","caller":"traceutil/trace.go:171","msg":"trace[743575063] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:940; }","duration":"113.056287ms","start":"2026-03-25T07:32:14.289163Z","end":"2026-03-25T07:32:14.40222Z","steps":["trace[743575063] 'read index received' (duration: 52.389753ms)","trace[743575063] 'applied index is now lower than readState.Index' (duration: 60.664674ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:32:14.402317Z","caller":"traceutil/trace.go:171","msg":"trace[1933852443] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"116.035855ms","start":"2026-03-25T07:32:14.28623Z","end":"2026-03-25T07:32:14.402266Z","steps":["trace[1933852443] 'process raft request' (duration: 55.379502ms)","trace[1933852443] 'compare' (duration: 60.421961ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:32:14.402515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.307339ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:32:14.402582Z","caller":"traceutil/trace.go:171","msg":"trace[957921926] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:904; }","duration":"113.44582ms","start":"2026-03-25T07:32:14.289117Z","end":"2026-03-25T07:32:14.402563Z","steps":["trace[957921926] 'agreement among raft nodes before linearized reading' (duration: 113.280418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:15.238804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.235194ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-25T07:32:15.238911Z","caller":"traceutil/trace.go:171","msg":"trace[1667992235] linearizableReadLoop","detail":"{readStateIndex:947; appliedIndex:946; }","duration":"218.93681ms","start":"2026-03-25T07:32:15.019957Z","end":"2026-03-25T07:32:15.238894Z","steps":["trace[1667992235] 'read index received' (duration: 96.539596ms)","trace[1667992235] 'applied index is now lower than readState.Index' (duration: 122.396084ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:32:15.239Z","caller":"traceutil/trace.go:171","msg":"trace[1392481775] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"271.398865ms","start":"2026-03-25T07:32:14.967537Z","end":"2026-03-25T07:32:15.238936Z","steps":["trace[1392481775] 'process raft request' (duration: 148.96347ms)","trace[1392481775] 'compare' (duration: 122.075642ms)"],"step_count":2} {"level":"warn","ts":"2026-03-25T07:32:15.239165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.30465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-03-25T07:32:15.239163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.873698ms","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-25T07:32:15.239208Z","caller":"traceutil/trace.go:171","msg":"trace[350906686] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:909; }","duration":"205.40082ms","start":"2026-03-25T07:32:15.033795Z","end":"2026-03-25T07:32:15.239195Z","steps":["trace[350906686] 'agreement among raft nodes before linearized reading' (duration: 205.214258ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:32:15.239211Z","caller":"traceutil/trace.go:171","msg":"trace[2089545420] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:909; }","duration":"201.039449ms","start":"2026-03-25T07:32:15.038162Z","end":"2026-03-25T07:32:15.239201Z","steps":["trace[2089545420] 'agreement among raft nodes before linearized reading' (duration: 200.887988ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:15.239289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.377483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-25T07:32:15.239431Z","caller":"traceutil/trace.go:171","msg":"trace[1526372514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:909; }","duration":"201.515654ms","start":"2026-03-25T07:32:15.037859Z","end":"2026-03-25T07:32:15.239374Z","steps":["trace[1526372514] 'agreement among raft nodes before linearized reading' (duration: 201.219571ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:15.239622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.669497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-25T07:32:15.23969Z","caller":"traceutil/trace.go:171","msg":"trace[1863655622] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:909; }","duration":"219.743588ms","start":"2026-03-25T07:32:15.019903Z","end":"2026-03-25T07:32:15.239647Z","steps":["trace[1863655622] 'agreement among raft nodes before linearized reading' (duration: 219.072412ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:32:15.459253Z","caller":"traceutil/trace.go:171","msg":"trace[1325988742] transaction","detail":"{read_only:false; response_revision:913; number_of_response:1; }","duration":"215.195124ms","start":"2026-03-25T07:32:15.244027Z","end":"2026-03-25T07:32:15.459222Z","steps":["trace[1325988742] 'process raft request' (duration: 215.077103ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:32:15.459295Z","caller":"traceutil/trace.go:171","msg":"trace[30186947] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"215.200624ms","start":"2026-03-25T07:32:15.244049Z","end":"2026-03-25T07:32:15.459249Z","steps":["trace[30186947] 'process raft request' (duration: 215.015952ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:32:15.459455Z","caller":"traceutil/trace.go:171","msg":"trace[675153366] transaction","detail":"{read_only:false; response_revision:914; number_of_response:1; }","duration":"214.092414ms","start":"2026-03-25T07:32:15.245356Z","end":"2026-03-25T07:32:15.459449Z","steps":["trace[675153366] 'process raft request' (duration: 213.785341ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:32:15.459271Z","caller":"traceutil/trace.go:171","msg":"trace[1135820822] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"215.296175ms","start":"2026-03-25T07:32:15.243895Z","end":"2026-03-25T07:32:15.459192Z","steps":["trace[1135820822] 'process raft request' (duration: 189.7793ms)","trace[1135820822] 'compare' (duration: 25.265122ms)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:32:16.07109Z","caller":"traceutil/trace.go:171","msg":"trace[950654878] transaction","detail":"{read_only:false; response_revision:916; number_of_response:1; }","duration":"167.058682ms","start":"2026-03-25T07:32:15.904003Z","end":"2026-03-25T07:32:16.071062Z","steps":["trace[950654878] 'process raft request' (duration: 166.84712ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:32:23.504587Z","caller":"traceutil/trace.go:171","msg":"trace[123455676] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"200.162576ms","start":"2026-03-25T07:32:23.304392Z","end":"2026-03-25T07:32:23.504554Z","steps":["trace[123455676] 'read index received' (duration: 199.847953ms)","trace[123455676] 'applied index is now lower than readState.Index' (duration: 312.003µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:32:23.504869Z","caller":"traceutil/trace.go:171","msg":"trace[1972917568] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"211.461992ms","start":"2026-03-25T07:32:23.293375Z","end":"2026-03-25T07:32:23.504837Z","steps":["trace[1972917568] 'process raft request' (duration: 210.997908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:23.505039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.38646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T07:32:23.505131Z","caller":"traceutil/trace.go:171","msg":"trace[1778764554] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:954; }","duration":"158.557752ms","start":"2026-03-25T07:32:23.346547Z","end":"2026-03-25T07:32:23.505105Z","steps":["trace[1778764554] 'agreement among raft nodes before linearized reading' (duration: 158.38637ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:23.505171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.736892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-25T07:32:23.505299Z","caller":"traceutil/trace.go:171","msg":"trace[1532381739] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:954; }","duration":"200.903243ms","start":"2026-03-25T07:32:23.304368Z","end":"2026-03-25T07:32:23.505272Z","steps":["trace[1532381739] 'agreement among raft nodes before linearized reading' (duration: 200.696791ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:30.332289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.545363ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-25T07:32:43.364249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.227161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:job-controller\" ","response":"range_response_count:1 size:780"} {"level":"info","ts":"2026-03-25T07:32:43.364354Z","caller":"traceutil/trace.go:171","msg":"trace[1277305646] range","detail":"{range_begin:/registry/clusterroles/system:controller:job-controller; range_end:; response_count:1; response_revision:1019; }","duration":"129.355573ms","start":"2026-03-25T07:32:43.234973Z","end":"2026-03-25T07:32:43.364328Z","steps":["trace[1277305646] 'range keys from in-memory index tree' (duration: 129.135531ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:51.474393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.21126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/default/\" range_end:\"/registry/secrets/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:32:51.474512Z","caller":"traceutil/trace.go:171","msg":"trace[1496952891] range","detail":"{range_begin:/registry/secrets/default/; range_end:/registry/secrets/default0; response_count:0; response_revision:1043; }","duration":"107.421231ms","start":"2026-03-25T07:32:51.367068Z","end":"2026-03-25T07:32:51.47449Z","steps":["trace[1496952891] 'range keys from in-memory index tree' (duration: 107.116889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:51.474544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.32679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/default\" ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-03-25T07:32:51.474623Z","caller":"traceutil/trace.go:171","msg":"trace[1193656145] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/default; range_end:; response_count:1; response_revision:1043; }","duration":"109.4096ms","start":"2026-03-25T07:32:51.36519Z","end":"2026-03-25T07:32:51.474599Z","steps":["trace[1193656145] 'range keys from in-memory index tree' (duration: 109.276419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:51.474607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.256567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"warn","ts":"2026-03-25T07:32:51.474532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.840529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/default/\" range_end:\"/registry/roles/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-25T07:32:51.474685Z","caller":"traceutil/trace.go:171","msg":"trace[717470295] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1043; }","duration":"111.366789ms","start":"2026-03-25T07:32:51.363299Z","end":"2026-03-25T07:32:51.474665Z","steps":["trace[717470295] 'range keys from in-memory index tree' (duration: 111.086596ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:32:51.47491Z","caller":"traceutil/trace.go:171","msg":"trace[1390000310] range","detail":"{range_begin:/registry/roles/default/; range_end:/registry/roles/default0; response_count:0; response_revision:1043; }","duration":"105.125791ms","start":"2026-03-25T07:32:51.369578Z","end":"2026-03-25T07:32:51.474703Z","steps":["trace[1390000310] 'range keys from in-memory index tree' (duration: 104.765139ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:51.910813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.529378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-25T07:32:51.910883Z","caller":"traceutil/trace.go:171","msg":"trace[928866494] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1045; }","duration":"125.639749ms","start":"2026-03-25T07:32:51.785227Z","end":"2026-03-25T07:32:51.910866Z","steps":["trace[928866494] 'range keys from in-memory index tree' (duration: 125.381667ms)"],"step_count":1} {"level":"info","ts":"2026-03-25T07:32:57.958368Z","caller":"traceutil/trace.go:171","msg":"trace[310071207] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1106; }","duration":"123.2176ms","start":"2026-03-25T07:32:57.835085Z","end":"2026-03-25T07:32:57.958303Z","steps":["trace[310071207] 'read index received' (duration: 122.858127ms)","trace[310071207] 'applied index is now lower than readState.Index' (duration: 358.303µs)"],"step_count":2} {"level":"info","ts":"2026-03-25T07:32:57.95855Z","caller":"traceutil/trace.go:171","msg":"trace[827273302] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"137.316267ms","start":"2026-03-25T07:32:57.821152Z","end":"2026-03-25T07:32:57.958468Z","steps":["trace[827273302] 'process raft request' (duration: 136.949534ms)"],"step_count":1} {"level":"warn","ts":"2026-03-25T07:32:57.958932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.819376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:72 size:53468"} {"level":"info","ts":"2026-03-25T07:32:57.958994Z","caller":"traceutil/trace.go:171","msg":"trace[1705825361] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:72; response_revision:1061; }","duration":"123.930047ms","start":"2026-03-25T07:32:57.835042Z","end":"2026-03-25T07:32:57.958972Z","steps":["trace[1705825361] 'agreement among raft nodes before linearized reading' (duration: 123.455642ms)"],"step_count":1}