{"level":"warn","ts":"2026-03-26T17:49:21.085042Z","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-26T17:49:21.085141Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.43:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.43:2380","--initial-cluster=instance=https://162.253.55.43:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.43:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.43: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-26T17:49:21.085221Z","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-26T17:49:21.085243Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-03-26T17:49:21.085266Z","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-26T17:49:21.086236Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"]} {"level":"info","ts":"2026-03-26T17:49:21.086346Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.43: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-26T17:49:21.094113Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.978891ms"} {"level":"info","ts":"2026-03-26T17:49:21.1262Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"14f8f2dfdaccebdb","cluster-id":"5f02ccb15672f35d"} {"level":"info","ts":"2026-03-26T17:49:21.126352Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=()"} {"level":"info","ts":"2026-03-26T17:49:21.126401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 0"} {"level":"info","ts":"2026-03-26T17:49:21.126422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 14f8f2dfdaccebdb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-26T17:49:21.126436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became follower at term 1"} {"level":"info","ts":"2026-03-26T17:49:21.126501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"warn","ts":"2026-03-26T17:49:21.187881Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-26T17:49:21.19155Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-26T17:49:21.192878Z","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-26T17:49:21.198775Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"14f8f2dfdaccebdb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-26T17:49:21.198975Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"14f8f2dfdaccebdb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-26T17:49:21.199142Z","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-26T17:49:21.20039Z","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-26T17:49:21.20047Z","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-26T17:49:21.201006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb switched to configuration voters=(1511224718245293019)"} {"level":"info","ts":"2026-03-26T17:49:21.201328Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","added-peer-id":"14f8f2dfdaccebdb","added-peer-peer-urls":["https://162.253.55.43:2380"]} {"level":"info","ts":"2026-03-26T17:49:21.204834Z","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-26T17:49:21.205057Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-03-26T17:49:21.20511Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.43:2380"} {"level":"info","ts":"2026-03-26T17:49:21.20528Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"14f8f2dfdaccebdb","initial-advertise-peer-urls":["https://162.253.55.43:2380"],"listen-peer-urls":["https://162.253.55.43:2380"],"advertise-client-urls":["https://162.253.55.43:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.43:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-26T17:49:21.205344Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-26T17:49:21.527471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb is starting a new election at term 1"} {"level":"info","ts":"2026-03-26T17:49:21.527575Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became pre-candidate at term 1"} {"level":"info","ts":"2026-03-26T17:49:21.52763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgPreVoteResp from 14f8f2dfdaccebdb at term 1"} {"level":"info","ts":"2026-03-26T17:49:21.527646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became candidate at term 2"} {"level":"info","ts":"2026-03-26T17:49:21.527654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb received MsgVoteResp from 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-03-26T17:49:21.527666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"14f8f2dfdaccebdb became leader at term 2"} {"level":"info","ts":"2026-03-26T17:49:21.527676Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 14f8f2dfdaccebdb elected leader 14f8f2dfdaccebdb at term 2"} {"level":"info","ts":"2026-03-26T17:49:21.543263Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T17:49:21.544157Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-26T17:49:21.54416Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"14f8f2dfdaccebdb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.43:2379]}","request-path":"/0/members/14f8f2dfdaccebdb/attributes","cluster-id":"5f02ccb15672f35d","publish-timeout":"7s"} {"level":"info","ts":"2026-03-26T17:49:21.544478Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-26T17:49:21.545052Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5f02ccb15672f35d","local-member-id":"14f8f2dfdaccebdb","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T17:49:21.545945Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T17:49:21.546125Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-26T17:49:21.547102Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.43:2379"} {"level":"info","ts":"2026-03-26T17:49:21.545194Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-26T17:49:21.547402Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-26T17:49:21.549599Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-26T17:49:22.910389Z","caller":"traceutil/trace.go:171","msg":"trace[1412588369] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"101.973635ms","start":"2026-03-26T17:49:22.808399Z","end":"2026-03-26T17:49:22.910373Z","steps":["trace[1412588369] 'read index received' (duration: 101.919775ms)","trace[1412588369] 'applied index is now lower than readState.Index' (duration: 53.15µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:22.910449Z","caller":"traceutil/trace.go:171","msg":"trace[1394809953] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"102.446578ms","start":"2026-03-26T17:49:22.807972Z","end":"2026-03-26T17:49:22.910418Z","steps":["trace[1394809953] 'process raft request' (duration: 102.255227ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:22.910572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.150257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-26T17:49:22.91068Z","caller":"traceutil/trace.go:171","msg":"trace[1202067215] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:2; }","duration":"102.284007ms","start":"2026-03-26T17:49:22.808386Z","end":"2026-03-26T17:49:22.91067Z","steps":["trace[1202067215] 'agreement among raft nodes before linearized reading' (duration: 102.078416ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:22.922262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.389113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-03-26T17:49:22.922328Z","caller":"traceutil/trace.go:171","msg":"trace[1865673344] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"113.478513ms","start":"2026-03-26T17:49:22.80883Z","end":"2026-03-26T17:49:22.922308Z","steps":["trace[1865673344] 'agreement among raft nodes before linearized reading' (duration: 113.332382ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:22.923386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.413048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-26T17:49:22.923457Z","caller":"traceutil/trace.go:171","msg":"trace[155139909] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"114.500969ms","start":"2026-03-26T17:49:22.808941Z","end":"2026-03-26T17:49:22.923442Z","steps":["trace[155139909] 'agreement among raft nodes before linearized reading' (duration: 114.400708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:23.146553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.697502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-26T17:49:23.146635Z","caller":"traceutil/trace.go:171","msg":"trace[2010889840] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"220.792824ms","start":"2026-03-26T17:49:22.925829Z","end":"2026-03-26T17:49:23.146622Z","steps":["trace[2010889840] 'process raft request' (duration: 55.70001ms)","trace[2010889840] 'compare' (duration: 164.582641ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:23.215384Z","caller":"traceutil/trace.go:171","msg":"trace[2124088483] transaction","detail":"{read_only:false; number_of_response:0; response_revision:12; }","duration":"287.635708ms","start":"2026-03-26T17:49:22.927716Z","end":"2026-03-26T17:49:23.215352Z","steps":["trace[2124088483] 'process raft request' (duration: 287.346106ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:23.215531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.198946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-03-26T17:49:23.215521Z","caller":"traceutil/trace.go:171","msg":"trace[753520238] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"286.632203ms","start":"2026-03-26T17:49:22.928813Z","end":"2026-03-26T17:49:23.215445Z","steps":["trace[753520238] 'process raft request' (duration: 286.315591ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.215645Z","caller":"traceutil/trace.go:171","msg":"trace[185067815] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"284.63019ms","start":"2026-03-26T17:49:22.930848Z","end":"2026-03-26T17:49:23.215478Z","steps":["trace[185067815] 'process raft request' (duration: 284.352688ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.215417Z","caller":"traceutil/trace.go:171","msg":"trace[1299904463] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:14; }","duration":"287.081125ms","start":"2026-03-26T17:49:22.928315Z","end":"2026-03-26T17:49:23.215396Z","steps":["trace[1299904463] 'read index received' (duration: 53.276387ms)","trace[1299904463] 'applied index is now lower than readState.Index' (duration: 233.804038ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:23.21575Z","caller":"traceutil/trace.go:171","msg":"trace[165795539] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"280.581176ms","start":"2026-03-26T17:49:22.935133Z","end":"2026-03-26T17:49:23.215714Z","steps":["trace[165795539] 'process raft request' (duration: 280.221894ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.215811Z","caller":"traceutil/trace.go:171","msg":"trace[686789899] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"283.250262ms","start":"2026-03-26T17:49:22.932553Z","end":"2026-03-26T17:49:23.215803Z","steps":["trace[686789899] 'process raft request' (duration: 282.687688ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.21589Z","caller":"traceutil/trace.go:171","msg":"trace[242780103] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"283.621384ms","start":"2026-03-26T17:49:22.932241Z","end":"2026-03-26T17:49:23.215862Z","steps":["trace[242780103] 'process raft request' (duration: 282.98413ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.21557Z","caller":"traceutil/trace.go:171","msg":"trace[452574884] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"287.293546ms","start":"2026-03-26T17:49:22.92827Z","end":"2026-03-26T17:49:23.215563Z","steps":["trace[452574884] 'agreement among raft nodes before linearized reading' (duration: 287.198096ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.215609Z","caller":"traceutil/trace.go:171","msg":"trace[656178407] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"282.561378ms","start":"2026-03-26T17:49:22.933042Z","end":"2026-03-26T17:49:23.215604Z","steps":["trace[656178407] 'process raft request' (duration: 282.234356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:23.216087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.146145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-mnfhc\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-03-26T17:49:23.216183Z","caller":"traceutil/trace.go:171","msg":"trace[1358144910] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-mnfhc; range_end:; response_count:1; response_revision:19; }","duration":"287.256286ms","start":"2026-03-26T17:49:22.928895Z","end":"2026-03-26T17:49:23.216151Z","steps":["trace[1358144910] 'agreement among raft nodes before linearized reading' (duration: 287.053565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:23.216211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.877645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-26T17:49:23.216236Z","caller":"traceutil/trace.go:171","msg":"trace[2107578301] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:19; }","duration":"261.928715ms","start":"2026-03-26T17:49:22.9543Z","end":"2026-03-26T17:49:23.216229Z","steps":["trace[2107578301] 'agreement among raft nodes before linearized reading' (duration: 261.891935ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.215436Z","caller":"traceutil/trace.go:171","msg":"trace[1827996853] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"282.843379ms","start":"2026-03-26T17:49:22.932573Z","end":"2026-03-26T17:49:23.215416Z","steps":["trace[1827996853] 'process raft request' (duration: 282.688399ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:23.216288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.921389ms","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-26T17:49:23.216728Z","caller":"traceutil/trace.go:171","msg":"trace[2066891420] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"281.375201ms","start":"2026-03-26T17:49:22.935344Z","end":"2026-03-26T17:49:23.21672Z","steps":["trace[2066891420] 'agreement among raft nodes before linearized reading' (duration: 280.912628ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:23.216293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.072887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-26T17:49:23.216835Z","caller":"traceutil/trace.go:171","msg":"trace[1144203819] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:0; response_revision:19; }","duration":"235.64932ms","start":"2026-03-26T17:49:22.98118Z","end":"2026-03-26T17:49:23.216829Z","steps":["trace[1144203819] 'agreement among raft nodes before linearized reading' (duration: 235.046976ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.449573Z","caller":"traceutil/trace.go:171","msg":"trace[476098934] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"228.291965ms","start":"2026-03-26T17:49:23.221247Z","end":"2026-03-26T17:49:23.449539Z","steps":["trace[476098934] 'process raft request' (duration: 145.048265ms)","trace[476098934] 'compare' (duration: 83.013359ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:23.449679Z","caller":"traceutil/trace.go:171","msg":"trace[824703154] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"227.911053ms","start":"2026-03-26T17:49:23.221702Z","end":"2026-03-26T17:49:23.449614Z","steps":["trace[824703154] 'process raft request' (duration: 227.735172ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.449831Z","caller":"traceutil/trace.go:171","msg":"trace[1070188794] linearizableReadLoop","detail":"{readStateIndex:32; appliedIndex:24; }","duration":"217.979505ms","start":"2026-03-26T17:49:23.231829Z","end":"2026-03-26T17:49:23.449809Z","steps":["trace[1070188794] 'read index received' (duration: 11.431298ms)","trace[1070188794] 'applied index is now lower than readState.Index' (duration: 206.547107ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:23.449858Z","caller":"traceutil/trace.go:171","msg":"trace[147685236] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"225.755141ms","start":"2026-03-26T17:49:23.224036Z","end":"2026-03-26T17:49:23.449791Z","steps":["trace[147685236] 'process raft request' (duration: 225.55199ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.45006Z","caller":"traceutil/trace.go:171","msg":"trace[274208483] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"226.065442ms","start":"2026-03-26T17:49:23.223975Z","end":"2026-03-26T17:49:23.45004Z","steps":["trace[274208483] 'process raft request' (duration: 225.57756ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.450097Z","caller":"traceutil/trace.go:171","msg":"trace[3697673] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"225.916842ms","start":"2026-03-26T17:49:23.224171Z","end":"2026-03-26T17:49:23.450088Z","steps":["trace[3697673] 'process raft request' (duration: 225.517519ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.450119Z","caller":"traceutil/trace.go:171","msg":"trace[1470877677] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"218.195886ms","start":"2026-03-26T17:49:23.2319Z","end":"2026-03-26T17:49:23.450096Z","steps":["trace[1470877677] 'process raft request' (duration: 217.868354ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.450193Z","caller":"traceutil/trace.go:171","msg":"trace[596842705] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"218.460258ms","start":"2026-03-26T17:49:23.231635Z","end":"2026-03-26T17:49:23.450096Z","steps":["trace[596842705] 'process raft request' (duration: 218.109336ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.450302Z","caller":"traceutil/trace.go:171","msg":"trace[1962799696] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"224.681734ms","start":"2026-03-26T17:49:23.225608Z","end":"2026-03-26T17:49:23.45029Z","steps":["trace[1962799696] 'process raft request' (duration: 224.108531ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:23.450341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.973127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-26T17:49:23.450369Z","caller":"traceutil/trace.go:171","msg":"trace[166102505] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:29; }","duration":"176.034727ms","start":"2026-03-26T17:49:23.274326Z","end":"2026-03-26T17:49:23.450361Z","steps":["trace[166102505] 'agreement among raft nodes before linearized reading' (duration: 175.915307ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.449833Z","caller":"traceutil/trace.go:171","msg":"trace[2137678666] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"227.165729ms","start":"2026-03-26T17:49:23.222639Z","end":"2026-03-26T17:49:23.449805Z","steps":["trace[2137678666] 'process raft request' (duration: 226.858307ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:23.450544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.651629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-26T17:49:23.450603Z","caller":"traceutil/trace.go:171","msg":"trace[1531662543] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:29; }","duration":"218.78035ms","start":"2026-03-26T17:49:23.231805Z","end":"2026-03-26T17:49:23.450585Z","steps":["trace[1531662543] 'agreement among raft nodes before linearized reading' (duration: 218.552558ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.762239Z","caller":"traceutil/trace.go:171","msg":"trace[1192200301] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"305.585541ms","start":"2026-03-26T17:49:23.45662Z","end":"2026-03-26T17:49:23.762205Z","steps":["trace[1192200301] 'process raft request' (duration: 209.117433ms)","trace[1192200301] 'compare' (duration: 96.257057ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:23.762421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:23.456601Z","time spent":"305.732852ms","remote":"127.0.0.1:39512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T17:49:23.764347Z","caller":"traceutil/trace.go:171","msg":"trace[2102024273] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"307.371681ms","start":"2026-03-26T17:49:23.456936Z","end":"2026-03-26T17:49:23.764307Z","steps":["trace[2102024273] 'process raft request' (duration: 307.16063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:23.764514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:23.456928Z","time spent":"307.505792ms","remote":"127.0.0.1:39432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T17:49:23.764511Z","caller":"traceutil/trace.go:171","msg":"trace[623698892] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"307.520812ms","start":"2026-03-26T17:49:23.45696Z","end":"2026-03-26T17:49:23.76448Z","steps":["trace[623698892] 'process raft request' (duration: 307.273081ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.76457Z","caller":"traceutil/trace.go:171","msg":"trace[2021470690] linearizableReadLoop","detail":"{readStateIndex:38; appliedIndex:33; }","duration":"307.291701ms","start":"2026-03-26T17:49:23.45726Z","end":"2026-03-26T17:49:23.764552Z","steps":["trace[2021470690] 'read index received' (duration: 208.585239ms)","trace[2021470690] 'applied index is now lower than readState.Index' (duration: 98.705372ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:23.764674Z","caller":"traceutil/trace.go:171","msg":"trace[670695217] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"307.518022ms","start":"2026-03-26T17:49:23.457143Z","end":"2026-03-26T17:49:23.764661Z","steps":["trace[670695217] 'process raft request' (duration: 307.14656ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:23.764764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:23.457136Z","time spent":"307.580173ms","remote":"127.0.0.1:39512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T17:49:23.764667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:23.456937Z","time spent":"307.647583ms","remote":"127.0.0.1:39512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T17:49:23.764769Z","caller":"traceutil/trace.go:171","msg":"trace[520012121] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"306.759407ms","start":"2026-03-26T17:49:23.457911Z","end":"2026-03-26T17:49:23.76467Z","steps":["trace[520012121] 'process raft request' (duration: 306.582486ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:23.765035Z","caller":"traceutil/trace.go:171","msg":"trace[315639579] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"307.836834ms","start":"2026-03-26T17:49:23.457186Z","end":"2026-03-26T17:49:23.765022Z","steps":["trace[315639579] 'process raft request' (duration: 307.149689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:23.765112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:23.457177Z","time spent":"307.897574ms","remote":"127.0.0.1:39512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T17:49:23.765238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:23.457904Z","time spent":"307.12514ms","remote":"127.0.0.1:39512","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T17:49:23.76529Z","caller":"traceutil/trace.go:171","msg":"trace[1203592589] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"307.857944ms","start":"2026-03-26T17:49:23.457417Z","end":"2026-03-26T17:49:23.765275Z","steps":["trace[1203592589] 'process raft request' (duration: 307.017219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:23.765645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:23.457393Z","time spent":"308.188126ms","remote":"127.0.0.1:39098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3004,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T17:49:23.765385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.344996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-26T17:49:23.765855Z","caller":"traceutil/trace.go:171","msg":"trace[1864514270] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:36; }","duration":"257.93564ms","start":"2026-03-26T17:49:23.507902Z","end":"2026-03-26T17:49:23.765838Z","steps":["trace[1864514270] 'agreement among raft nodes before linearized reading' (duration: 257.066545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:23.765447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.100006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-26T17:49:23.766004Z","caller":"traceutil/trace.go:171","msg":"trace[1668497144] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:36; }","duration":"308.752449ms","start":"2026-03-26T17:49:23.457239Z","end":"2026-03-26T17:49:23.765991Z","steps":["trace[1668497144] 'agreement among raft nodes before linearized reading' (duration: 308.094075ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:23.766056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:23.457232Z","time spent":"308.7953ms","remote":"127.0.0.1:39080","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" "} {"level":"warn","ts":"2026-03-26T17:49:24.095314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.904609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-26T17:49:24.095393Z","caller":"traceutil/trace.go:171","msg":"trace[1969384288] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:47; }","duration":"104.058391ms","start":"2026-03-26T17:49:23.991316Z","end":"2026-03-26T17:49:24.095374Z","steps":["trace[1969384288] 'range keys from in-memory index tree' (duration: 103.823629ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:24.095515Z","caller":"traceutil/trace.go:171","msg":"trace[1986976397] transaction","detail":"{read_only:false; response_revision:48; number_of_response:1; }","duration":"103.811838ms","start":"2026-03-26T17:49:23.991667Z","end":"2026-03-26T17:49:24.095479Z","steps":["trace[1986976397] 'process raft request' (duration: 51.14403ms)","trace[1986976397] 'compare' (duration: 52.482577ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:24.131659Z","caller":"traceutil/trace.go:171","msg":"trace[122881521] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"139.786909ms","start":"2026-03-26T17:49:23.991839Z","end":"2026-03-26T17:49:24.131626Z","steps":["trace[122881521] 'process raft request' (duration: 139.633508ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:24.131721Z","caller":"traceutil/trace.go:171","msg":"trace[119788579] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"137.925659ms","start":"2026-03-26T17:49:23.993774Z","end":"2026-03-26T17:49:24.1317Z","steps":["trace[119788579] 'process raft request' (duration: 137.771968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:24.373835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.485015ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-03-26T17:49:24.374051Z","caller":"traceutil/trace.go:171","msg":"trace[1701824957] linearizableReadLoop","detail":"{readStateIndex:62; appliedIndex:59; }","duration":"197.209096ms","start":"2026-03-26T17:49:24.176825Z","end":"2026-03-26T17:49:24.374035Z","steps":["trace[1701824957] 'read index received' (duration: 30.325138ms)","trace[1701824957] 'applied index is now lower than readState.Index' (duration: 166.883078ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:24.374161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.325996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a0756c16e86479\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-03-26T17:49:24.374197Z","caller":"traceutil/trace.go:171","msg":"trace[1023955383] range","detail":"{range_begin:/registry/events/default/instance.18a0756c16e86479; range_end:; response_count:1; response_revision:57; }","duration":"197.384597ms","start":"2026-03-26T17:49:24.176801Z","end":"2026-03-26T17:49:24.374186Z","steps":["trace[1023955383] 'agreement among raft nodes before linearized reading' (duration: 197.287026ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:24.374544Z","caller":"traceutil/trace.go:171","msg":"trace[1672760473] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"200.829547ms","start":"2026-03-26T17:49:24.173703Z","end":"2026-03-26T17:49:24.374532Z","steps":["trace[1672760473] 'process raft request' (duration: 33.443246ms)","trace[1672760473] 'compare' (duration: 166.362325ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:24.374669Z","caller":"traceutil/trace.go:171","msg":"trace[2112281114] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"198.425903ms","start":"2026-03-26T17:49:24.176236Z","end":"2026-03-26T17:49:24.374662Z","steps":["trace[2112281114] 'process raft request' (duration: 197.696149ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:24.374854Z","caller":"traceutil/trace.go:171","msg":"trace[1220311737] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"198.513043ms","start":"2026-03-26T17:49:24.176327Z","end":"2026-03-26T17:49:24.37484Z","steps":["trace[1220311737] 'process raft request' (duration: 197.672258ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:24.484892Z","caller":"traceutil/trace.go:171","msg":"trace[86227110] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"105.655849ms","start":"2026-03-26T17:49:24.379179Z","end":"2026-03-26T17:49:24.484835Z","steps":["trace[86227110] 'process raft request' (duration: 105.554658ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:24.484875Z","caller":"traceutil/trace.go:171","msg":"trace[857196604] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"106.679455ms","start":"2026-03-26T17:49:24.378156Z","end":"2026-03-26T17:49:24.484835Z","steps":["trace[857196604] 'process raft request' (duration: 102.814772ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:24.484904Z","caller":"traceutil/trace.go:171","msg":"trace[1965341219] transaction","detail":"{read_only:false; response_revision:60; number_of_response:1; }","duration":"105.710909ms","start":"2026-03-26T17:49:24.37916Z","end":"2026-03-26T17:49:24.484871Z","steps":["trace[1965341219] 'process raft request' (duration: 105.521118ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:25.237627Z","caller":"traceutil/trace.go:171","msg":"trace[1071825774] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"100.249514ms","start":"2026-03-26T17:49:25.137352Z","end":"2026-03-26T17:49:25.237602Z","steps":["trace[1071825774] 'process raft request' (duration: 100.203174ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:25.237694Z","caller":"traceutil/trace.go:171","msg":"trace[21607723] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"100.358304ms","start":"2026-03-26T17:49:25.137296Z","end":"2026-03-26T17:49:25.237654Z","steps":["trace[21607723] 'process raft request' (duration: 48.17894ms)","trace[21607723] 'compare' (duration: 51.907693ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:25.8472Z","caller":"traceutil/trace.go:171","msg":"trace[359611067] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"145.742989ms","start":"2026-03-26T17:49:25.701435Z","end":"2026-03-26T17:49:25.847178Z","steps":["trace[359611067] 'process raft request' (duration: 73.038425ms)","trace[359611067] 'compare' (duration: 72.586743ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:26.584795Z","caller":"traceutil/trace.go:171","msg":"trace[587863678] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"139.798869ms","start":"2026-03-26T17:49:26.444973Z","end":"2026-03-26T17:49:26.584772Z","steps":["trace[587863678] 'process raft request' (duration: 128.668925ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:26.789557Z","caller":"traceutil/trace.go:171","msg":"trace[1702270784] transaction","detail":"{read_only:false; response_revision:186; number_of_response:1; }","duration":"104.945338ms","start":"2026-03-26T17:49:26.68458Z","end":"2026-03-26T17:49:26.789525Z","steps":["trace[1702270784] 'process raft request' (duration: 22.50479ms)","trace[1702270784] 'compare' (duration: 82.298417ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:27.43621Z","caller":"traceutil/trace.go:171","msg":"trace[1665517136] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"107.901532ms","start":"2026-03-26T17:49:27.328278Z","end":"2026-03-26T17:49:27.436179Z","steps":["trace[1665517136] 'process raft request' (duration: 25.804219ms)","trace[1665517136] 'compare' (duration: 81.920582ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:28.213683Z","caller":"traceutil/trace.go:171","msg":"trace[289392302] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"130.449918ms","start":"2026-03-26T17:49:28.083197Z","end":"2026-03-26T17:49:28.213647Z","steps":["trace[289392302] 'process raft request' (duration: 72.832548ms)","trace[289392302] 'compare' (duration: 57.281678ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:28.546551Z","caller":"traceutil/trace.go:171","msg":"trace[317585231] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"100.853559ms","start":"2026-03-26T17:49:28.44567Z","end":"2026-03-26T17:49:28.546523Z","steps":["trace[317585231] 'process raft request' (duration: 54.156161ms)","trace[317585231] 'compare' (duration: 46.448186ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:29.671914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.734109ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-26T17:49:29.67217Z","caller":"traceutil/trace.go:171","msg":"trace[578130386] linearizableReadLoop","detail":"{readStateIndex:272; appliedIndex:271; }","duration":"409.680587ms","start":"2026-03-26T17:49:29.262467Z","end":"2026-03-26T17:49:29.672148Z","steps":["trace[578130386] 'read index received' (duration: 274.559346ms)","trace[578130386] 'applied index is now lower than readState.Index' (duration: 135.119141ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:29.672228Z","caller":"traceutil/trace.go:171","msg":"trace[1465496709] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"410.756063ms","start":"2026-03-26T17:49:29.261411Z","end":"2026-03-26T17:49:29.672167Z","steps":["trace[1465496709] 'process raft request' (duration: 275.684242ms)","trace[1465496709] 'compare' (duration: 134.582458ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:29.672386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.901908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-03-26T17:49:29.672412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:29.261399Z","time spent":"410.933414ms","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11436,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T17:49:29.672449Z","caller":"traceutil/trace.go:171","msg":"trace[1799545246] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:266; }","duration":"409.993478ms","start":"2026-03-26T17:49:29.262443Z","end":"2026-03-26T17:49:29.672437Z","steps":["trace[1799545246] 'agreement among raft nodes before linearized reading' (duration: 409.803257ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:29.6726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:29.262433Z","time spent":"410.153369ms","remote":"127.0.0.1:39112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-26T17:49:29.879034Z","caller":"traceutil/trace.go:171","msg":"trace[464150635] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"201.888912ms","start":"2026-03-26T17:49:29.677111Z","end":"2026-03-26T17:49:29.879Z","steps":["trace[464150635] 'process raft request' (duration: 104.247955ms)","trace[464150635] 'compare' (duration: 97.473256ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:29.87924Z","caller":"traceutil/trace.go:171","msg":"trace[2107079706] linearizableReadLoop","detail":"{readStateIndex:274; appliedIndex:273; }","duration":"141.822729ms","start":"2026-03-26T17:49:29.737384Z","end":"2026-03-26T17:49:29.879206Z","steps":["trace[2107079706] 'read index received' (duration: 43.93269ms)","trace[2107079706] 'applied index is now lower than readState.Index' (duration: 97.886729ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:29.879469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.05842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T17:49:29.879617Z","caller":"traceutil/trace.go:171","msg":"trace[1517026589] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:268; }","duration":"142.255902ms","start":"2026-03-26T17:49:29.737346Z","end":"2026-03-26T17:49:29.879602Z","steps":["trace[1517026589] 'agreement among raft nodes before linearized reading' (duration: 142.011691ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:30.085176Z","caller":"traceutil/trace.go:171","msg":"trace[840656117] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"109.654183ms","start":"2026-03-26T17:49:29.975488Z","end":"2026-03-26T17:49:30.085142Z","steps":["trace[840656117] 'process raft request' (duration: 70.25321ms)","trace[840656117] 'compare' (duration: 39.173952ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:30.600437Z","caller":"traceutil/trace.go:171","msg":"trace[351894958] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"117.652378ms","start":"2026-03-26T17:49:30.482765Z","end":"2026-03-26T17:49:30.600418Z","steps":["trace[351894958] 'process raft request' (duration: 72.340241ms)","trace[351894958] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/minions/instance; req_size:3999; } (duration: 45.074046ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:30.600551Z","caller":"traceutil/trace.go:171","msg":"trace[1114260905] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"115.273685ms","start":"2026-03-26T17:49:30.485271Z","end":"2026-03-26T17:49:30.600545Z","steps":["trace[1114260905] 'process raft request' (duration: 115.123084ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:30.80341Z","caller":"traceutil/trace.go:171","msg":"trace[1964326678] linearizableReadLoop","detail":"{readStateIndex:289; appliedIndex:287; }","duration":"142.827921ms","start":"2026-03-26T17:49:30.66056Z","end":"2026-03-26T17:49:30.803387Z","steps":["trace[1964326678] 'read index received' (duration: 56.723192ms)","trace[1964326678] 'applied index is now lower than readState.Index' (duration: 86.103689ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:30.803501Z","caller":"traceutil/trace.go:171","msg":"trace[644113058] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"197.578741ms","start":"2026-03-26T17:49:30.6059Z","end":"2026-03-26T17:49:30.803479Z","steps":["trace[644113058] 'process raft request' (duration: 111.370202ms)","trace[644113058] 'compare' (duration: 85.911768ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:30.803578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.960991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-03-26T17:49:30.80357Z","caller":"traceutil/trace.go:171","msg":"trace[922514748] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"195.57151ms","start":"2026-03-26T17:49:30.60797Z","end":"2026-03-26T17:49:30.803542Z","steps":["trace[922514748] 'process raft request' (duration: 195.358458ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:30.803742Z","caller":"traceutil/trace.go:171","msg":"trace[573831920] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:283; }","duration":"143.142563ms","start":"2026-03-26T17:49:30.660546Z","end":"2026-03-26T17:49:30.803689Z","steps":["trace[573831920] 'agreement among raft nodes before linearized reading' (duration: 142.933261ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:31.027475Z","caller":"traceutil/trace.go:171","msg":"trace[108063949] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"178.810864ms","start":"2026-03-26T17:49:30.848642Z","end":"2026-03-26T17:49:31.027453Z","steps":["trace[108063949] 'process raft request' (duration: 178.764454ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:31.027517Z","caller":"traceutil/trace.go:171","msg":"trace[1252438496] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"215.284821ms","start":"2026-03-26T17:49:30.812196Z","end":"2026-03-26T17:49:31.027481Z","steps":["trace[1252438496] 'process raft request' (duration: 132.19775ms)","trace[1252438496] 'compare' (duration: 82.86796ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:31.027655Z","caller":"traceutil/trace.go:171","msg":"trace[661424743] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"183.054058ms","start":"2026-03-26T17:49:30.84457Z","end":"2026-03-26T17:49:31.027624Z","steps":["trace[661424743] 'read index received' (duration: 99.823066ms)","trace[661424743] 'applied index is now lower than readState.Index' (duration: 83.228292ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:31.027789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.892358ms","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-26T17:49:31.027843Z","caller":"traceutil/trace.go:171","msg":"trace[237981418] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:286; }","duration":"198.986269ms","start":"2026-03-26T17:49:30.828845Z","end":"2026-03-26T17:49:31.027831Z","steps":["trace[237981418] 'agreement among raft nodes before linearized reading' (duration: 198.881358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:31.027893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.04725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T17:49:31.027963Z","caller":"traceutil/trace.go:171","msg":"trace[1027017999] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:286; }","duration":"171.144691ms","start":"2026-03-26T17:49:30.8568Z","end":"2026-03-26T17:49:31.027945Z","steps":["trace[1027017999] 'agreement among raft nodes before linearized reading' (duration: 171.02712ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:31.028284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.13754ms","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-26T17:49:31.028384Z","caller":"traceutil/trace.go:171","msg":"trace[833780169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:286; }","duration":"171.259911ms","start":"2026-03-26T17:49:30.857101Z","end":"2026-03-26T17:49:31.028361Z","steps":["trace[833780169] 'agreement among raft nodes before linearized reading' (duration: 170.893959ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:31.35423Z","caller":"traceutil/trace.go:171","msg":"trace[1422033177] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"320.479501ms","start":"2026-03-26T17:49:31.033726Z","end":"2026-03-26T17:49:31.354205Z","steps":["trace[1422033177] 'process raft request' (duration: 320.34972ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:31.354411Z","caller":"traceutil/trace.go:171","msg":"trace[1123659754] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"320.791042ms","start":"2026-03-26T17:49:31.033386Z","end":"2026-03-26T17:49:31.354178Z","steps":["trace[1123659754] 'process raft request' (duration: 263.305587ms)","trace[1123659754] 'compare' (duration: 57.253523ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:31.354491Z","caller":"traceutil/trace.go:171","msg":"trace[2133151481] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"318.58778ms","start":"2026-03-26T17:49:31.03586Z","end":"2026-03-26T17:49:31.354448Z","steps":["trace[2133151481] 'process raft request' (duration: 318.277998ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:31.35448Z","caller":"traceutil/trace.go:171","msg":"trace[520159793] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"293.570288ms","start":"2026-03-26T17:49:31.060875Z","end":"2026-03-26T17:49:31.354445Z","steps":["trace[520159793] 'process raft request' (duration: 293.469888ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:31.354578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:31.033363Z","time spent":"321.133925ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T17:49:31.354247Z","caller":"traceutil/trace.go:171","msg":"trace[1961360632] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"320.251029ms","start":"2026-03-26T17:49:31.033979Z","end":"2026-03-26T17:49:31.35423Z","steps":["trace[1961360632] 'process raft request' (duration: 320.131968ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:31.354653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:31.035847Z","time spent":"318.735421ms","remote":"127.0.0.1:39204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":203,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T17:49:31.354664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:31.033967Z","time spent":"320.654071ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T17:49:31.354528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:31.033703Z","time spent":"320.664192ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T17:49:31.587948Z","caller":"traceutil/trace.go:171","msg":"trace[1469377377] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"222.529627ms","start":"2026-03-26T17:49:31.36539Z","end":"2026-03-26T17:49:31.58792Z","steps":["trace[1469377377] 'process raft request' (duration: 222.473116ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:31.588026Z","caller":"traceutil/trace.go:171","msg":"trace[1054715573] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"223.285341ms","start":"2026-03-26T17:49:31.364713Z","end":"2026-03-26T17:49:31.587999Z","steps":["trace[1054715573] 'process raft request' (duration: 177.298121ms)","trace[1054715573] 'compare' (duration: 45.663138ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:32.139668Z","caller":"traceutil/trace.go:171","msg":"trace[2016805164] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"275.839244ms","start":"2026-03-26T17:49:31.863794Z","end":"2026-03-26T17:49:32.139633Z","steps":["trace[2016805164] 'process raft request' (duration: 202.526723ms)","trace[2016805164] 'compare' (duration: 72.886799ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:32.139885Z","caller":"traceutil/trace.go:171","msg":"trace[1968383352] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:307; }","duration":"170.424599ms","start":"2026-03-26T17:49:31.969437Z","end":"2026-03-26T17:49:32.139861Z","steps":["trace[1968383352] 'read index received' (duration: 96.909076ms)","trace[1968383352] 'applied index is now lower than readState.Index' (duration: 73.513053ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:32.14009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.63514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T17:49:32.140138Z","caller":"traceutil/trace.go:171","msg":"trace[162733868] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:303; }","duration":"170.73108ms","start":"2026-03-26T17:49:31.969396Z","end":"2026-03-26T17:49:32.140127Z","steps":["trace[162733868] 'agreement among raft nodes before linearized reading' (duration: 170.60984ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:32.256668Z","caller":"traceutil/trace.go:171","msg":"trace[1849360660] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"112.856625ms","start":"2026-03-26T17:49:32.14379Z","end":"2026-03-26T17:49:32.256646Z","steps":["trace[1849360660] 'process raft request' (duration: 112.553993ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:32.256694Z","caller":"traceutil/trace.go:171","msg":"trace[316248322] transaction","detail":"{read_only:false; number_of_response:0; response_revision:305; }","duration":"102.134195ms","start":"2026-03-26T17:49:32.154532Z","end":"2026-03-26T17:49:32.256666Z","steps":["trace[316248322] 'process raft request' (duration: 102.057764ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:32.307568Z","caller":"traceutil/trace.go:171","msg":"trace[1716097462] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"128.160081ms","start":"2026-03-26T17:49:32.179383Z","end":"2026-03-26T17:49:32.307543Z","steps":["trace[1716097462] 'process raft request' (duration: 127.874719ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:32.307642Z","caller":"traceutil/trace.go:171","msg":"trace[1832736644] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"126.916943ms","start":"2026-03-26T17:49:32.180708Z","end":"2026-03-26T17:49:32.307625Z","steps":["trace[1832736644] 'process raft request' (duration: 126.767882ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:32.30764Z","caller":"traceutil/trace.go:171","msg":"trace[416941350] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"126.927883ms","start":"2026-03-26T17:49:32.18068Z","end":"2026-03-26T17:49:32.307608Z","steps":["trace[416941350] 'process raft request' (duration: 126.758792ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:32.307706Z","caller":"traceutil/trace.go:171","msg":"trace[98579814] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"128.176101ms","start":"2026-03-26T17:49:32.179508Z","end":"2026-03-26T17:49:32.307684Z","steps":["trace[98579814] 'process raft request' (duration: 127.890179ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:32.307764Z","caller":"traceutil/trace.go:171","msg":"trace[1838045248] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"126.506231ms","start":"2026-03-26T17:49:32.181238Z","end":"2026-03-26T17:49:32.307744Z","steps":["trace[1838045248] 'process raft request' (duration: 126.29986ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:32.307851Z","caller":"traceutil/trace.go:171","msg":"trace[869304074] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"127.118534ms","start":"2026-03-26T17:49:32.180723Z","end":"2026-03-26T17:49:32.307842Z","steps":["trace[869304074] 'process raft request' (duration: 126.784003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:32.307929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.677855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-26T17:49:32.307988Z","caller":"traceutil/trace.go:171","msg":"trace[1373166708] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:307; }","duration":"130.771955ms","start":"2026-03-26T17:49:32.177198Z","end":"2026-03-26T17:49:32.30797Z","steps":["trace[1373166708] 'agreement among raft nodes before linearized reading' (duration: 130.627104ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:32.484002Z","caller":"traceutil/trace.go:171","msg":"trace[1106162813] transaction","detail":"{read_only:false; response_revision:314; number_of_response:1; }","duration":"135.262921ms","start":"2026-03-26T17:49:32.348717Z","end":"2026-03-26T17:49:32.48398Z","steps":["trace[1106162813] 'process raft request' (duration: 135.14215ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:32.484067Z","caller":"traceutil/trace.go:171","msg":"trace[75083844] transaction","detail":"{read_only:false; response_revision:313; number_of_response:1; }","duration":"138.597879ms","start":"2026-03-26T17:49:32.345439Z","end":"2026-03-26T17:49:32.484037Z","steps":["trace[75083844] 'process raft request' (duration: 89.794015ms)","trace[75083844] 'compare' (duration: 48.509162ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:32.484252Z","caller":"traceutil/trace.go:171","msg":"trace[1935749706] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:323; }","duration":"135.407621ms","start":"2026-03-26T17:49:32.348615Z","end":"2026-03-26T17:49:32.484023Z","steps":["trace[1935749706] 'read index received' (duration: 86.603337ms)","trace[1935749706] 'applied index is now lower than readState.Index' (duration: 48.800834ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:32.484356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.683782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-26T17:49:32.484983Z","caller":"traceutil/trace.go:171","msg":"trace[1463340929] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:314; }","duration":"136.344276ms","start":"2026-03-26T17:49:32.348593Z","end":"2026-03-26T17:49:32.484937Z","steps":["trace[1463340929] 'agreement among raft nodes before linearized reading' (duration: 135.626762ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:32.685277Z","caller":"traceutil/trace.go:171","msg":"trace[798558641] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"123.575405ms","start":"2026-03-26T17:49:32.561681Z","end":"2026-03-26T17:49:32.685256Z","steps":["trace[798558641] 'process raft request' (duration: 123.524925ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:32.685433Z","caller":"traceutil/trace.go:171","msg":"trace[293716440] transaction","detail":"{read_only:false; response_revision:318; number_of_response:1; }","duration":"140.430089ms","start":"2026-03-26T17:49:32.544952Z","end":"2026-03-26T17:49:32.685382Z","steps":["trace[293716440] 'process raft request' (duration: 74.149717ms)","trace[293716440] 'compare' (duration: 65.98018ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:32.685538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.916726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-03-26T17:49:32.685585Z","caller":"traceutil/trace.go:171","msg":"trace[734516921] linearizableReadLoop","detail":"{readStateIndex:329; appliedIndex:327; }","duration":"139.826306ms","start":"2026-03-26T17:49:32.545562Z","end":"2026-03-26T17:49:32.685389Z","steps":["trace[734516921] 'read index received' (duration: 13.253504ms)","trace[734516921] 'applied index is now lower than readState.Index' (duration: 126.569832ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:32.685608Z","caller":"traceutil/trace.go:171","msg":"trace[94821124] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:319; }","duration":"140.055947ms","start":"2026-03-26T17:49:32.545533Z","end":"2026-03-26T17:49:32.685589Z","steps":["trace[94821124] 'agreement among raft nodes before linearized reading' (duration: 139.862516ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:32.685686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.791445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T17:49:32.685716Z","caller":"traceutil/trace.go:171","msg":"trace[1839408998] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:319; }","duration":"123.834626ms","start":"2026-03-26T17:49:32.56187Z","end":"2026-03-26T17:49:32.685705Z","steps":["trace[1839408998] 'agreement among raft nodes before linearized reading' (duration: 123.780005ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:32.910469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.786685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-26T17:49:32.910584Z","caller":"traceutil/trace.go:171","msg":"trace[1451701954] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:331; }","duration":"198.906489ms","start":"2026-03-26T17:49:32.711628Z","end":"2026-03-26T17:49:32.910535Z","steps":["trace[1451701954] 'read index received' (duration: 4.916928ms)","trace[1451701954] 'applied index is now lower than readState.Index' (duration: 193.988451ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:32.910598Z","caller":"traceutil/trace.go:171","msg":"trace[295980650] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"200.252516ms","start":"2026-03-26T17:49:32.710326Z","end":"2026-03-26T17:49:32.910579Z","steps":["trace[295980650] 'process raft request' (duration: 85.30231ms)","trace[295980650] 'compare' (duration: 114.692804ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:32.910692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.057009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T17:49:32.910771Z","caller":"traceutil/trace.go:171","msg":"trace[657558508] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:322; }","duration":"199.1158ms","start":"2026-03-26T17:49:32.711604Z","end":"2026-03-26T17:49:32.91072Z","steps":["trace[657558508] 'agreement among raft nodes before linearized reading' (duration: 199.006969ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:33.192962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.885712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-26T17:49:33.193572Z","caller":"traceutil/trace.go:171","msg":"trace[1517417597] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"225.22892ms","start":"2026-03-26T17:49:32.968316Z","end":"2026-03-26T17:49:33.193545Z","steps":["trace[1517417597] 'process raft request' (duration: 224.864468ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:33.193639Z","caller":"traceutil/trace.go:171","msg":"trace[1567891950] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"228.005256ms","start":"2026-03-26T17:49:32.965605Z","end":"2026-03-26T17:49:33.193611Z","steps":["trace[1567891950] 'process raft request' (duration: 123.402591ms)","trace[1567891950] 'compare' (duration: 103.73937ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:33.205942Z","caller":"traceutil/trace.go:171","msg":"trace[166003701] transaction","detail":"{read_only:false; number_of_response:0; response_revision:326; }","duration":"110.015446ms","start":"2026-03-26T17:49:33.095906Z","end":"2026-03-26T17:49:33.205921Z","steps":["trace[166003701] 'process raft request' (duration: 109.923705ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:33.20609Z","caller":"traceutil/trace.go:171","msg":"trace[1945938236] transaction","detail":"{read_only:false; number_of_response:0; response_revision:326; }","duration":"110.233907ms","start":"2026-03-26T17:49:33.095758Z","end":"2026-03-26T17:49:33.205992Z","steps":["trace[1945938236] 'process raft request' (duration: 110.016736ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:33.20617Z","caller":"traceutil/trace.go:171","msg":"trace[168854577] transaction","detail":"{read_only:false; number_of_response:0; response_revision:326; }","duration":"109.692984ms","start":"2026-03-26T17:49:33.096316Z","end":"2026-03-26T17:49:33.206009Z","steps":["trace[168854577] 'process raft request' (duration: 109.531863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:33.535726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.372936ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-26T17:49:33.536164Z","caller":"traceutil/trace.go:171","msg":"trace[1588589810] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"337.899641ms","start":"2026-03-26T17:49:33.198246Z","end":"2026-03-26T17:49:33.536146Z","steps":["trace[1588589810] 'process raft request' (duration: 204.050042ms)","trace[1588589810] 'compare' (duration: 133.188615ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:33.536243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:33.19823Z","time spent":"337.995121ms","remote":"127.0.0.1:39204","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":170,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T17:49:33.536372Z","caller":"traceutil/trace.go:171","msg":"trace[633513617] linearizableReadLoop","detail":"{readStateIndex:342; appliedIndex:340; }","duration":"329.562345ms","start":"2026-03-26T17:49:33.206791Z","end":"2026-03-26T17:49:33.536353Z","steps":["trace[633513617] 'read index received' (duration: 195.452995ms)","trace[633513617] 'applied index is now lower than readState.Index' (duration: 134.10809ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:33.536443Z","caller":"traceutil/trace.go:171","msg":"trace[2048514261] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"332.032618ms","start":"2026-03-26T17:49:33.204375Z","end":"2026-03-26T17:49:33.536408Z","steps":["trace[2048514261] 'process raft request' (duration: 331.742817ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:33.536639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.531354ms","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-26T17:49:33.536672Z","caller":"traceutil/trace.go:171","msg":"trace[286860419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:328; }","duration":"177.563284ms","start":"2026-03-26T17:49:33.359101Z","end":"2026-03-26T17:49:33.536664Z","steps":["trace[286860419] 'agreement among raft nodes before linearized reading' (duration: 177.511053ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:33.536613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:33.204351Z","time spent":"332.174579ms","remote":"127.0.0.1:39174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T17:49:33.536702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.527355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"warn","ts":"2026-03-26T17:49:33.53672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.905617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6751"} {"level":"info","ts":"2026-03-26T17:49:33.536767Z","caller":"traceutil/trace.go:171","msg":"trace[2006518721] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:328; }","duration":"329.644985ms","start":"2026-03-26T17:49:33.207106Z","end":"2026-03-26T17:49:33.536751Z","steps":["trace[2006518721] 'agreement among raft nodes before linearized reading' (duration: 329.488714ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:33.536797Z","caller":"traceutil/trace.go:171","msg":"trace[1155982921] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:328; }","duration":"330.009227ms","start":"2026-03-26T17:49:33.206771Z","end":"2026-03-26T17:49:33.536781Z","steps":["trace[1155982921] 'agreement among raft nodes before linearized reading' (duration: 329.853897ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:33.536814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:33.20709Z","time spent":"329.716566ms","remote":"127.0.0.1:39174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5348,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-03-26T17:49:33.536931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.771695ms","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-26T17:49:33.536953Z","caller":"traceutil/trace.go:171","msg":"trace[812375866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:328; }","duration":"177.821055ms","start":"2026-03-26T17:49:33.359127Z","end":"2026-03-26T17:49:33.536948Z","steps":["trace[812375866] 'agreement among raft nodes before linearized reading' (duration: 177.772245ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:33.536926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.868035ms","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-26T17:49:33.537059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.519434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-26T17:49:33.537098Z","caller":"traceutil/trace.go:171","msg":"trace[1033328139] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:328; }","duration":"329.573904ms","start":"2026-03-26T17:49:33.207515Z","end":"2026-03-26T17:49:33.537089Z","steps":["trace[1033328139] 'agreement among raft nodes before linearized reading' (duration: 329.508614ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:33.537124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:33.207507Z","time spent":"329.611304ms","remote":"127.0.0.1:39112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-03-26T17:49:33.537063Z","caller":"traceutil/trace.go:171","msg":"trace[1505569352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:328; }","duration":"178.044037ms","start":"2026-03-26T17:49:33.359002Z","end":"2026-03-26T17:49:33.537046Z","steps":["trace[1505569352] 'agreement among raft nodes before linearized reading' (duration: 177.870666ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:33.538217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:33.206747Z","time spent":"331.432615ms","remote":"127.0.0.1:39174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6773,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-03-26T17:49:33.53664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.833506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-03-26T17:49:33.538426Z","caller":"traceutil/trace.go:171","msg":"trace[1577110485] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:328; }","duration":"331.647787ms","start":"2026-03-26T17:49:33.206764Z","end":"2026-03-26T17:49:33.538412Z","steps":["trace[1577110485] 'agreement among raft nodes before linearized reading' (duration: 329.789956ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:33.538461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:33.206752Z","time spent":"331.699457ms","remote":"127.0.0.1:39174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7316,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-03-26T17:49:33.922277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.394267ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T17:49:33.922551Z","caller":"traceutil/trace.go:171","msg":"trace[2060352612] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"287.958512ms","start":"2026-03-26T17:49:33.634572Z","end":"2026-03-26T17:49:33.922531Z","steps":["trace[2060352612] 'process raft request' (duration: 287.887781ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:33.922748Z","caller":"traceutil/trace.go:171","msg":"trace[81583145] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"297.151273ms","start":"2026-03-26T17:49:33.625569Z","end":"2026-03-26T17:49:33.92272Z","steps":["trace[81583145] 'process raft request' (duration: 104.227493ms)","trace[81583145] 'compare' (duration: 192.281896ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:33.922754Z","caller":"traceutil/trace.go:171","msg":"trace[1183259204] linearizableReadLoop","detail":"{readStateIndex:350; appliedIndex:349; }","duration":"288.810416ms","start":"2026-03-26T17:49:33.633911Z","end":"2026-03-26T17:49:33.922721Z","steps":["trace[1183259204] 'read index received' (duration: 95.969727ms)","trace[1183259204] 'applied index is now lower than readState.Index' (duration: 192.836309ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:33.923017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.060287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-26T17:49:33.923072Z","caller":"traceutil/trace.go:171","msg":"trace[998821292] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:337; }","duration":"289.228099ms","start":"2026-03-26T17:49:33.633833Z","end":"2026-03-26T17:49:33.923061Z","steps":["trace[998821292] 'agreement among raft nodes before linearized reading' (duration: 288.973737ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:34.193481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.693972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T17:49:34.193586Z","caller":"traceutil/trace.go:171","msg":"trace[430997275] linearizableReadLoop","detail":"{readStateIndex:355; appliedIndex:354; }","duration":"168.78134ms","start":"2026-03-26T17:49:34.024789Z","end":"2026-03-26T17:49:34.19357Z","steps":["trace[430997275] 'read index received' (duration: 65.855737ms)","trace[430997275] 'applied index is now lower than readState.Index' (duration: 102.924553ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:34.193713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.918021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-26T17:49:34.193758Z","caller":"traceutil/trace.go:171","msg":"trace[1082104878] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:341; }","duration":"168.993751ms","start":"2026-03-26T17:49:34.02475Z","end":"2026-03-26T17:49:34.193744Z","steps":["trace[1082104878] 'agreement among raft nodes before linearized reading' (duration: 168.86309ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:34.19382Z","caller":"traceutil/trace.go:171","msg":"trace[604952798] transaction","detail":"{read_only:false; response_revision:341; number_of_response:1; }","duration":"198.829568ms","start":"2026-03-26T17:49:33.994951Z","end":"2026-03-26T17:49:34.193781Z","steps":["trace[604952798] 'process raft request' (duration: 95.758004ms)","trace[604952798] 'compare' (duration: 102.588222ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:34.529711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.07423ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-26T17:49:34.529814Z","caller":"traceutil/trace.go:171","msg":"trace[164874839] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"267.921013ms","start":"2026-03-26T17:49:34.261875Z","end":"2026-03-26T17:49:34.529796Z","steps":["trace[164874839] 'process raft request' (duration: 102.698663ms)","trace[164874839] 'compare' (duration: 164.936589ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:34.600188Z","caller":"traceutil/trace.go:171","msg":"trace[935074249] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"337.740372ms","start":"2026-03-26T17:49:34.262418Z","end":"2026-03-26T17:49:34.600158Z","steps":["trace[935074249] 'process raft request' (duration: 337.532841ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:34.600356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:34.262384Z","time spent":"337.886403ms","remote":"127.0.0.1:39174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T17:49:34.600594Z","caller":"traceutil/trace.go:171","msg":"trace[1412858988] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"334.150272ms","start":"2026-03-26T17:49:34.26642Z","end":"2026-03-26T17:49:34.60057Z","steps":["trace[1412858988] 'process raft request' (duration: 334.080641ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:34.600669Z","caller":"traceutil/trace.go:171","msg":"trace[1286673132] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"338.199034ms","start":"2026-03-26T17:49:34.262428Z","end":"2026-03-26T17:49:34.600627Z","steps":["trace[1286673132] 'process raft request' (duration: 337.641721ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:34.600741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:34.26641Z","time spent":"334.258022ms","remote":"127.0.0.1:39342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T17:49:34.600765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:34.262408Z","time spent":"338.313755ms","remote":"127.0.0.1:39162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":596,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-26T17:49:34.600826Z","caller":"traceutil/trace.go:171","msg":"trace[241701647] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"334.351613ms","start":"2026-03-26T17:49:34.266456Z","end":"2026-03-26T17:49:34.600808Z","steps":["trace[241701647] 'process raft request' (duration: 334.079951ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:34.600922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:34.266439Z","time spent":"334.440083ms","remote":"127.0.0.1:39342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T17:49:34.601106Z","caller":"traceutil/trace.go:171","msg":"trace[705487267] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:356; }","duration":"337.3467ms","start":"2026-03-26T17:49:34.263746Z","end":"2026-03-26T17:49:34.601092Z","steps":["trace[705487267] 'read index received' (duration: 100.830062ms)","trace[705487267] 'applied index is now lower than readState.Index' (duration: 236.515798ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:34.601154Z","caller":"traceutil/trace.go:171","msg":"trace[286566244] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"334.231432ms","start":"2026-03-26T17:49:34.266903Z","end":"2026-03-26T17:49:34.601134Z","steps":["trace[286566244] 'process raft request' (duration: 333.83443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:34.601198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.44414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"info","ts":"2026-03-26T17:49:34.601238Z","caller":"traceutil/trace.go:171","msg":"trace[1362070892] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:349; }","duration":"337.51051ms","start":"2026-03-26T17:49:34.263717Z","end":"2026-03-26T17:49:34.601227Z","steps":["trace[1362070892] 'agreement among raft nodes before linearized reading' (duration: 337.4386ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:34.601241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:34.266897Z","time spent":"334.297252ms","remote":"127.0.0.1:39342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T17:49:34.601268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:34.263705Z","time spent":"337.55612ms","remote":"127.0.0.1:39204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":240,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-03-26T17:49:34.601594Z","caller":"traceutil/trace.go:171","msg":"trace[119449185] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"337.699361ms","start":"2026-03-26T17:49:34.263877Z","end":"2026-03-26T17:49:34.601576Z","steps":["trace[119449185] 'process raft request' (duration: 336.544455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:34.601725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:34.26387Z","time spent":"337.806802ms","remote":"127.0.0.1:39098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2162,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T17:49:34.698242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.755816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-03-26T17:49:34.698295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.531083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-03-26T17:49:34.698305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.977086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-26T17:49:34.698336Z","caller":"traceutil/trace.go:171","msg":"trace[2115650629] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:350; }","duration":"336.897207ms","start":"2026-03-26T17:49:34.361418Z","end":"2026-03-26T17:49:34.698315Z","steps":["trace[2115650629] 'agreement among raft nodes before linearized reading' (duration: 336.726896ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:34.698373Z","caller":"traceutil/trace.go:171","msg":"trace[621931009] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:350; }","duration":"332.654623ms","start":"2026-03-26T17:49:34.365698Z","end":"2026-03-26T17:49:34.698353Z","steps":["trace[621931009] 'agreement among raft nodes before linearized reading' (duration: 332.439612ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:34.698383Z","caller":"traceutil/trace.go:171","msg":"trace[19943361] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:350; }","duration":"430.092846ms","start":"2026-03-26T17:49:34.268272Z","end":"2026-03-26T17:49:34.698365Z","steps":["trace[19943361] 'agreement among raft nodes before linearized reading' (duration: 429.945455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:34.698392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:34.361398Z","time spent":"336.982817ms","remote":"127.0.0.1:39204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"warn","ts":"2026-03-26T17:49:34.69842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:34.365681Z","time spent":"332.731173ms","remote":"127.0.0.1:39204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":215,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-03-26T17:49:34.698433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:34.268266Z","time spent":"430.156827ms","remote":"127.0.0.1:39204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-03-26T17:49:34.69855Z","caller":"traceutil/trace.go:171","msg":"trace[1338852389] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"166.051905ms","start":"2026-03-26T17:49:34.532484Z","end":"2026-03-26T17:49:34.698536Z","steps":["trace[1338852389] 'process raft request' (duration: 107.954742ms)","trace[1338852389] 'compare' (duration: 57.5443ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:34.698578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.376055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-03-26T17:49:34.698615Z","caller":"traceutil/trace.go:171","msg":"trace[499797554] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:350; }","duration":"365.433876ms","start":"2026-03-26T17:49:34.333167Z","end":"2026-03-26T17:49:34.698601Z","steps":["trace[499797554] 'agreement among raft nodes before linearized reading' (duration: 365.377955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:34.698642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:34.333153Z","time spent":"365.482916ms","remote":"127.0.0.1:39204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":236,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-03-26T17:49:34.698671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.573709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-03-26T17:49:34.698782Z","caller":"traceutil/trace.go:171","msg":"trace[1641901980] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:350; }","duration":"247.74844ms","start":"2026-03-26T17:49:34.451022Z","end":"2026-03-26T17:49:34.698771Z","steps":["trace[1641901980] 'agreement among raft nodes before linearized reading' (duration: 247.510509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:34.698299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.876913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-03-26T17:49:34.699095Z","caller":"traceutil/trace.go:171","msg":"trace[1288618668] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:350; }","duration":"417.662267ms","start":"2026-03-26T17:49:34.281361Z","end":"2026-03-26T17:49:34.699023Z","steps":["trace[1288618668] 'agreement among raft nodes before linearized reading' (duration: 416.824012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:34.699213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:34.281346Z","time spent":"417.850648ms","remote":"127.0.0.1:39204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-03-26T17:49:34.710567Z","caller":"traceutil/trace.go:171","msg":"trace[989645360] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"103.431146ms","start":"2026-03-26T17:49:34.607123Z","end":"2026-03-26T17:49:34.710554Z","steps":["trace[989645360] 'process raft request' (duration: 103.240455ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:34.710592Z","caller":"traceutil/trace.go:171","msg":"trace[2082690714] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"101.545635ms","start":"2026-03-26T17:49:34.609026Z","end":"2026-03-26T17:49:34.710571Z","steps":["trace[2082690714] 'process raft request' (duration: 101.440465ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:34.710768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.306911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7102"} {"level":"info","ts":"2026-03-26T17:49:34.710801Z","caller":"traceutil/trace.go:171","msg":"trace[857532424] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:353; }","duration":"104.377281ms","start":"2026-03-26T17:49:34.606415Z","end":"2026-03-26T17:49:34.710792Z","steps":["trace[857532424] 'agreement among raft nodes before linearized reading' (duration: 104.277651ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:34.916242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.173036ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-26T17:49:34.916477Z","caller":"traceutil/trace.go:171","msg":"trace[1390936496] linearizableReadLoop","detail":"{readStateIndex:371; appliedIndex:368; }","duration":"178.028701ms","start":"2026-03-26T17:49:34.738431Z","end":"2026-03-26T17:49:34.91646Z","steps":["trace[1390936496] 'read index received' (duration: 63.565714ms)","trace[1390936496] 'applied index is now lower than readState.Index' (duration: 114.462157ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:34.916522Z","caller":"traceutil/trace.go:171","msg":"trace[1920020102] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"197.38739ms","start":"2026-03-26T17:49:34.7191Z","end":"2026-03-26T17:49:34.916488Z","steps":["trace[1920020102] 'process raft request' (duration: 197.236729ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:34.916565Z","caller":"traceutil/trace.go:171","msg":"trace[730342571] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"202.425208ms","start":"2026-03-26T17:49:34.714104Z","end":"2026-03-26T17:49:34.916529Z","steps":["trace[730342571] 'process raft request' (duration: 87.89116ms)","trace[730342571] 'compare' (duration: 114.026625ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:34.916751Z","caller":"traceutil/trace.go:171","msg":"trace[1457154534] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"195.807411ms","start":"2026-03-26T17:49:34.72093Z","end":"2026-03-26T17:49:34.916737Z","steps":["trace[1457154534] 'process raft request' (duration: 195.471129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:34.916818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.55686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T17:49:34.916907Z","caller":"traceutil/trace.go:171","msg":"trace[2030735666] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:357; }","duration":"197.682141ms","start":"2026-03-26T17:49:34.719212Z","end":"2026-03-26T17:49:34.916894Z","steps":["trace[2030735666] 'agreement among raft nodes before linearized reading' (duration: 197.53629ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:34.918699Z","caller":"traceutil/trace.go:171","msg":"trace[1041136629] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"169.278273ms","start":"2026-03-26T17:49:34.7494Z","end":"2026-03-26T17:49:34.918678Z","steps":["trace[1041136629] 'process raft request' (duration: 169.098182ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:34.919011Z","caller":"traceutil/trace.go:171","msg":"trace[2125342337] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"176.173712ms","start":"2026-03-26T17:49:34.742799Z","end":"2026-03-26T17:49:34.918973Z","steps":["trace[2125342337] 'process raft request' (duration: 175.559978ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:34.919019Z","caller":"traceutil/trace.go:171","msg":"trace[1481651913] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"161.957633ms","start":"2026-03-26T17:49:34.757042Z","end":"2026-03-26T17:49:34.918999Z","steps":["trace[1481651913] 'process raft request' (duration: 161.657061ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:34.919276Z","caller":"traceutil/trace.go:171","msg":"trace[298738672] transaction","detail":"{read_only:false; number_of_response:1; response_revision:360; }","duration":"169.721015ms","start":"2026-03-26T17:49:34.749543Z","end":"2026-03-26T17:49:34.919264Z","steps":["trace[298738672] 'process raft request' (duration: 168.990511ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:34.9193Z","caller":"traceutil/trace.go:171","msg":"trace[519437282] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"173.208915ms","start":"2026-03-26T17:49:34.746071Z","end":"2026-03-26T17:49:34.91928Z","steps":["trace[519437282] 'process raft request' (duration: 172.380231ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:34.919535Z","caller":"traceutil/trace.go:171","msg":"trace[107254849] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"162.345845ms","start":"2026-03-26T17:49:34.757181Z","end":"2026-03-26T17:49:34.919527Z","steps":["trace[107254849] 'process raft request' (duration: 161.58487ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:34.919423Z","caller":"traceutil/trace.go:171","msg":"trace[1357859241] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"162.323545ms","start":"2026-03-26T17:49:34.757088Z","end":"2026-03-26T17:49:34.919412Z","steps":["trace[1357859241] 'process raft request' (duration: 161.644181ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:34.920001Z","caller":"traceutil/trace.go:171","msg":"trace[1679633446] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"167.443613ms","start":"2026-03-26T17:49:34.752536Z","end":"2026-03-26T17:49:34.919979Z","steps":["trace[1679633446] 'process raft request' (duration: 166.080256ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:34.918941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.434237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4322"} {"level":"info","ts":"2026-03-26T17:49:34.920186Z","caller":"traceutil/trace.go:171","msg":"trace[1376964821] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"167.500973ms","start":"2026-03-26T17:49:34.752638Z","end":"2026-03-26T17:49:34.920139Z","steps":["trace[1376964821] 'process raft request' (duration: 166.025825ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:34.920211Z","caller":"traceutil/trace.go:171","msg":"trace[863953467] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:366; }","duration":"167.729474ms","start":"2026-03-26T17:49:34.752461Z","end":"2026-03-26T17:49:34.92019Z","steps":["trace[863953467] 'agreement among raft nodes before linearized reading' (duration: 166.348187ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:34.920006Z","caller":"traceutil/trace.go:171","msg":"trace[1599151895] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"167.146021ms","start":"2026-03-26T17:49:34.752428Z","end":"2026-03-26T17:49:34.919574Z","steps":["trace[1599151895] 'process raft request' (duration: 166.150845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:36.296037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.744359ms","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-26T17:49:36.296107Z","caller":"traceutil/trace.go:171","msg":"trace[1148666986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:397; }","duration":"671.84364ms","start":"2026-03-26T17:49:35.624249Z","end":"2026-03-26T17:49:36.296092Z","steps":["trace[1148666986] 'range keys from in-memory index tree' (duration: 671.676799ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:36.296098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.92413ms","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-26T17:49:36.296167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.036251ms","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-26T17:49:36.296186Z","caller":"traceutil/trace.go:171","msg":"trace[444977953] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:397; }","duration":"672.080761ms","start":"2026-03-26T17:49:35.6241Z","end":"2026-03-26T17:49:36.296181Z","steps":["trace[444977953] 'range keys from in-memory index tree' (duration: 671.95147ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:36.296179Z","caller":"traceutil/trace.go:171","msg":"trace[407072007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:397; }","duration":"672.053951ms","start":"2026-03-26T17:49:35.624105Z","end":"2026-03-26T17:49:36.296159Z","steps":["trace[407072007] 'range keys from in-memory index tree' (duration: 671.819669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:36.296204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:35.624083Z","time spent":"672.117471ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-26T17:49:36.296224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:35.624095Z","time spent":"672.119561ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T17:49:36.296138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:35.624243Z","time spent":"671.88925ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-26T17:49:36.527992Z","caller":"traceutil/trace.go:171","msg":"trace[1119596186] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"226.623571ms","start":"2026-03-26T17:49:36.301344Z","end":"2026-03-26T17:49:36.527967Z","steps":["trace[1119596186] 'process raft request' (duration: 226.40118ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:36.569446Z","caller":"traceutil/trace.go:171","msg":"trace[1079224285] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"265.882209ms","start":"2026-03-26T17:49:36.303541Z","end":"2026-03-26T17:49:36.569423Z","steps":["trace[1079224285] 'process raft request' (duration: 265.838108ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:36.569522Z","caller":"traceutil/trace.go:171","msg":"trace[1879278245] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"266.793494ms","start":"2026-03-26T17:49:36.302688Z","end":"2026-03-26T17:49:36.569481Z","steps":["trace[1879278245] 'process raft request' (duration: 266.580483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:37.543254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.069163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T17:49:37.543341Z","caller":"traceutil/trace.go:171","msg":"trace[529486910] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"226.182304ms","start":"2026-03-26T17:49:37.317136Z","end":"2026-03-26T17:49:37.543318Z","steps":["trace[529486910] 'range keys from in-memory index tree' (duration: 225.991393ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:44.729981Z","caller":"traceutil/trace.go:171","msg":"trace[91253677] linearizableReadLoop","detail":"{readStateIndex:447; appliedIndex:446; }","duration":"114.380573ms","start":"2026-03-26T17:49:44.615578Z","end":"2026-03-26T17:49:44.729959Z","steps":["trace[91253677] 'read index received' (duration: 114.255406ms)","trace[91253677] 'applied index is now lower than readState.Index' (duration: 124.237µs)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:49:44.730152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.554512ms","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-26T17:49:44.730163Z","caller":"traceutil/trace.go:171","msg":"trace[1895426545] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"119.37254ms","start":"2026-03-26T17:49:44.610747Z","end":"2026-03-26T17:49:44.73012Z","steps":["trace[1895426545] 'process raft request' (duration: 119.060153ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:44.730209Z","caller":"traceutil/trace.go:171","msg":"trace[466574809] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:429; }","duration":"114.671208ms","start":"2026-03-26T17:49:44.61552Z","end":"2026-03-26T17:49:44.730192Z","steps":["trace[466574809] 'agreement among raft nodes before linearized reading' (duration: 114.563562ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:49:44.730378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.793488ms","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-26T17:49:44.73046Z","caller":"traceutil/trace.go:171","msg":"trace[112455170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:429; }","duration":"112.919644ms","start":"2026-03-26T17:49:44.617519Z","end":"2026-03-26T17:49:44.730439Z","steps":["trace[112455170] 'agreement among raft nodes before linearized reading' (duration: 112.740895ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:51.210758Z","caller":"traceutil/trace.go:171","msg":"trace[1463346900] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"164.671352ms","start":"2026-03-26T17:49:51.046066Z","end":"2026-03-26T17:49:51.210737Z","steps":["trace[1463346900] 'process raft request' (duration: 164.561227ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:54.959131Z","caller":"traceutil/trace.go:171","msg":"trace[32762300] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"115.587912ms","start":"2026-03-26T17:49:54.843519Z","end":"2026-03-26T17:49:54.959107Z","steps":["trace[32762300] 'process raft request' (duration: 115.54442ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:49:54.959193Z","caller":"traceutil/trace.go:171","msg":"trace[565721610] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"116.834891ms","start":"2026-03-26T17:49:54.842323Z","end":"2026-03-26T17:49:54.959158Z","steps":["trace[565721610] 'process raft request' (duration: 66.516155ms)","trace[565721610] 'compare' (duration: 50.090686ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:49:59.431839Z","caller":"traceutil/trace.go:171","msg":"trace[2037768418] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"122.515279ms","start":"2026-03-26T17:49:59.309295Z","end":"2026-03-26T17:49:59.431811Z","steps":["trace[2037768418] 'process raft request' (duration: 122.369132ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:00.159295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.878909ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T17:50:00.159557Z","caller":"traceutil/trace.go:171","msg":"trace[1572920811] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"635.534247ms","start":"2026-03-26T17:49:59.524001Z","end":"2026-03-26T17:50:00.159535Z","steps":["trace[1572920811] 'process raft request' (duration: 635.426312ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:50:00.159614Z","caller":"traceutil/trace.go:171","msg":"trace[721737621] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"639.513094ms","start":"2026-03-26T17:49:59.520027Z","end":"2026-03-26T17:50:00.15954Z","steps":["trace[721737621] 'compare' (duration: 632.729721ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:00.159694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:59.523993Z","time spent":"635.647552ms","remote":"127.0.0.1:39048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T17:50:00.159826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:59.520009Z","time spent":"639.719404ms","remote":"127.0.0.1:39048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T17:50:00.17495Z","caller":"traceutil/trace.go:171","msg":"trace[1929171533] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"598.229554ms","start":"2026-03-26T17:49:59.576694Z","end":"2026-03-26T17:50:00.174923Z","steps":["trace[1929171533] 'process raft request' (duration: 597.851107ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:00.175077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:49:59.576666Z","time spent":"598.349409ms","remote":"127.0.0.1:39048","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T17:50:07.222575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.197695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T17:50:07.222653Z","caller":"traceutil/trace.go:171","msg":"trace[1103973585] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"836.312449ms","start":"2026-03-26T17:50:06.386324Z","end":"2026-03-26T17:50:07.222637Z","steps":["trace[1103973585] 'range keys from in-memory index tree' (duration: 836.081319ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:07.222683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:06.386308Z","time spent":"836.368791ms","remote":"127.0.0.1:39014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T17:50:07.392475Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995350427960437610,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T17:50:07.483045Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.077491975s","expected-duration":"1s"} {"level":"info","ts":"2026-03-26T17:50:07.485148Z","caller":"traceutil/trace.go:171","msg":"trace[916894458] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:669; }","duration":"592.935705ms","start":"2026-03-26T17:50:06.892184Z","end":"2026-03-26T17:50:07.48512Z","steps":["trace[916894458] 'read index received' (duration: 591.163413ms)","trace[916894458] 'applied index is now lower than readState.Index' (duration: 1.771332ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:50:07.485237Z","caller":"traceutil/trace.go:171","msg":"trace[341928463] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.081029429s","start":"2026-03-26T17:50:06.404175Z","end":"2026-03-26T17:50:07.485205Z","steps":["trace[341928463] 'process raft request' (duration: 1.079386963s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:07.485411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:06.404147Z","time spent":"1.081166915s","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036277,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T17:50:07.485431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.247687ms","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-26T17:50:07.485437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.108726ms","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-26T17:50:07.485471Z","caller":"traceutil/trace.go:171","msg":"trace[1821998184] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:639; }","duration":"593.315449ms","start":"2026-03-26T17:50:06.892142Z","end":"2026-03-26T17:50:07.485458Z","steps":["trace[1821998184] 'agreement among raft nodes before linearized reading' (duration: 593.141282ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:50:07.485476Z","caller":"traceutil/trace.go:171","msg":"trace[157503875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"459.229471ms","start":"2026-03-26T17:50:07.026233Z","end":"2026-03-26T17:50:07.485463Z","steps":["trace[157503875] 'agreement among raft nodes before linearized reading' (duration: 459.068834ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:07.485498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:06.892125Z","time spent":"593.366902ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-26T17:50:07.485511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:07.026204Z","time spent":"459.300073ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T17:50:07.4857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.270012ms","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-26T17:50:07.485734Z","caller":"traceutil/trace.go:171","msg":"trace[360293556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"459.335705ms","start":"2026-03-26T17:50:07.02639Z","end":"2026-03-26T17:50:07.485726Z","steps":["trace[360293556] 'agreement among raft nodes before linearized reading' (duration: 459.251492ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:07.48576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:07.026362Z","time spent":"459.392408ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-26T17:50:08.2702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.28822ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-26T17:50:08.270419Z","caller":"traceutil/trace.go:171","msg":"trace[957753496] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"780.557867ms","start":"2026-03-26T17:50:07.489838Z","end":"2026-03-26T17:50:08.270396Z","steps":["trace[957753496] 'process raft request' (duration: 41.974485ms)","trace[957753496] 'compare' (duration: 737.983498ms)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:50:08.270454Z","caller":"traceutil/trace.go:171","msg":"trace[795743555] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"779.547685ms","start":"2026-03-26T17:50:07.490889Z","end":"2026-03-26T17:50:08.270436Z","steps":["trace[795743555] 'process raft request' (duration: 779.476993ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:08.270548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:07.490883Z","time spent":"779.614848ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T17:50:08.270626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:07.489813Z","time spent":"780.665981ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T17:50:08.270769Z","caller":"traceutil/trace.go:171","msg":"trace[1745783766] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"779.92428ms","start":"2026-03-26T17:50:07.490831Z","end":"2026-03-26T17:50:08.270755Z","steps":["trace[1745783766] 'process raft request' (duration: 779.478962ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:08.270902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:07.490822Z","time spent":"779.979853ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T17:50:08.271149Z","caller":"traceutil/trace.go:171","msg":"trace[309683291] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"716.765396ms","start":"2026-03-26T17:50:07.554372Z","end":"2026-03-26T17:50:08.271137Z","steps":["trace[309683291] 'process raft request' (duration: 716.270006ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:50:08.271126Z","caller":"traceutil/trace.go:171","msg":"trace[1341702408] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"779.687921ms","start":"2026-03-26T17:50:07.491415Z","end":"2026-03-26T17:50:08.271103Z","steps":["trace[1341702408] 'process raft request' (duration: 778.984653ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:08.271305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:07.554349Z","time spent":"716.832758ms","remote":"127.0.0.1:39494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-26T17:50:08.271336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:07.491407Z","time spent":"779.786145ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T17:50:10.775634Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995350427960437622,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T17:50:10.779929Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.093543953s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-26T17:50:10.780365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.393706573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T17:50:10.780421Z","caller":"traceutil/trace.go:171","msg":"trace[798470442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"2.393808266s","start":"2026-03-26T17:50:08.386598Z","end":"2026-03-26T17:50:10.780406Z","steps":["trace[798470442] 'range keys from in-memory index tree' (duration: 2.393617829s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:10.780443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:08.386573Z","time spent":"2.393865299s","remote":"127.0.0.1:39024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T17:50:10.780557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.343885202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.43\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-03-26T17:50:10.78057Z","caller":"traceutil/trace.go:171","msg":"trace[1641145936] range","detail":"{range_begin:/registry/masterleases/162.253.55.43; range_end:; response_count:1; response_revision:644; }","duration":"2.343929434s","start":"2026-03-26T17:50:08.436635Z","end":"2026-03-26T17:50:10.780565Z","steps":["trace[1641145936] 'range keys from in-memory index tree' (duration: 2.343735056s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:10.780583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:08.436613Z","time spent":"2.343966465s","remote":"127.0.0.1:39040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.43\" "} {"level":"warn","ts":"2026-03-26T17:50:10.780853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.611133164s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T17:50:10.780955Z","caller":"traceutil/trace.go:171","msg":"trace[1174136094] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:644; }","duration":"1.611244198s","start":"2026-03-26T17:50:09.169692Z","end":"2026-03-26T17:50:10.780936Z","steps":["trace[1174136094] 'range keys from in-memory index tree' (duration: 1.611118223s)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:50:10.780992Z","caller":"traceutil/trace.go:171","msg":"trace[1745394115] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"505.814493ms","start":"2026-03-26T17:50:10.275148Z","end":"2026-03-26T17:50:10.780963Z","steps":["trace[1745394115] 'read index received' (duration: 504.898707ms)","trace[1745394115] 'applied index is now lower than readState.Index' (duration: 914.786µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:50:10.781138Z","caller":"traceutil/trace.go:171","msg":"trace[1370107201] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"2.096285272s","start":"2026-03-26T17:50:08.684827Z","end":"2026-03-26T17:50:10.781113Z","steps":["trace[1370107201] 'process raft request' (duration: 2.095488651s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:10.781267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:08.684792Z","time spent":"2.096400047s","remote":"127.0.0.1:39088","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T17:50:10.78135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.197318ms","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-26T17:50:10.781427Z","caller":"traceutil/trace.go:171","msg":"trace[1736258911] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:645; }","duration":"506.292352ms","start":"2026-03-26T17:50:10.27512Z","end":"2026-03-26T17:50:10.781413Z","steps":["trace[1736258911] 'agreement among raft nodes before linearized reading' (duration: 506.062743ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:10.781494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:10.275105Z","time spent":"506.379215ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T17:50:10.781554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.338014ms","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-26T17:50:10.781615Z","caller":"traceutil/trace.go:171","msg":"trace[137335523] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:645; }","duration":"506.439568ms","start":"2026-03-26T17:50:10.275152Z","end":"2026-03-26T17:50:10.781591Z","steps":["trace[137335523] 'agreement among raft nodes before linearized reading' (duration: 506.135666ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:10.781657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:10.275131Z","time spent":"506.51693ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-26T17:50:10.781691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.946308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T17:50:10.781738Z","caller":"traceutil/trace.go:171","msg":"trace[220610408] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"505.99632ms","start":"2026-03-26T17:50:10.275731Z","end":"2026-03-26T17:50:10.781727Z","steps":["trace[220610408] 'agreement among raft nodes before linearized reading' (duration: 505.860045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:10.781769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:10.27566Z","time spent":"506.103055ms","remote":"127.0.0.1:39240","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-26T17:50:10.784011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.504259ms","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-26T17:50:10.784073Z","caller":"traceutil/trace.go:171","msg":"trace[1936950980] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:645; }","duration":"507.597653ms","start":"2026-03-26T17:50:10.276461Z","end":"2026-03-26T17:50:10.784059Z","steps":["trace[1936950980] 'agreement among raft nodes before linearized reading' (duration: 505.124626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:10.784115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:10.276447Z","time spent":"507.659356ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-26T17:50:10.784491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.101719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T17:50:10.784634Z","caller":"traceutil/trace.go:171","msg":"trace[1776548426] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"397.215963ms","start":"2026-03-26T17:50:10.387339Z","end":"2026-03-26T17:50:10.784555Z","steps":["trace[1776548426] 'agreement among raft nodes before linearized reading' (duration: 397.075738ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:10.784781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:10.387324Z","time spent":"397.440171ms","remote":"127.0.0.1:39014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-26T17:50:12.399484Z","caller":"traceutil/trace.go:171","msg":"trace[409766349] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"189.192603ms","start":"2026-03-26T17:50:12.210252Z","end":"2026-03-26T17:50:12.399445Z","steps":["trace[409766349] 'process raft request' (duration: 187.523739ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:12.931734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.991341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-03-26T17:50:12.93176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.201411ms","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-26T17:50:12.931819Z","caller":"traceutil/trace.go:171","msg":"trace[836700529] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:668; }","duration":"114.093484ms","start":"2026-03-26T17:50:12.817704Z","end":"2026-03-26T17:50:12.931798Z","steps":["trace[836700529] 'range keys from in-memory index tree' (duration: 113.767512ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:50:12.931838Z","caller":"traceutil/trace.go:171","msg":"trace[281739602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:668; }","duration":"113.332305ms","start":"2026-03-26T17:50:12.818486Z","end":"2026-03-26T17:50:12.931818Z","steps":["trace[281739602] 'range keys from in-memory index tree' (duration: 112.977012ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:12.931771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.656758ms","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-26T17:50:12.932102Z","caller":"traceutil/trace.go:171","msg":"trace[1251243105] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:668; }","duration":"114.043143ms","start":"2026-03-26T17:50:12.818038Z","end":"2026-03-26T17:50:12.932081Z","steps":["trace[1251243105] 'range keys from in-memory index tree' (duration: 113.472751ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:12.931848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.363125ms","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-26T17:50:12.93224Z","caller":"traceutil/trace.go:171","msg":"trace[1071530677] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:668; }","duration":"114.856004ms","start":"2026-03-26T17:50:12.81737Z","end":"2026-03-26T17:50:12.932226Z","steps":["trace[1071530677] 'range keys from in-memory index tree' (duration: 114.125286ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:27.380448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.850889ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-26T17:50:27.380588Z","caller":"traceutil/trace.go:171","msg":"trace[1682720481] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:847; }","duration":"1.10540247s","start":"2026-03-26T17:50:26.275166Z","end":"2026-03-26T17:50:27.380569Z","steps":["trace[1682720481] 'read index received' (duration: 432.257082ms)","trace[1682720481] 'applied index is now lower than readState.Index' (duration: 673.143018ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:50:27.380733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.063772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T17:50:27.380764Z","caller":"traceutil/trace.go:171","msg":"trace[1430847730] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:811; }","duration":"994.133043ms","start":"2026-03-26T17:50:26.386621Z","end":"2026-03-26T17:50:27.380754Z","steps":["trace[1430847730] 'agreement among raft nodes before linearized reading' (duration: 994.054401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:27.380802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:26.386607Z","time spent":"994.187346ms","remote":"127.0.0.1:39024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T17:50:27.380932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.864828ms","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-26T17:50:27.380957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.974131ms","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-26T17:50:27.380948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.100374ms","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-26T17:50:27.381019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.420334ms","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-26T17:50:27.381039Z","caller":"traceutil/trace.go:171","msg":"trace[1358536207] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:811; }","duration":"382.205147ms","start":"2026-03-26T17:50:26.998827Z","end":"2026-03-26T17:50:27.381032Z","steps":["trace[1358536207] 'agreement among raft nodes before linearized reading' (duration: 381.997981ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:50:27.381052Z","caller":"traceutil/trace.go:171","msg":"trace[423948225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:811; }","duration":"287.483657ms","start":"2026-03-26T17:50:27.093557Z","end":"2026-03-26T17:50:27.38104Z","steps":["trace[423948225] 'agreement among raft nodes before linearized reading' (duration: 287.422015ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:50:27.38105Z","caller":"traceutil/trace.go:171","msg":"trace[1118668511] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:811; }","duration":"379.108136ms","start":"2026-03-26T17:50:27.001922Z","end":"2026-03-26T17:50:27.38103Z","steps":["trace[1118668511] 'agreement among raft nodes before linearized reading' (duration: 378.91431ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:27.38096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105784762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dwdsf\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-03-26T17:50:27.381073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.767546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T17:50:27.381206Z","caller":"traceutil/trace.go:171","msg":"trace[1445039024] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:811; }","duration":"382.940171ms","start":"2026-03-26T17:50:26.998247Z","end":"2026-03-26T17:50:27.381187Z","steps":["trace[1445039024] 'agreement among raft nodes before linearized reading' (duration: 382.721744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:27.381263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:26.998227Z","time spent":"383.018134ms","remote":"127.0.0.1:39240","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-26T17:50:27.381007Z","caller":"traceutil/trace.go:171","msg":"trace[1312083638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:811; }","duration":"378.987232ms","start":"2026-03-26T17:50:27.002Z","end":"2026-03-26T17:50:27.380987Z","steps":["trace[1312083638] 'agreement among raft nodes before linearized reading' (duration: 378.776365ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:50:27.381283Z","caller":"traceutil/trace.go:171","msg":"trace[1508050025] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dwdsf; range_end:; response_count:1; response_revision:811; }","duration":"1.106060751s","start":"2026-03-26T17:50:26.275114Z","end":"2026-03-26T17:50:27.381175Z","steps":["trace[1508050025] 'agreement among raft nodes before linearized reading' (duration: 1.105551435s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:50:27.381509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:26.275095Z","time spent":"1.106394962s","remote":"127.0.0.1:39174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-dwdsf\" "} {"level":"warn","ts":"2026-03-26T17:50:27.381106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:27.001902Z","time spent":"379.190668ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-26T17:50:27.381389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:27.001979Z","time spent":"379.381015ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T17:50:27.381113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:50:26.998822Z","time spent":"382.239828ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-26T17:50:36.719086Z","caller":"traceutil/trace.go:171","msg":"trace[408006068] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"273.189802ms","start":"2026-03-26T17:50:36.44587Z","end":"2026-03-26T17:50:36.71906Z","steps":["trace[408006068] 'process raft request' (duration: 272.974965ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:50:36.719072Z","caller":"traceutil/trace.go:171","msg":"trace[447734568] transaction","detail":"{read_only:false; response_revision:912; number_of_response:1; }","duration":"272.020326ms","start":"2026-03-26T17:50:36.447027Z","end":"2026-03-26T17:50:36.719047Z","steps":["trace[447734568] 'process raft request' (duration: 271.958334ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:50:41.89795Z","caller":"traceutil/trace.go:171","msg":"trace[1389365887] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"117.988775ms","start":"2026-03-26T17:50:41.77994Z","end":"2026-03-26T17:50:41.897929Z","steps":["trace[1389365887] 'process raft request' (duration: 117.868781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:51:08.227637Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995350427960438511,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-26T17:51:08.311044Z","caller":"traceutil/trace.go:171","msg":"trace[752276330] linearizableReadLoop","detail":"{readStateIndex:1124; appliedIndex:1123; }","duration":"584.386267ms","start":"2026-03-26T17:51:07.726632Z","end":"2026-03-26T17:51:08.311018Z","steps":["trace[752276330] 'read index received' (duration: 584.170392ms)","trace[752276330] 'applied index is now lower than readState.Index' (duration: 215.015µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:51:08.311102Z","caller":"traceutil/trace.go:171","msg":"trace[1101678896] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"592.801405ms","start":"2026-03-26T17:51:07.718277Z","end":"2026-03-26T17:51:08.311079Z","steps":["trace[1101678896] 'process raft request' (duration: 592.614541ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:51:08.311244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:51:07.718256Z","time spent":"592.898557ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T17:51:08.311313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.422153ms","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-26T17:51:08.311302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.254623ms","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-26T17:51:08.311345Z","caller":"traceutil/trace.go:171","msg":"trace[1327819099] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1077; }","duration":"420.493755ms","start":"2026-03-26T17:51:07.890844Z","end":"2026-03-26T17:51:08.311337Z","steps":["trace[1327819099] 'agreement among raft nodes before linearized reading' (duration: 420.384433ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:51:08.311384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:51:07.890826Z","time spent":"420.553296ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-26T17:51:08.311407Z","caller":"traceutil/trace.go:171","msg":"trace[997949946] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1077; }","duration":"531.353925ms","start":"2026-03-26T17:51:07.779992Z","end":"2026-03-26T17:51:08.311346Z","steps":["trace[997949946] 'agreement among raft nodes before linearized reading' (duration: 531.185422ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:51:08.311387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.872481ms","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-26T17:51:08.31146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.980774ms","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-26T17:51:08.311481Z","caller":"traceutil/trace.go:171","msg":"trace[348560447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1077; }","duration":"398.026104ms","start":"2026-03-26T17:51:07.91345Z","end":"2026-03-26T17:51:08.311476Z","steps":["trace[348560447] 'agreement among raft nodes before linearized reading' (duration: 397.981083ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:51:08.311471Z","caller":"traceutil/trace.go:171","msg":"trace[1844718926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1077; }","duration":"398.046765ms","start":"2026-03-26T17:51:07.913403Z","end":"2026-03-26T17:51:08.31145Z","steps":["trace[1844718926] 'agreement among raft nodes before linearized reading' (duration: 397.816149ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:51:08.311497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:51:07.913433Z","time spent":"398.060386ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-26T17:51:08.311455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:51:07.77997Z","time spent":"531.473718ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-26T17:51:08.311513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:51:07.913373Z","time spent":"398.130727ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-26T17:51:08.311527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.605141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T17:51:08.311566Z","caller":"traceutil/trace.go:171","msg":"trace[415702634] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1077; }","duration":"439.675963ms","start":"2026-03-26T17:51:07.871876Z","end":"2026-03-26T17:51:08.311552Z","steps":["trace[415702634] 'agreement among raft nodes before linearized reading' (duration: 439.618612ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:51:08.311649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:51:07.871852Z","time spent":"439.784435ms","remote":"127.0.0.1:39478","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-03-26T17:51:08.311953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.318308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-26T17:51:08.312057Z","caller":"traceutil/trace.go:171","msg":"trace[2021506090] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1077; }","duration":"585.471372ms","start":"2026-03-26T17:51:07.726568Z","end":"2026-03-26T17:51:08.31204Z","steps":["trace[2021506090] 'agreement among raft nodes before linearized reading' (duration: 585.323418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:51:08.31213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:51:07.726545Z","time spent":"585.571233ms","remote":"127.0.0.1:39356","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":29,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-03-26T17:51:08.870016Z","caller":"traceutil/trace.go:171","msg":"trace[703474462] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"149.905133ms","start":"2026-03-26T17:51:08.720092Z","end":"2026-03-26T17:51:08.869997Z","steps":["trace[703474462] 'process raft request' (duration: 149.77041ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:51:12.448104Z","caller":"traceutil/trace.go:171","msg":"trace[1663329592] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"120.54484ms","start":"2026-03-26T17:51:12.327533Z","end":"2026-03-26T17:51:12.448078Z","steps":["trace[1663329592] 'process raft request' (duration: 120.255744ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:51:13.887713Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995350427960438550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T17:51:14.400943Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995350427960438550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T17:51:14.901898Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995350427960438550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T17:51:15.387152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999938678s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-03-26T17:51:15.387254Z","caller":"traceutil/trace.go:171","msg":"trace[877853135] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000157362s","start":"2026-03-26T17:51:13.387072Z","end":"2026-03-26T17:51:15.387229Z","steps":["trace[877853135] 'agreement among raft nodes before linearized reading' (duration: 1.999965018s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:51:15.387481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:51:13.387056Z","time spent":"2.000400028s","remote":"127.0.0.1:39024","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/03/26 17:51:15 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-03-26T17:51:15.402706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":16995350427960438550,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-26T17:51:15.753189Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.866850642s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-26T17:51:15.754511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:51:13.657961Z","time spent":"2.096543988s","remote":"127.0.0.1:37942","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-03-26T17:51:15.756738Z","caller":"traceutil/trace.go:171","msg":"trace[53799475] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"2.870472378s","start":"2026-03-26T17:51:12.886238Z","end":"2026-03-26T17:51:15.75671Z","steps":["trace[53799475] 'process raft request' (duration: 2.86721212s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:51:15.75694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:51:12.886221Z","time spent":"2.87058903s","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T17:51:15.758919Z","caller":"traceutil/trace.go:171","msg":"trace[740521722] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"1.431962061s","start":"2026-03-26T17:51:14.326938Z","end":"2026-03-26T17:51:15.7589Z","steps":["trace[740521722] 'process raft request' (duration: 1.431718256s)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:51:15.759022Z","caller":"traceutil/trace.go:171","msg":"trace[1422712347] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"197.552699ms","start":"2026-03-26T17:51:15.561454Z","end":"2026-03-26T17:51:15.759007Z","steps":["trace[1422712347] 'process raft request' (duration: 197.331675ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:51:15.758939Z","caller":"traceutil/trace.go:171","msg":"trace[1590449428] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1142; }","duration":"2.371808061s","start":"2026-03-26T17:51:13.387113Z","end":"2026-03-26T17:51:15.758921Z","steps":["trace[1590449428] 'read index received' (duration: 2.366314336s)","trace[1590449428] 'applied index is now lower than readState.Index' (duration: 5.493045ms)"],"step_count":2} {"level":"warn","ts":"2026-03-26T17:51:15.7599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307435508s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-03-26T17:51:15.760005Z","caller":"traceutil/trace.go:171","msg":"trace[347002269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1097; }","duration":"1.307592602s","start":"2026-03-26T17:51:14.452401Z","end":"2026-03-26T17:51:15.759994Z","steps":["trace[347002269] 'agreement among raft nodes before linearized reading' (duration: 1.307381657s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:51:15.760092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.336953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T17:51:15.760124Z","caller":"traceutil/trace.go:171","msg":"trace[1583648655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1097; }","duration":"372.384223ms","start":"2026-03-26T17:51:15.387731Z","end":"2026-03-26T17:51:15.760115Z","steps":["trace[1583648655] 'agreement among raft nodes before linearized reading' (duration: 372.346662ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:51:15.760144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:51:15.387701Z","time spent":"372.437665ms","remote":"127.0.0.1:39014","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-26T17:51:15.760099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:51:14.452385Z","time spent":"1.307698134s","remote":"127.0.0.1:39240","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-26T17:51:15.760288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.610159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-26T17:51:15.760317Z","caller":"traceutil/trace.go:171","msg":"trace[1228634584] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1097; }","duration":"361.650599ms","start":"2026-03-26T17:51:15.398657Z","end":"2026-03-26T17:51:15.760307Z","steps":["trace[1228634584] 'agreement among raft nodes before linearized reading' (duration: 361.601418ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:51:15.760341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:51:15.39865Z","time spent":"361.6856ms","remote":"127.0.0.1:39512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-03-26T17:51:15.759959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.043067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-26T17:51:15.760595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301574004s","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-26T17:51:15.760621Z","caller":"traceutil/trace.go:171","msg":"trace[536815491] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1097; }","duration":"1.301616305s","start":"2026-03-26T17:51:14.458998Z","end":"2026-03-26T17:51:15.760614Z","steps":["trace[536815491] 'agreement among raft nodes before linearized reading' (duration: 1.301565794s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:51:15.760644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:51:14.458988Z","time spent":"1.301652056s","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-26T17:51:15.7606Z","caller":"traceutil/trace.go:171","msg":"trace[1580878103] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1097; }","duration":"139.70262ms","start":"2026-03-26T17:51:15.620886Z","end":"2026-03-26T17:51:15.760589Z","steps":["trace[1580878103] 'agreement among raft nodes before linearized reading' (duration: 139.031696ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:51:15.760551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.3046855s","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-26T17:51:15.760761Z","caller":"traceutil/trace.go:171","msg":"trace[308113096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1097; }","duration":"1.304900154s","start":"2026-03-26T17:51:14.455849Z","end":"2026-03-26T17:51:15.760749Z","steps":["trace[308113096] 'agreement among raft nodes before linearized reading' (duration: 1.304653909s)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:51:15.760797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:51:14.455843Z","time spent":"1.304944245s","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-26T17:51:15.763634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:51:14.326915Z","time spent":"1.432041753s","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-26T17:52:24.531352Z","caller":"traceutil/trace.go:171","msg":"trace[1347291105] linearizableReadLoop","detail":"{readStateIndex:1424; appliedIndex:1423; }","duration":"384.077412ms","start":"2026-03-26T17:52:24.147255Z","end":"2026-03-26T17:52:24.531332Z","steps":["trace[1347291105] 'read index received' (duration: 383.823088ms)","trace[1347291105] 'applied index is now lower than readState.Index' (duration: 253.514µs)"],"step_count":2} {"level":"info","ts":"2026-03-26T17:52:24.531521Z","caller":"traceutil/trace.go:171","msg":"trace[1105797083] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"464.868141ms","start":"2026-03-26T17:52:24.066624Z","end":"2026-03-26T17:52:24.531492Z","steps":["trace[1105797083] 'process raft request' (duration: 464.546886ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:52:24.531626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.334626ms","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-26T17:52:24.531662Z","caller":"traceutil/trace.go:171","msg":"trace[269125366] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1360; }","duration":"384.465837ms","start":"2026-03-26T17:52:24.147187Z","end":"2026-03-26T17:52:24.531653Z","steps":["trace[269125366] 'agreement among raft nodes before linearized reading' (duration: 384.258594ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:52:24.531669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.915237ms","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-26T17:52:24.531754Z","caller":"traceutil/trace.go:171","msg":"trace[749834138] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1360; }","duration":"298.137111ms","start":"2026-03-26T17:52:24.233602Z","end":"2026-03-26T17:52:24.531739Z","steps":["trace[749834138] 'agreement among raft nodes before linearized reading' (duration: 297.878517ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:52:24.531809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.081116ms","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-26T17:52:24.531829Z","caller":"traceutil/trace.go:171","msg":"trace[188443688] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1360; }","duration":"258.148318ms","start":"2026-03-26T17:52:24.273675Z","end":"2026-03-26T17:52:24.531824Z","steps":["trace[188443688] 'agreement among raft nodes before linearized reading' (duration: 258.065687ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:52:24.531795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:52:24.066589Z","time spent":"465.034433ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-26T17:52:24.531911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.644254ms","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":"warn","ts":"2026-03-26T17:52:24.531716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-26T17:52:24.147163Z","time spent":"384.545629ms","remote":"127.0.0.1:39240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-26T17:52:24.531983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.532171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-26T17:52:24.532004Z","caller":"traceutil/trace.go:171","msg":"trace[1396081338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1360; }","duration":"231.789816ms","start":"2026-03-26T17:52:24.300193Z","end":"2026-03-26T17:52:24.531982Z","steps":["trace[1396081338] 'agreement among raft nodes before linearized reading' (duration: 231.502602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-26T17:52:24.53203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.024906ms","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-26T17:52:24.53215Z","caller":"traceutil/trace.go:171","msg":"trace[1860044849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1360; }","duration":"145.758504ms","start":"2026-03-26T17:52:24.386316Z","end":"2026-03-26T17:52:24.532075Z","steps":["trace[1860044849] 'agreement among raft nodes before linearized reading' (duration: 145.502061ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:52:24.532117Z","caller":"traceutil/trace.go:171","msg":"trace[504824561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1360; }","duration":"285.156669ms","start":"2026-03-26T17:52:24.246934Z","end":"2026-03-26T17:52:24.532091Z","steps":["trace[504824561] 'agreement among raft nodes before linearized reading' (duration: 284.906725ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:52:24.692453Z","caller":"traceutil/trace.go:171","msg":"trace[1023061085] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"155.45366ms","start":"2026-03-26T17:52:24.536977Z","end":"2026-03-26T17:52:24.69243Z","steps":["trace[1023061085] 'process raft request' (duration: 155.164066ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:52:24.692487Z","caller":"traceutil/trace.go:171","msg":"trace[923941945] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"155.080555ms","start":"2026-03-26T17:52:24.537384Z","end":"2026-03-26T17:52:24.692464Z","steps":["trace[923941945] 'process raft request' (duration: 154.792021ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:52:24.692669Z","caller":"traceutil/trace.go:171","msg":"trace[712899475] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"156.496885ms","start":"2026-03-26T17:52:24.536161Z","end":"2026-03-26T17:52:24.692658Z","steps":["trace[712899475] 'process raft request' (duration: 155.708644ms)"],"step_count":1} {"level":"info","ts":"2026-03-26T17:52:24.692832Z","caller":"traceutil/trace.go:171","msg":"trace[1040065030] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"155.871936ms","start":"2026-03-26T17:52:24.536928Z","end":"2026-03-26T17:52:24.6928Z","steps":["trace[1040065030] 'process raft request' (duration: 155.134746ms)"],"step_count":1}