{"level":"warn","ts":"2026-05-07T23:19:05.055559Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T23:19:05.055646Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.153:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.153:2380","--initial-cluster=instance=https://199.19.213.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.153: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-05-07T23:19:05.055707Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-07T23:19:05.055721Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.153:2380"]} {"level":"info","ts":"2026-05-07T23:19:05.055739Z","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-05-07T23:19:05.056378Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.153:2379"]} {"level":"info","ts":"2026-05-07T23:19:05.05645Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.153:2380"],"listen-peer-urls":["https://199.19.213.153:2380"],"advertise-client-urls":["https://199.19.213.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.153: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-05-07T23:19:05.061235Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.593653ms"} {"level":"info","ts":"2026-05-07T23:19:05.069552Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"36b63984d68b7065","cluster-id":"7c251967b436417d"} {"level":"info","ts":"2026-05-07T23:19:05.069624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T23:19:05.069648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became follower at term 0"} {"level":"info","ts":"2026-05-07T23:19:05.069663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 36b63984d68b7065 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T23:19:05.069679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became follower at term 1"} {"level":"info","ts":"2026-05-07T23:19:05.069715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 switched to configuration voters=(3942401766507376741)"} {"level":"warn","ts":"2026-05-07T23:19:05.08094Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T23:19:05.082705Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T23:19:05.083797Z","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-05-07T23:19:05.086608Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"36b63984d68b7065","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T23:19:05.086795Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"36b63984d68b7065","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T23:19:05.087159Z","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-05-07T23:19:05.087411Z","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-05-07T23:19:05.087681Z","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-05-07T23:19:05.08953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 switched to configuration voters=(3942401766507376741)"} {"level":"info","ts":"2026-05-07T23:19:05.09202Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"7c251967b436417d","local-member-id":"36b63984d68b7065","added-peer-id":"36b63984d68b7065","added-peer-peer-urls":["https://199.19.213.153:2380"]} {"level":"info","ts":"2026-05-07T23:19:05.093241Z","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-05-07T23:19:05.093442Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"36b63984d68b7065","initial-advertise-peer-urls":["https://199.19.213.153:2380"],"listen-peer-urls":["https://199.19.213.153:2380"],"advertise-client-urls":["https://199.19.213.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T23:19:05.093518Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T23:19:05.094112Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.153:2380"} {"level":"info","ts":"2026-05-07T23:19:05.094128Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.153:2380"} {"level":"info","ts":"2026-05-07T23:19:05.970569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T23:19:05.970646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T23:19:05.97069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 received MsgPreVoteResp from 36b63984d68b7065 at term 1"} {"level":"info","ts":"2026-05-07T23:19:05.970742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became candidate at term 2"} {"level":"info","ts":"2026-05-07T23:19:05.970761Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 received MsgVoteResp from 36b63984d68b7065 at term 2"} {"level":"info","ts":"2026-05-07T23:19:05.970778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"36b63984d68b7065 became leader at term 2"} {"level":"info","ts":"2026-05-07T23:19:05.970792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 36b63984d68b7065 elected leader 36b63984d68b7065 at term 2"} {"level":"info","ts":"2026-05-07T23:19:05.972218Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:19:05.974277Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T23:19:05.974275Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"36b63984d68b7065","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.153:2379]}","request-path":"/0/members/36b63984d68b7065/attributes","cluster-id":"7c251967b436417d","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T23:19:05.974317Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T23:19:05.974481Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"7c251967b436417d","local-member-id":"36b63984d68b7065","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:19:05.974606Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:19:05.974643Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T23:19:05.974665Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T23:19:05.974739Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T23:19:05.976618Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T23:19:05.978467Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.153:2379"} {"level":"info","ts":"2026-05-07T23:19:10.937158Z","caller":"traceutil/trace.go:171","msg":"trace[894649555] transaction","detail":"{read_only:false; response_revision:231; number_of_response:1; }","duration":"166.772766ms","start":"2026-05-07T23:19:10.770353Z","end":"2026-05-07T23:19:10.937126Z","steps":["trace[894649555] 'process raft request' (duration: 81.048536ms)","trace[894649555] 'compare' (duration: 85.60075ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:10.946073Z","caller":"traceutil/trace.go:171","msg":"trace[1633603542] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"174.280498ms","start":"2026-05-07T23:19:10.771773Z","end":"2026-05-07T23:19:10.946054Z","steps":["trace[1633603542] 'process raft request' (duration: 174.240017ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:10.946101Z","caller":"traceutil/trace.go:171","msg":"trace[2078001527] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"174.639691ms","start":"2026-05-07T23:19:10.771428Z","end":"2026-05-07T23:19:10.946067Z","steps":["trace[2078001527] 'process raft request' (duration: 174.48068ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:12.826868Z","caller":"traceutil/trace.go:171","msg":"trace[557222218] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"187.235223ms","start":"2026-05-07T23:19:12.639575Z","end":"2026-05-07T23:19:12.82681Z","steps":["trace[557222218] 'process raft request' (duration: 181.595135ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:12.943859Z","caller":"traceutil/trace.go:171","msg":"trace[132998695] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"107.874861ms","start":"2026-05-07T23:19:12.835958Z","end":"2026-05-07T23:19:12.943833Z","steps":["trace[132998695] 'process raft request' (duration: 107.540698ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:13.586539Z","caller":"traceutil/trace.go:171","msg":"trace[1619100572] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"105.321652ms","start":"2026-05-07T23:19:13.481189Z","end":"2026-05-07T23:19:13.586511Z","steps":["trace[1619100572] 'process raft request' (duration: 35.671708ms)","trace[1619100572] 'compare' (duration: 69.420583ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:13.586784Z","caller":"traceutil/trace.go:171","msg":"trace[1208510003] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"103.804871ms","start":"2026-05-07T23:19:13.482942Z","end":"2026-05-07T23:19:13.586747Z","steps":["trace[1208510003] 'process raft request' (duration: 103.529649ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:14.502661Z","caller":"traceutil/trace.go:171","msg":"trace[1776889168] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"112.144562ms","start":"2026-05-07T23:19:14.390494Z","end":"2026-05-07T23:19:14.502639Z","steps":["trace[1776889168] 'process raft request' (duration: 111.999811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:15.07672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.371512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:19:15.076968Z","caller":"traceutil/trace.go:171","msg":"trace[1637554036] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"237.176648ms","start":"2026-05-07T23:19:14.839735Z","end":"2026-05-07T23:19:15.076911Z","steps":["trace[1637554036] 'process raft request' (duration: 121.141492ms)","trace[1637554036] 'compare' (duration: 115.220291ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:15.262829Z","caller":"traceutil/trace.go:171","msg":"trace[799741008] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"148.565489ms","start":"2026-05-07T23:19:15.114216Z","end":"2026-05-07T23:19:15.262782Z","steps":["trace[799741008] 'process raft request' (duration: 120.937087ms)","trace[799741008] 'compare' (duration: 27.33184ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:15.437799Z","caller":"traceutil/trace.go:171","msg":"trace[936690768] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"163.84087ms","start":"2026-05-07T23:19:15.273921Z","end":"2026-05-07T23:19:15.437762Z","steps":["trace[936690768] 'process raft request' (duration: 89.047587ms)","trace[936690768] 'compare' (duration: 74.594181ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:15.684718Z","caller":"traceutil/trace.go:171","msg":"trace[24626264] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"184.803828ms","start":"2026-05-07T23:19:15.499889Z","end":"2026-05-07T23:19:15.684693Z","steps":["trace[24626264] 'process raft request' (duration: 106.915944ms)","trace[24626264] 'compare' (duration: 77.753853ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:15.936458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.322058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:19:15.936635Z","caller":"traceutil/trace.go:171","msg":"trace[586919106] transaction","detail":"{read_only:false; response_revision:301; number_of_response:1; }","duration":"245.085195ms","start":"2026-05-07T23:19:15.691532Z","end":"2026-05-07T23:19:15.936617Z","steps":["trace[586919106] 'process raft request' (duration: 245.032765ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:15.936687Z","caller":"traceutil/trace.go:171","msg":"trace[24708990] linearizableReadLoop","detail":"{readStateIndex:313; appliedIndex:311; }","duration":"425.700945ms","start":"2026-05-07T23:19:15.510949Z","end":"2026-05-07T23:19:15.93665Z","steps":["trace[24708990] 'read index received' (duration: 95.869712ms)","trace[24708990] 'applied index is now lower than readState.Index' (duration: 329.827443ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:15.937015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.989172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-07T23:19:15.93709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.922443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-05-07T23:19:15.937101Z","caller":"traceutil/trace.go:171","msg":"trace[401373904] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:301; }","duration":"416.244024ms","start":"2026-05-07T23:19:15.520838Z","end":"2026-05-07T23:19:15.937082Z","steps":["trace[401373904] 'agreement among raft nodes before linearized reading' (duration: 416.004883ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:15.937133Z","caller":"traceutil/trace.go:171","msg":"trace[597829271] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:301; }","duration":"298.015973ms","start":"2026-05-07T23:19:15.639103Z","end":"2026-05-07T23:19:15.937119Z","steps":["trace[597829271] 'agreement among raft nodes before linearized reading' (duration: 297.922013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:15.937162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:15.520827Z","time spent":"416.319815ms","remote":"127.0.0.1:52690","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":"warn","ts":"2026-05-07T23:19:15.937326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.671014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-05-07T23:19:15.937365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.656197ms","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-05-07T23:19:15.937396Z","caller":"traceutil/trace.go:171","msg":"trace[1079625742] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:301; }","duration":"197.764224ms","start":"2026-05-07T23:19:15.739622Z","end":"2026-05-07T23:19:15.937387Z","steps":["trace[1079625742] 'agreement among raft nodes before linearized reading' (duration: 197.672524ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:15.937424Z","caller":"traceutil/trace.go:171","msg":"trace[1602036972] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:301; }","duration":"146.775607ms","start":"2026-05-07T23:19:15.790629Z","end":"2026-05-07T23:19:15.937405Z","steps":["trace[1602036972] 'agreement among raft nodes before linearized reading' (duration: 146.612026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:15.937489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.356607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-05-07T23:19:15.937474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.792472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-05-07T23:19:15.937516Z","caller":"traceutil/trace.go:171","msg":"trace[2144734408] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:301; }","duration":"248.383717ms","start":"2026-05-07T23:19:15.689124Z","end":"2026-05-07T23:19:15.937508Z","steps":["trace[2144734408] 'agreement among raft nodes before linearized reading' (duration: 248.335527ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:15.93751Z","caller":"traceutil/trace.go:171","msg":"trace[610237557] transaction","detail":"{read_only:false; response_revision:300; number_of_response:1; }","duration":"432.688741ms","start":"2026-05-07T23:19:15.504748Z","end":"2026-05-07T23:19:15.937437Z","steps":["trace[610237557] 'process raft request' (duration: 283.323637ms)","trace[610237557] 'compare' (duration: 148.162717ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:15.937628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.587365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-07T23:19:15.93763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.708846ms","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-05-07T23:19:15.937653Z","caller":"traceutil/trace.go:171","msg":"trace[45805254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:301; }","duration":"346.654285ms","start":"2026-05-07T23:19:15.590993Z","end":"2026-05-07T23:19:15.937647Z","steps":["trace[45805254] 'agreement among raft nodes before linearized reading' (duration: 346.596204ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:15.937661Z","caller":"traceutil/trace.go:171","msg":"trace[2100050820] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:301; }","duration":"346.752336ms","start":"2026-05-07T23:19:15.590901Z","end":"2026-05-07T23:19:15.937653Z","steps":["trace[2100050820] 'agreement among raft nodes before linearized reading' (duration: 346.692445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:15.937689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:15.59097Z","time spent":"346.712565ms","remote":"127.0.0.1:52752","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-05-07T23:19:15.937689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:15.590892Z","time spent":"346.789216ms","remote":"127.0.0.1:52752","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-05-07T23:19:15.93769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.200612ms","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-05-07T23:19:15.937721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:15.504723Z","time spent":"432.895163ms","remote":"127.0.0.1:52620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1723,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:19:15.937557Z","caller":"traceutil/trace.go:171","msg":"trace[1877390972] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:301; }","duration":"347.914663ms","start":"2026-05-07T23:19:15.589618Z","end":"2026-05-07T23:19:15.937533Z","steps":["trace[1877390972] 'agreement among raft nodes before linearized reading' (duration: 347.694852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:15.937814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:15.589572Z","time spent":"348.235405ms","remote":"127.0.0.1:52690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":225,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"info","ts":"2026-05-07T23:19:15.937727Z","caller":"traceutil/trace.go:171","msg":"trace[911639719] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:301; }","duration":"120.269753ms","start":"2026-05-07T23:19:15.817447Z","end":"2026-05-07T23:19:15.937717Z","steps":["trace[911639719] 'agreement among raft nodes before linearized reading' (duration: 120.199373ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:15.937628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.919225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-05-07T23:19:15.937554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.366095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" ","response":"range_response_count:1 size:234"} {"level":"info","ts":"2026-05-07T23:19:15.937868Z","caller":"traceutil/trace.go:171","msg":"trace[1635032002] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:301; }","duration":"415.171556ms","start":"2026-05-07T23:19:15.522682Z","end":"2026-05-07T23:19:15.937854Z","steps":["trace[1635032002] 'agreement among raft nodes before linearized reading' (duration: 414.909784ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:15.937882Z","caller":"traceutil/trace.go:171","msg":"trace[701774047] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:301; }","duration":"398.695367ms","start":"2026-05-07T23:19:15.539174Z","end":"2026-05-07T23:19:15.937869Z","steps":["trace[701774047] 'agreement among raft nodes before linearized reading' (duration: 398.328915ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:15.937909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:15.539142Z","time spent":"398.760367ms","remote":"127.0.0.1:52690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":256,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-05-07T23:19:15.937917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:15.522675Z","time spent":"415.228186ms","remote":"127.0.0.1:52690","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":"warn","ts":"2026-05-07T23:19:15.939135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.998667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-07T23:19:15.939222Z","caller":"traceutil/trace.go:171","msg":"trace[1633541826] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:301; }","duration":"428.300232ms","start":"2026-05-07T23:19:15.51091Z","end":"2026-05-07T23:19:15.93921Z","steps":["trace[1633541826] 'agreement among raft nodes before linearized reading' (duration: 425.832916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:15.939679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:15.510892Z","time spent":"428.775405ms","remote":"127.0.0.1:52690","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":"info","ts":"2026-05-07T23:19:16.088142Z","caller":"traceutil/trace.go:171","msg":"trace[1082535397] transaction","detail":"{read_only:false; response_revision:302; number_of_response:1; }","duration":"145.632447ms","start":"2026-05-07T23:19:15.942482Z","end":"2026-05-07T23:19:16.088114Z","steps":["trace[1082535397] 'process raft request' (duration: 124.172136ms)","trace[1082535397] 'compare' (duration: 21.31734ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:16.123441Z","caller":"traceutil/trace.go:171","msg":"trace[331361494] transaction","detail":"{read_only:false; response_revision:303; number_of_response:1; }","duration":"180.753676ms","start":"2026-05-07T23:19:15.942643Z","end":"2026-05-07T23:19:16.123396Z","steps":["trace[331361494] 'process raft request' (duration: 180.477745ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:16.123451Z","caller":"traceutil/trace.go:171","msg":"trace[1908320904] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"180.575546ms","start":"2026-05-07T23:19:15.942771Z","end":"2026-05-07T23:19:16.123347Z","steps":["trace[1908320904] 'process raft request' (duration: 180.485866ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:16.123705Z","caller":"traceutil/trace.go:171","msg":"trace[1966310480] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"180.883278ms","start":"2026-05-07T23:19:15.942811Z","end":"2026-05-07T23:19:16.123694Z","steps":["trace[1966310480] 'process raft request' (duration: 180.482975ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:16.124114Z","caller":"traceutil/trace.go:171","msg":"trace[399115148] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"180.240735ms","start":"2026-05-07T23:19:15.943856Z","end":"2026-05-07T23:19:16.124096Z","steps":["trace[399115148] 'process raft request' (duration: 179.67057ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:16.124174Z","caller":"traceutil/trace.go:171","msg":"trace[1624037980] linearizableReadLoop","detail":"{readStateIndex:317; appliedIndex:314; }","duration":"181.361971ms","start":"2026-05-07T23:19:15.942791Z","end":"2026-05-07T23:19:16.124153Z","steps":["trace[1624037980] 'read index received' (duration: 123.875984ms)","trace[1624037980] 'applied index is now lower than readState.Index' (duration: 57.484727ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:16.124391Z","caller":"traceutil/trace.go:171","msg":"trace[338449625] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"179.806911ms","start":"2026-05-07T23:19:15.944571Z","end":"2026-05-07T23:19:16.124378Z","steps":["trace[338449625] 'process raft request' (duration: 179.528489ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:16.124438Z","caller":"traceutil/trace.go:171","msg":"trace[602562808] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"180.115274ms","start":"2026-05-07T23:19:15.944304Z","end":"2026-05-07T23:19:16.124419Z","steps":["trace[602562808] 'process raft request' (duration: 179.704381ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:16.12455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.725723ms","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-05-07T23:19:16.124678Z","caller":"traceutil/trace.go:171","msg":"trace[1596589456] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:308; }","duration":"181.883725ms","start":"2026-05-07T23:19:15.942756Z","end":"2026-05-07T23:19:16.124639Z","steps":["trace[1596589456] 'agreement among raft nodes before linearized reading' (duration: 181.702474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:16.126345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.487465ms","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-05-07T23:19:16.126492Z","caller":"traceutil/trace.go:171","msg":"trace[950786544] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:309; }","duration":"183.646057ms","start":"2026-05-07T23:19:15.942826Z","end":"2026-05-07T23:19:16.126472Z","steps":["trace[950786544] 'agreement among raft nodes before linearized reading' (duration: 183.437794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:16.126736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.846608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"warn","ts":"2026-05-07T23:19:16.126779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.158404ms","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-05-07T23:19:16.126945Z","caller":"traceutil/trace.go:171","msg":"trace[1206362655] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:309; }","duration":"136.341436ms","start":"2026-05-07T23:19:15.990582Z","end":"2026-05-07T23:19:16.126924Z","steps":["trace[1206362655] 'agreement among raft nodes before linearized reading' (duration: 136.104864ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:16.1269Z","caller":"traceutil/trace.go:171","msg":"trace[1341489816] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:309; }","duration":"184.025339ms","start":"2026-05-07T23:19:15.942857Z","end":"2026-05-07T23:19:16.126882Z","steps":["trace[1341489816] 'agreement among raft nodes before linearized reading' (duration: 183.831608ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:16.543349Z","caller":"traceutil/trace.go:171","msg":"trace[1065681094] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"138.698029ms","start":"2026-05-07T23:19:16.404609Z","end":"2026-05-07T23:19:16.543307Z","steps":["trace[1065681094] 'process raft request' (duration: 90.408433ms)","trace[1065681094] 'compare' (duration: 48.050295ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:16.594151Z","caller":"traceutil/trace.go:171","msg":"trace[1455628806] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"189.503532ms","start":"2026-05-07T23:19:16.404618Z","end":"2026-05-07T23:19:16.594121Z","steps":["trace[1455628806] 'process raft request' (duration: 189.352911ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:20.918499Z","caller":"traceutil/trace.go:171","msg":"trace[275028862] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"104.567644ms","start":"2026-05-07T23:19:20.813895Z","end":"2026-05-07T23:19:20.918463Z","steps":["trace[275028862] 'process raft request' (duration: 104.360572ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:21.261609Z","caller":"traceutil/trace.go:171","msg":"trace[923179648] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"102.679689ms","start":"2026-05-07T23:19:21.158903Z","end":"2026-05-07T23:19:21.261583Z","steps":["trace[923179648] 'process raft request' (duration: 102.409338ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:24.387891Z","caller":"traceutil/trace.go:171","msg":"trace[2147337648] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"223.242136ms","start":"2026-05-07T23:19:24.164626Z","end":"2026-05-07T23:19:24.387868Z","steps":["trace[2147337648] 'read index received' (duration: 223.086605ms)","trace[2147337648] 'applied index is now lower than readState.Index' (duration: 154.481µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:24.388003Z","caller":"traceutil/trace.go:171","msg":"trace[1376393960] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"224.141422ms","start":"2026-05-07T23:19:24.163825Z","end":"2026-05-07T23:19:24.387967Z","steps":["trace[1376393960] 'process raft request' (duration: 223.86209ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:24.388547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.529488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-07T23:19:24.388123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.471798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-07T23:19:24.388607Z","caller":"traceutil/trace.go:171","msg":"trace[922563686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:371; }","duration":"223.589819ms","start":"2026-05-07T23:19:24.164997Z","end":"2026-05-07T23:19:24.388587Z","steps":["trace[922563686] 'agreement among raft nodes before linearized reading' (duration: 223.517108ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:24.388625Z","caller":"traceutil/trace.go:171","msg":"trace[963750084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:371; }","duration":"224.006582ms","start":"2026-05-07T23:19:24.164598Z","end":"2026-05-07T23:19:24.388605Z","steps":["trace[963750084] 'agreement among raft nodes before linearized reading' (duration: 223.433058ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:30.906448Z","caller":"traceutil/trace.go:171","msg":"trace[1282185475] linearizableReadLoop","detail":"{readStateIndex:437; appliedIndex:436; }","duration":"426.749238ms","start":"2026-05-07T23:19:30.47966Z","end":"2026-05-07T23:19:30.90641Z","steps":["trace[1282185475] 'read index received' (duration: 426.537218ms)","trace[1282185475] 'applied index is now lower than readState.Index' (duration: 210.46µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:30.906516Z","caller":"traceutil/trace.go:171","msg":"trace[2039266383] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"487.604808ms","start":"2026-05-07T23:19:30.418879Z","end":"2026-05-07T23:19:30.906484Z","steps":["trace[2039266383] 'process raft request' (duration: 487.333154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:30.906682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.98646ms","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-05-07T23:19:30.906753Z","caller":"traceutil/trace.go:171","msg":"trace[127071618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:419; }","duration":"427.129477ms","start":"2026-05-07T23:19:30.479606Z","end":"2026-05-07T23:19:30.906735Z","steps":["trace[127071618] 'agreement among raft nodes before linearized reading' (duration: 426.962879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:30.906811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:30.479579Z","time spent":"427.217052ms","remote":"127.0.0.1:52752","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-05-07T23:19:30.906794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.962039ms","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-05-07T23:19:30.906883Z","caller":"traceutil/trace.go:171","msg":"trace[903970530] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:419; }","duration":"420.124087ms","start":"2026-05-07T23:19:30.48674Z","end":"2026-05-07T23:19:30.906864Z","steps":["trace[903970530] 'agreement among raft nodes before linearized reading' (duration: 419.98529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:30.906926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:30.486707Z","time spent":"420.210822ms","remote":"127.0.0.1:52752","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-05-07T23:19:30.907038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.105541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:30.90706Z","caller":"traceutil/trace.go:171","msg":"trace[2056105913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:419; }","duration":"385.167154ms","start":"2026-05-07T23:19:30.521886Z","end":"2026-05-07T23:19:30.907053Z","steps":["trace[2056105913] 'agreement among raft nodes before linearized reading' (duration: 385.136883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:30.907077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:30.521862Z","time spent":"385.211636ms","remote":"127.0.0.1:52480","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-05-07T23:19:30.907142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:30.418846Z","time spent":"487.829209ms","remote":"127.0.0.1:52752","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-05-07T23:19:31.837799Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099053248080933026,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T23:19:32.177905Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.265790644s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-07T23:19:32.393563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.28536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:19:32.39381Z","caller":"traceutil/trace.go:171","msg":"trace[1727960320] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"1.481787418s","start":"2026-05-07T23:19:30.911999Z","end":"2026-05-07T23:19:32.393787Z","steps":["trace[1727960320] 'process raft request' (duration: 1.481703104s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:32.393887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:30.911969Z","time spent":"1.481882482s","remote":"127.0.0.1:52752","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-05-07T23:19:32.394189Z","caller":"traceutil/trace.go:171","msg":"trace[1923449481] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"1.482156507s","start":"2026-05-07T23:19:30.911981Z","end":"2026-05-07T23:19:32.394138Z","steps":["trace[1923449481] 'process raft request' (duration: 1.266124301s)","trace[1923449481] 'compare' (duration: 215.096171ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:32.394496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:30.911952Z","time spent":"1.482407408s","remote":"127.0.0.1:52752","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-05-07T23:19:32.934438Z","caller":"traceutil/trace.go:171","msg":"trace[576459149] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"1.535005662s","start":"2026-05-07T23:19:31.399411Z","end":"2026-05-07T23:19:32.934417Z","steps":["trace[576459149] 'process raft request' (duration: 1.534787362s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:32.934554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:31.399383Z","time spent":"1.535109047s","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:19:32.934545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.597392467s","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-05-07T23:19:32.934422Z","caller":"traceutil/trace.go:171","msg":"trace[407504729] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:437; }","duration":"1.597257781s","start":"2026-05-07T23:19:31.337134Z","end":"2026-05-07T23:19:32.934392Z","steps":["trace[407504729] 'read index received' (duration: 841.044665ms)","trace[407504729] 'applied index is now lower than readState.Index' (duration: 756.211556ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:32.934596Z","caller":"traceutil/trace.go:171","msg":"trace[1557489390] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:422; }","duration":"1.597487672s","start":"2026-05-07T23:19:31.337093Z","end":"2026-05-07T23:19:32.93458Z","steps":["trace[1557489390] 'agreement among raft nodes before linearized reading' (duration: 1.597383757s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:32.934664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:31.337074Z","time spent":"1.597555965s","remote":"127.0.0.1:52752","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-05-07T23:19:32.934739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.388793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:32.934818Z","caller":"traceutil/trace.go:171","msg":"trace[436517638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:422; }","duration":"429.505698ms","start":"2026-05-07T23:19:32.505294Z","end":"2026-05-07T23:19:32.934799Z","steps":["trace[436517638] 'agreement among raft nodes before linearized reading' (duration: 429.390152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:32.934856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:32.505276Z","time spent":"429.572062ms","remote":"127.0.0.1:52480","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-05-07T23:19:33.347349Z","caller":"traceutil/trace.go:171","msg":"trace[1325953868] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"408.734382ms","start":"2026-05-07T23:19:32.938588Z","end":"2026-05-07T23:19:33.347322Z","steps":["trace[1325953868] 'process raft request' (duration: 372.291679ms)","trace[1325953868] 'compare' (duration: 36.245344ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:33.347505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:32.938546Z","time spent":"408.879528ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:19:33.347526Z","caller":"traceutil/trace.go:171","msg":"trace[718374447] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"407.098684ms","start":"2026-05-07T23:19:32.940406Z","end":"2026-05-07T23:19:33.347505Z","steps":["trace[718374447] 'process raft request' (duration: 406.877774ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:33.347651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:32.940383Z","time spent":"407.192768ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:19:38.771122Z","caller":"traceutil/trace.go:171","msg":"trace[1454435852] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"152.753349ms","start":"2026-05-07T23:19:38.618336Z","end":"2026-05-07T23:19:38.77109Z","steps":["trace[1454435852] 'process raft request' (duration: 74.296378ms)","trace[1454435852] 'compare' (duration: 78.270353ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:38.954916Z","caller":"traceutil/trace.go:171","msg":"trace[140431545] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"122.209755ms","start":"2026-05-07T23:19:38.832674Z","end":"2026-05-07T23:19:38.954884Z","steps":["trace[140431545] 'process raft request' (duration: 122.166903ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:38.95499Z","caller":"traceutil/trace.go:171","msg":"trace[29614752] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"122.347401ms","start":"2026-05-07T23:19:38.832579Z","end":"2026-05-07T23:19:38.954926Z","steps":["trace[29614752] 'process raft request' (duration: 110.898657ms)","trace[29614752] 'compare' (duration: 11.200233ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:39.146128Z","caller":"traceutil/trace.go:171","msg":"trace[2012391425] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"108.014186ms","start":"2026-05-07T23:19:39.038075Z","end":"2026-05-07T23:19:39.146089Z","steps":["trace[2012391425] 'process raft request' (duration: 41.464573ms)","trace[2012391425] 'compare' (duration: 66.089574ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:39.295527Z","caller":"traceutil/trace.go:171","msg":"trace[1037127948] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"103.399405ms","start":"2026-05-07T23:19:39.192072Z","end":"2026-05-07T23:19:39.295471Z","steps":["trace[1037127948] 'process raft request' (duration: 102.882643ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.077331Z","caller":"traceutil/trace.go:171","msg":"trace[1094941376] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"102.10427ms","start":"2026-05-07T23:19:40.975202Z","end":"2026-05-07T23:19:41.077306Z","steps":["trace[1094941376] 'process raft request' (duration: 101.890011ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.241467Z","caller":"traceutil/trace.go:171","msg":"trace[871854355] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"259.629494ms","start":"2026-05-07T23:19:40.981805Z","end":"2026-05-07T23:19:41.241434Z","steps":["trace[871854355] 'process raft request' (duration: 259.332541ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.241643Z","caller":"traceutil/trace.go:171","msg":"trace[1609563773] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"259.750929ms","start":"2026-05-07T23:19:40.981858Z","end":"2026-05-07T23:19:41.241609Z","steps":["trace[1609563773] 'process raft request' (duration: 259.451796ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.24183Z","caller":"traceutil/trace.go:171","msg":"trace[575832868] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"251.688983ms","start":"2026-05-07T23:19:40.990098Z","end":"2026-05-07T23:19:41.241787Z","steps":["trace[575832868] 'process raft request' (duration: 251.361409ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.242029Z","caller":"traceutil/trace.go:171","msg":"trace[1970747889] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"250.193718ms","start":"2026-05-07T23:19:40.99181Z","end":"2026-05-07T23:19:41.242004Z","steps":["trace[1970747889] 'process raft request' (duration: 249.748159ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.24239Z","caller":"traceutil/trace.go:171","msg":"trace[677651864] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"248.897833ms","start":"2026-05-07T23:19:40.993484Z","end":"2026-05-07T23:19:41.242382Z","steps":["trace[677651864] 'process raft request' (duration: 248.769837ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.242495Z","caller":"traceutil/trace.go:171","msg":"trace[1338052933] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"248.244074ms","start":"2026-05-07T23:19:40.994244Z","end":"2026-05-07T23:19:41.242488Z","steps":["trace[1338052933] 'process raft request' (duration: 248.077207ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.242564Z","caller":"traceutil/trace.go:171","msg":"trace[662283583] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"240.729151ms","start":"2026-05-07T23:19:41.00181Z","end":"2026-05-07T23:19:41.242539Z","steps":["trace[662283583] 'process raft request' (duration: 240.594526ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.24264Z","caller":"traceutil/trace.go:171","msg":"trace[1227046358] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"239.336751ms","start":"2026-05-07T23:19:41.003282Z","end":"2026-05-07T23:19:41.242619Z","steps":["trace[1227046358] 'process raft request' (duration: 239.171994ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.242712Z","caller":"traceutil/trace.go:171","msg":"trace[273130941] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"231.128121ms","start":"2026-05-07T23:19:41.011574Z","end":"2026-05-07T23:19:41.242702Z","steps":["trace[273130941] 'process raft request' (duration: 230.927983ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.242875Z","caller":"traceutil/trace.go:171","msg":"trace[1614149791] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"209.324477ms","start":"2026-05-07T23:19:41.033537Z","end":"2026-05-07T23:19:41.242861Z","steps":["trace[1614149791] 'process raft request' (duration: 209.264364ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.259554Z","caller":"traceutil/trace.go:171","msg":"trace[836908461] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"176.651579ms","start":"2026-05-07T23:19:41.08289Z","end":"2026-05-07T23:19:41.259541Z","steps":["trace[836908461] 'process raft request' (duration: 176.385318ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.259542Z","caller":"traceutil/trace.go:171","msg":"trace[704769129] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"174.254227ms","start":"2026-05-07T23:19:41.085265Z","end":"2026-05-07T23:19:41.259519Z","steps":["trace[704769129] 'process raft request' (duration: 174.101551ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.259674Z","caller":"traceutil/trace.go:171","msg":"trace[182776761] transaction","detail":"{read_only:false; number_of_response:1; response_revision:488; }","duration":"174.336431ms","start":"2026-05-07T23:19:41.085325Z","end":"2026-05-07T23:19:41.259662Z","steps":["trace[182776761] 'process raft request' (duration: 174.117432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:41.470821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.281882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:19:41.471077Z","caller":"traceutil/trace.go:171","msg":"trace[359234068] transaction","detail":"{read_only:false; number_of_response:1; response_revision:496; }","duration":"206.348939ms","start":"2026-05-07T23:19:41.264711Z","end":"2026-05-07T23:19:41.47106Z","steps":["trace[359234068] 'process raft request' (duration: 206.300297ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.471134Z","caller":"traceutil/trace.go:171","msg":"trace[13024106] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"209.076985ms","start":"2026-05-07T23:19:41.262022Z","end":"2026-05-07T23:19:41.471099Z","steps":["trace[13024106] 'process raft request' (duration: 94.410718ms)","trace[13024106] 'compare' (duration: 114.161885ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:41.471168Z","caller":"traceutil/trace.go:171","msg":"trace[1871340789] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"206.567429ms","start":"2026-05-07T23:19:41.264578Z","end":"2026-05-07T23:19:41.471146Z","steps":["trace[1871340789] 'process raft request' (duration: 206.34798ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.509999Z","caller":"traceutil/trace.go:171","msg":"trace[1332187837] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"242.355268ms","start":"2026-05-07T23:19:41.267618Z","end":"2026-05-07T23:19:41.509974Z","steps":["trace[1332187837] 'process raft request' (duration: 241.853046ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.510345Z","caller":"traceutil/trace.go:171","msg":"trace[1070565975] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"242.6433ms","start":"2026-05-07T23:19:41.26768Z","end":"2026-05-07T23:19:41.510323Z","steps":["trace[1070565975] 'process raft request' (duration: 242.098246ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.510516Z","caller":"traceutil/trace.go:171","msg":"trace[1107889175] transaction","detail":"{read_only:false; number_of_response:0; response_revision:498; }","duration":"239.393773ms","start":"2026-05-07T23:19:41.271114Z","end":"2026-05-07T23:19:41.510508Z","steps":["trace[1107889175] 'process raft request' (duration: 238.785386ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.510556Z","caller":"traceutil/trace.go:171","msg":"trace[169117884] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"225.1817ms","start":"2026-05-07T23:19:41.285363Z","end":"2026-05-07T23:19:41.510545Z","steps":["trace[169117884] 'process raft request' (duration: 224.699599ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.510856Z","caller":"traceutil/trace.go:171","msg":"trace[2089461114] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"237.576235ms","start":"2026-05-07T23:19:41.273272Z","end":"2026-05-07T23:19:41.510848Z","steps":["trace[2089461114] 'process raft request' (duration: 236.656947ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.510856Z","caller":"traceutil/trace.go:171","msg":"trace[752034755] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"194.289168ms","start":"2026-05-07T23:19:41.316557Z","end":"2026-05-07T23:19:41.510846Z","steps":["trace[752034755] 'process raft request' (duration: 193.655302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:41.520651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.637019ms","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-05-07T23:19:41.520713Z","caller":"traceutil/trace.go:171","msg":"trace[297063828] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:503; }","duration":"103.746294ms","start":"2026-05-07T23:19:41.416949Z","end":"2026-05-07T23:19:41.520696Z","steps":["trace[297063828] 'agreement among raft nodes before linearized reading' (duration: 103.576707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:41.892536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.489981ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:19:41.892744Z","caller":"traceutil/trace.go:171","msg":"trace[2006333066] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"259.672654ms","start":"2026-05-07T23:19:41.633046Z","end":"2026-05-07T23:19:41.892718Z","steps":["trace[2006333066] 'process raft request' (duration: 123.89363ms)","trace[2006333066] 'compare' (duration: 135.326365ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:41.89279Z","caller":"traceutil/trace.go:171","msg":"trace[2074123175] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"248.326751ms","start":"2026-05-07T23:19:41.644438Z","end":"2026-05-07T23:19:41.892765Z","steps":["trace[2074123175] 'process raft request' (duration: 248.227626ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:41.89391Z","caller":"traceutil/trace.go:171","msg":"trace[738639607] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"165.36698ms","start":"2026-05-07T23:19:41.72853Z","end":"2026-05-07T23:19:41.893897Z","steps":["trace[738639607] 'process raft request' (duration: 165.074027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:42.859918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.211125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:19:42.860046Z","caller":"traceutil/trace.go:171","msg":"trace[2028917724] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"748.196831ms","start":"2026-05-07T23:19:42.111818Z","end":"2026-05-07T23:19:42.860015Z","steps":["trace[2028917724] 'process raft request' (duration: 43.797388ms)","trace[2028917724] 'compare' (duration: 703.275206ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:42.860109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:42.111772Z","time spent":"748.307055ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:19:42.861671Z","caller":"traceutil/trace.go:171","msg":"trace[2059773237] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"748.609548ms","start":"2026-05-07T23:19:42.113035Z","end":"2026-05-07T23:19:42.861645Z","steps":["trace[2059773237] 'process raft request' (duration: 748.364757ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:42.861967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:42.113002Z","time spent":"748.867408ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:19:42.886016Z","caller":"traceutil/trace.go:171","msg":"trace[2017945729] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:551; }","duration":"402.023357ms","start":"2026-05-07T23:19:42.48396Z","end":"2026-05-07T23:19:42.885984Z","steps":["trace[2017945729] 'read index received' (duration: 377.457036ms)","trace[2017945729] 'applied index is now lower than readState.Index' (duration: 24.564781ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:42.886356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.110718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:42.886321Z","caller":"traceutil/trace.go:171","msg":"trace[781613360] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"499.163662ms","start":"2026-05-07T23:19:42.386929Z","end":"2026-05-07T23:19:42.886093Z","steps":["trace[781613360] 'process raft request' (duration: 498.547127ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:42.88641Z","caller":"traceutil/trace.go:171","msg":"trace[1765745206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:529; }","duration":"380.213812ms","start":"2026-05-07T23:19:42.506179Z","end":"2026-05-07T23:19:42.886393Z","steps":["trace[1765745206] 'agreement among raft nodes before linearized reading' (duration: 379.992363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:42.886533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.133513ms","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-05-07T23:19:42.886584Z","caller":"traceutil/trace.go:171","msg":"trace[1127958876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:529; }","duration":"372.219046ms","start":"2026-05-07T23:19:42.514355Z","end":"2026-05-07T23:19:42.886574Z","steps":["trace[1127958876] 'agreement among raft nodes before linearized reading' (duration: 372.017998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:42.88661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:42.514345Z","time spent":"372.259248ms","remote":"127.0.0.1:52752","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-05-07T23:19:42.88658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.590751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-05-07T23:19:42.886632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:42.386897Z","time spent":"499.59127ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T23:19:42.886697Z","caller":"traceutil/trace.go:171","msg":"trace[1351240184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"402.759217ms","start":"2026-05-07T23:19:42.483914Z","end":"2026-05-07T23:19:42.886673Z","steps":["trace[1351240184] 'agreement among raft nodes before linearized reading' (duration: 402.253546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:42.88672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:42.506165Z","time spent":"380.540156ms","remote":"127.0.0.1:52480","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-05-07T23:19:42.886747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:42.483891Z","time spent":"402.844001ms","remote":"127.0.0.1:52752","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-05-07T23:19:43.892528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.251179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:19:43.892664Z","caller":"traceutil/trace.go:171","msg":"trace[1616876901] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"864.002977ms","start":"2026-05-07T23:19:43.02864Z","end":"2026-05-07T23:19:43.892643Z","steps":["trace[1616876901] 'process raft request' (duration: 557.530149ms)","trace[1616876901] 'compare' (duration: 306.108673ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:43.89274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:43.028615Z","time spent":"864.08431ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10846,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:19:43.894563Z","caller":"traceutil/trace.go:171","msg":"trace[1906440092] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"814.923612ms","start":"2026-05-07T23:19:43.079598Z","end":"2026-05-07T23:19:43.894521Z","steps":["trace[1906440092] 'process raft request' (duration: 814.507335ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:43.894607Z","caller":"traceutil/trace.go:171","msg":"trace[657020386] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"791.72597ms","start":"2026-05-07T23:19:43.102852Z","end":"2026-05-07T23:19:43.894578Z","steps":["trace[657020386] 'process raft request' (duration: 791.523022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:43.894767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:43.102829Z","time spent":"791.845155ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:19:43.89482Z","caller":"traceutil/trace.go:171","msg":"trace[1908046145] linearizableReadLoop","detail":"{readStateIndex:562; appliedIndex:558; }","duration":"361.99649ms","start":"2026-05-07T23:19:43.532804Z","end":"2026-05-07T23:19:43.8948Z","steps":["trace[1908046145] 'read index received' (duration: 53.367533ms)","trace[1908046145] 'applied index is now lower than readState.Index' (duration: 308.627277ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:43.894873Z","caller":"traceutil/trace.go:171","msg":"trace[418963356] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"721.29125ms","start":"2026-05-07T23:19:43.173544Z","end":"2026-05-07T23:19:43.894835Z","steps":["trace[418963356] 'process raft request' (duration: 720.956986ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:43.894984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.186898ms","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-05-07T23:19:43.895024Z","caller":"traceutil/trace.go:171","msg":"trace[1277672326] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:538; }","duration":"362.258101ms","start":"2026-05-07T23:19:43.532751Z","end":"2026-05-07T23:19:43.895009Z","steps":["trace[1277672326] 'agreement among raft nodes before linearized reading' (duration: 362.172927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:43.895031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:43.173519Z","time spent":"721.435816ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T23:19:43.895059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:43.532729Z","time spent":"362.321944ms","remote":"127.0.0.1:52752","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-05-07T23:19:43.895231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.520036ms","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-05-07T23:19:43.895277Z","caller":"traceutil/trace.go:171","msg":"trace[92400193] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:538; }","duration":"357.586868ms","start":"2026-05-07T23:19:43.537678Z","end":"2026-05-07T23:19:43.895265Z","steps":["trace[92400193] 'agreement among raft nodes before linearized reading' (duration: 357.501565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:43.895309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:43.537667Z","time spent":"357.63536ms","remote":"127.0.0.1:52752","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-05-07T23:19:43.897765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:43.079577Z","time spent":"815.047037ms","remote":"127.0.0.1:52556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:19:44.654116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.064092ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T23:19:44.654291Z","caller":"traceutil/trace.go:171","msg":"trace[129866883] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:578; }","duration":"144.442531ms","start":"2026-05-07T23:19:44.50982Z","end":"2026-05-07T23:19:44.654262Z","steps":["trace[129866883] 'read index received' (duration: 44.16282ms)","trace[129866883] 'applied index is now lower than readState.Index' (duration: 100.277881ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:19:44.654373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.548265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:44.654401Z","caller":"traceutil/trace.go:171","msg":"trace[1394990784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"144.611098ms","start":"2026-05-07T23:19:44.509777Z","end":"2026-05-07T23:19:44.654388Z","steps":["trace[1394990784] 'agreement among raft nodes before linearized reading' (duration: 144.546805ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:44.654474Z","caller":"traceutil/trace.go:171","msg":"trace[1738995475] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"198.415283ms","start":"2026-05-07T23:19:44.45602Z","end":"2026-05-07T23:19:44.654435Z","steps":["trace[1738995475] 'process raft request' (duration: 97.911843ms)","trace[1738995475] 'compare' (duration: 99.856843ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:44.686993Z","caller":"traceutil/trace.go:171","msg":"trace[599203657] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"139.570951ms","start":"2026-05-07T23:19:44.547395Z","end":"2026-05-07T23:19:44.686966Z","steps":["trace[599203657] 'process raft request' (duration: 139.129642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:48.659053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.144674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:48.659204Z","caller":"traceutil/trace.go:171","msg":"trace[1976888468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"153.090722ms","start":"2026-05-07T23:19:48.506086Z","end":"2026-05-07T23:19:48.659176Z","steps":["trace[1976888468] 'range keys from in-memory index tree' (duration: 151.958057ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:48.66399Z","caller":"traceutil/trace.go:171","msg":"trace[1890163246] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"117.977198ms","start":"2026-05-07T23:19:48.545973Z","end":"2026-05-07T23:19:48.66395Z","steps":["trace[1890163246] 'process raft request' (duration: 117.50716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:50.3828Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099053248080933404,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T23:19:50.884612Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099053248080933404,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T23:19:51.072613Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.408551636s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T23:19:51.073692Z","caller":"traceutil/trace.go:171","msg":"trace[1828961576] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:604; }","duration":"1.191366291s","start":"2026-05-07T23:19:49.8823Z","end":"2026-05-07T23:19:51.073666Z","steps":["trace[1828961576] 'read index received' (duration: 1.190463757s)","trace[1828961576] 'applied index is now lower than readState.Index' (duration: 901.743µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:51.073773Z","caller":"traceutil/trace.go:171","msg":"trace[1035206144] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"1.410649305s","start":"2026-05-07T23:19:49.663093Z","end":"2026-05-07T23:19:51.073742Z","steps":["trace[1035206144] 'process raft request' (duration: 1.40996883s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:51.07393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19161564s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qjmhq\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-05-07T23:19:51.073946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093817286s","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-05-07T23:19:51.073946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:49.663076Z","time spent":"1.4107825s","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036165,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:19:51.073991Z","caller":"traceutil/trace.go:171","msg":"trace[1849999965] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"1.09390612s","start":"2026-05-07T23:19:49.980071Z","end":"2026-05-07T23:19:51.073977Z","steps":["trace[1849999965] 'agreement among raft nodes before linearized reading' (duration: 1.093741043s)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:51.073995Z","caller":"traceutil/trace.go:171","msg":"trace[560862743] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qjmhq; range_end:; response_count:1; response_revision:579; }","duration":"1.191760797s","start":"2026-05-07T23:19:49.882212Z","end":"2026-05-07T23:19:51.073973Z","steps":["trace[560862743] 'agreement among raft nodes before linearized reading' (duration: 1.191541398s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:51.074028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:49.980049Z","time spent":"1.093970253s","remote":"127.0.0.1:52752","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-05-07T23:19:51.074033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:49.882188Z","time spent":"1.19183726s","remote":"127.0.0.1:52682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-qjmhq\" "} {"level":"warn","ts":"2026-05-07T23:19:51.07436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.201592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:51.074421Z","caller":"traceutil/trace.go:171","msg":"trace[996176574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"571.291865ms","start":"2026-05-07T23:19:50.503113Z","end":"2026-05-07T23:19:51.074405Z","steps":["trace[996176574] 'agreement among raft nodes before linearized reading' (duration: 571.204712ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:51.07445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:50.503089Z","time spent":"571.354538ms","remote":"127.0.0.1:52488","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-05-07T23:19:51.074552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079281774s","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-05-07T23:19:51.074776Z","caller":"traceutil/trace.go:171","msg":"trace[595657446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:579; }","duration":"1.079538624s","start":"2026-05-07T23:19:49.995223Z","end":"2026-05-07T23:19:51.074762Z","steps":["trace[595657446] 'agreement among raft nodes before linearized reading' (duration: 1.079266723s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:51.074822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:49.995212Z","time spent":"1.079599687s","remote":"127.0.0.1:52752","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-05-07T23:19:51.220873Z","caller":"traceutil/trace.go:171","msg":"trace[1231764429] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"140.648939ms","start":"2026-05-07T23:19:51.080204Z","end":"2026-05-07T23:19:51.220853Z","steps":["trace[1231764429] 'process raft request' (duration: 140.494454ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:51.221264Z","caller":"traceutil/trace.go:171","msg":"trace[87367362] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"140.66553ms","start":"2026-05-07T23:19:51.080551Z","end":"2026-05-07T23:19:51.221217Z","steps":["trace[87367362] 'process raft request' (duration: 140.269275ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:51.221565Z","caller":"traceutil/trace.go:171","msg":"trace[585121248] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"138.397324ms","start":"2026-05-07T23:19:51.083044Z","end":"2026-05-07T23:19:51.221441Z","steps":["trace[585121248] 'process raft request' (duration: 138.028461ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:51.221615Z","caller":"traceutil/trace.go:171","msg":"trace[1288959549] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"129.496218ms","start":"2026-05-07T23:19:51.092068Z","end":"2026-05-07T23:19:51.221564Z","steps":["trace[1288959549] 'process raft request' (duration: 129.145985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:53.432147Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.812101301s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-07T23:19:53.437564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.932255011s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:19:53.437647Z","caller":"traceutil/trace.go:171","msg":"trace[914346467] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"1.932383516s","start":"2026-05-07T23:19:51.505243Z","end":"2026-05-07T23:19:53.437626Z","steps":["trace[914346467] 'range keys from in-memory index tree' (duration: 1.932173638s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:53.437687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:51.505225Z","time spent":"1.932453378s","remote":"127.0.0.1:52480","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-05-07T23:19:53.438129Z","caller":"traceutil/trace.go:171","msg":"trace[1565611008] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"287.522343ms","start":"2026-05-07T23:19:53.150585Z","end":"2026-05-07T23:19:53.438107Z","steps":["trace[1565611008] 'read index received' (duration: 281.699117ms)","trace[1565611008] 'applied index is now lower than readState.Index' (duration: 5.821786ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:19:53.438271Z","caller":"traceutil/trace.go:171","msg":"trace[1188111020] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.819938481s","start":"2026-05-07T23:19:51.618318Z","end":"2026-05-07T23:19:53.438256Z","steps":["trace[1188111020] 'process raft request' (duration: 1.814279362s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:53.438406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:19:51.618283Z","time spent":"1.820031354s","remote":"127.0.0.1:52612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036201,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:19:53.438698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.106936ms","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-05-07T23:19:53.438743Z","caller":"traceutil/trace.go:171","msg":"trace[1204992136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"288.185378ms","start":"2026-05-07T23:19:53.150545Z","end":"2026-05-07T23:19:53.438731Z","steps":["trace[1204992136] 'agreement among raft nodes before linearized reading' (duration: 288.046663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:53.438956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.331299ms","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-05-07T23:19:53.438992Z","caller":"traceutil/trace.go:171","msg":"trace[67413948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"213.401453ms","start":"2026-05-07T23:19:53.225578Z","end":"2026-05-07T23:19:53.43898Z","steps":["trace[67413948] 'agreement among raft nodes before linearized reading' (duration: 213.274148ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:53.439112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.677915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-07T23:19:53.439139Z","caller":"traceutil/trace.go:171","msg":"trace[2061932325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"212.716577ms","start":"2026-05-07T23:19:53.226415Z","end":"2026-05-07T23:19:53.439132Z","steps":["trace[2061932325] 'agreement among raft nodes before linearized reading' (duration: 212.642684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:53.439191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.79233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-05-07T23:19:53.439228Z","caller":"traceutil/trace.go:171","msg":"trace[864587243] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"212.834621ms","start":"2026-05-07T23:19:53.226386Z","end":"2026-05-07T23:19:53.43922Z","steps":["trace[864587243] 'agreement among raft nodes before linearized reading' (duration: 212.745278ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:19:55.355953Z","caller":"traceutil/trace.go:171","msg":"trace[2004186071] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"181.675791ms","start":"2026-05-07T23:19:55.174251Z","end":"2026-05-07T23:19:55.355927Z","steps":["trace[2004186071] 'process raft request' (duration: 181.539426ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:19:58.285794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.917337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-05-07T23:19:58.285949Z","caller":"traceutil/trace.go:171","msg":"trace[661876113] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:673; }","duration":"114.145934ms","start":"2026-05-07T23:19:58.171776Z","end":"2026-05-07T23:19:58.285922Z","steps":["trace[661876113] 'range keys from in-memory index tree' (duration: 113.569615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:09.477402Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8099053248080933748,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T23:20:09.836769Z","caller":"traceutil/trace.go:171","msg":"trace[1124204523] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"927.64381ms","start":"2026-05-07T23:20:08.9091Z","end":"2026-05-07T23:20:09.836744Z","steps":["trace[1124204523] 'process raft request' (duration: 927.491735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:09.83697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:20:08.909085Z","time spent":"927.756404ms","remote":"127.0.0.1:52752","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T23:20:09.84894Z","caller":"traceutil/trace.go:171","msg":"trace[2075292202] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"872.422659ms","start":"2026-05-07T23:20:08.976489Z","end":"2026-05-07T23:20:09.848912Z","steps":["trace[2075292202] 'read index received' (duration: 860.981753ms)","trace[2075292202] 'applied index is now lower than readState.Index' (duration: 11.439326ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:20:09.849101Z","caller":"traceutil/trace.go:171","msg":"trace[793429856] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"833.980943ms","start":"2026-05-07T23:20:09.015112Z","end":"2026-05-07T23:20:09.849093Z","steps":["trace[793429856] 'process raft request' (duration: 833.60828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:09.849264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:20:09.015081Z","time spent":"834.061505ms","remote":"127.0.0.1:52730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T23:20:09.849573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.08828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-07T23:20:09.849616Z","caller":"traceutil/trace.go:171","msg":"trace[1483495728] range","detail":"{range_begin:/registry/masterleases/199.19.213.153; range_end:; response_count:1; response_revision:759; }","duration":"873.183414ms","start":"2026-05-07T23:20:08.976423Z","end":"2026-05-07T23:20:09.849606Z","steps":["trace[1483495728] 'agreement among raft nodes before linearized reading' (duration: 873.018369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:09.849652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:20:08.976394Z","time spent":"873.250236ms","remote":"127.0.0.1:52542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.153\" "} {"level":"warn","ts":"2026-05-07T23:20:09.849797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.787628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-07T23:20:09.849804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.602986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T23:20:09.849891Z","caller":"traceutil/trace.go:171","msg":"trace[347522374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:759; }","duration":"342.741941ms","start":"2026-05-07T23:20:09.507129Z","end":"2026-05-07T23:20:09.849871Z","steps":["trace[347522374] 'agreement among raft nodes before linearized reading' (duration: 342.597716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:09.849913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.745687ms","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-05-07T23:20:09.849935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:20:09.5071Z","time spent":"342.828193ms","remote":"127.0.0.1:52480","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-05-07T23:20:09.849941Z","caller":"traceutil/trace.go:171","msg":"trace[162285624] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:759; }","duration":"260.815059ms","start":"2026-05-07T23:20:09.589119Z","end":"2026-05-07T23:20:09.849934Z","steps":["trace[162285624] 'agreement among raft nodes before linearized reading' (duration: 260.743327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:09.850012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.619788ms","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-05-07T23:20:09.850045Z","caller":"traceutil/trace.go:171","msg":"trace[531577055] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:759; }","duration":"264.710071ms","start":"2026-05-07T23:20:09.585327Z","end":"2026-05-07T23:20:09.850037Z","steps":["trace[531577055] 'agreement among raft nodes before linearized reading' (duration: 264.634029ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:09.849844Z","caller":"traceutil/trace.go:171","msg":"trace[1762403742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:759; }","duration":"260.85011ms","start":"2026-05-07T23:20:09.588984Z","end":"2026-05-07T23:20:09.849834Z","steps":["trace[1762403742] 'agreement among raft nodes before linearized reading' (duration: 260.740396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:09.85019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.744808ms","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-05-07T23:20:09.850234Z","caller":"traceutil/trace.go:171","msg":"trace[849758714] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:759; }","duration":"269.821939ms","start":"2026-05-07T23:20:09.580389Z","end":"2026-05-07T23:20:09.850211Z","steps":["trace[849758714] 'agreement among raft nodes before linearized reading' (duration: 269.689565ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:21.257626Z","caller":"traceutil/trace.go:171","msg":"trace[400899631] transaction","detail":"{read_only:false; response_revision:895; number_of_response:1; }","duration":"143.262986ms","start":"2026-05-07T23:20:21.114338Z","end":"2026-05-07T23:20:21.257601Z","steps":["trace[400899631] 'process raft request' (duration: 143.073161ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:20:38.122603Z","caller":"traceutil/trace.go:171","msg":"trace[1925263791] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"104.290805ms","start":"2026-05-07T23:20:38.018281Z","end":"2026-05-07T23:20:38.122572Z","steps":["trace[1925263791] 'process raft request' (duration: 54.60337ms)","trace[1925263791] 'compare' (duration: 49.322517ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:20:46.202563Z","caller":"traceutil/trace.go:171","msg":"trace[937747632] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1050; }","duration":"107.648861ms","start":"2026-05-07T23:20:46.094886Z","end":"2026-05-07T23:20:46.202535Z","steps":["trace[937747632] 'read index received' (duration: 107.385724ms)","trace[937747632] 'applied index is now lower than readState.Index' (duration: 261.907µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:20:46.202657Z","caller":"traceutil/trace.go:171","msg":"trace[725979983] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"147.959741ms","start":"2026-05-07T23:20:46.054672Z","end":"2026-05-07T23:20:46.202632Z","steps":["trace[725979983] 'process raft request' (duration: 147.677944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:20:46.202892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.974568ms","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-05-07T23:20:46.203Z","caller":"traceutil/trace.go:171","msg":"trace[1905522356] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1010; }","duration":"108.157242ms","start":"2026-05-07T23:20:46.094817Z","end":"2026-05-07T23:20:46.202974Z","steps":["trace[1905522356] 'agreement among raft nodes before linearized reading' (duration: 107.852435ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:12.468495Z","caller":"traceutil/trace.go:171","msg":"trace[606456650] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1142; }","duration":"100.919865ms","start":"2026-05-07T23:21:12.367548Z","end":"2026-05-07T23:21:12.468467Z","steps":["trace[606456650] 'read index received' (duration: 100.770922ms)","trace[606456650] 'applied index is now lower than readState.Index' (duration: 147.473µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:21:12.4686Z","caller":"traceutil/trace.go:171","msg":"trace[575761514] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"105.790154ms","start":"2026-05-07T23:21:12.362765Z","end":"2026-05-07T23:21:12.468555Z","steps":["trace[575761514] 'process raft request' (duration: 105.496809ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:21:12.468764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.188469ms","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-05-07T23:21:12.468851Z","caller":"traceutil/trace.go:171","msg":"trace[837514239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1097; }","duration":"101.313552ms","start":"2026-05-07T23:21:12.367519Z","end":"2026-05-07T23:21:12.468833Z","steps":["trace[837514239] 'agreement among raft nodes before linearized reading' (duration: 101.084618ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:48.778747Z","caller":"traceutil/trace.go:171","msg":"trace[534636108] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"105.141146ms","start":"2026-05-07T23:21:48.673562Z","end":"2026-05-07T23:21:48.778703Z","steps":["trace[534636108] 'process raft request' (duration: 105.076625ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:21:48.778819Z","caller":"traceutil/trace.go:171","msg":"trace[1978693973] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"105.545842ms","start":"2026-05-07T23:21:48.673227Z","end":"2026-05-07T23:21:48.778773Z","steps":["trace[1978693973] 'process raft request' (duration: 105.237898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:11.139885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.136999ms","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-05-07T23:22:11.139985Z","caller":"traceutil/trace.go:171","msg":"trace[1778979410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1324; }","duration":"319.298822ms","start":"2026-05-07T23:22:10.820665Z","end":"2026-05-07T23:22:11.139964Z","steps":["trace[1778979410] 'range keys from in-memory index tree' (duration: 318.936877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:11.140036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:10.820643Z","time spent":"319.382203ms","remote":"127.0.0.1:52752","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-05-07T23:22:11.140493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.95422ms","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-05-07T23:22:11.140598Z","caller":"traceutil/trace.go:171","msg":"trace[1560075756] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1324; }","duration":"384.146432ms","start":"2026-05-07T23:22:10.756428Z","end":"2026-05-07T23:22:11.140574Z","steps":["trace[1560075756] 'range keys from in-memory index tree' (duration: 383.729987ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:11.140647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:10.756399Z","time spent":"384.236344ms","remote":"127.0.0.1:52752","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-05-07T23:22:11.140731Z","caller":"traceutil/trace.go:171","msg":"trace[1227879649] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"259.291467ms","start":"2026-05-07T23:22:10.881401Z","end":"2026-05-07T23:22:11.140692Z","steps":["trace[1227879649] 'read index received' (duration: 257.059936ms)","trace[1227879649] 'applied index is now lower than readState.Index' (duration: 2.229441ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T23:22:11.140858Z","caller":"traceutil/trace.go:171","msg":"trace[28716901] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"276.837868ms","start":"2026-05-07T23:22:10.863993Z","end":"2026-05-07T23:22:11.140831Z","steps":["trace[28716901] 'process raft request' (duration: 274.479265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:11.140957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.353208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T23:22:11.141031Z","caller":"traceutil/trace.go:171","msg":"trace[1575858541] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1325; }","duration":"203.47878ms","start":"2026-05-07T23:22:10.937532Z","end":"2026-05-07T23:22:11.141011Z","steps":["trace[1575858541] 'agreement among raft nodes before linearized reading' (duration: 203.368239ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:11.141067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.645882ms","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-05-07T23:22:11.141118Z","caller":"traceutil/trace.go:171","msg":"trace[1056659425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1325; }","duration":"259.802974ms","start":"2026-05-07T23:22:10.881302Z","end":"2026-05-07T23:22:11.141105Z","steps":["trace[1056659425] 'agreement among raft nodes before linearized reading' (duration: 259.564341ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:11.141117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.195088ms","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-05-07T23:22:11.141214Z","caller":"traceutil/trace.go:171","msg":"trace[1886238558] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1325; }","duration":"233.34241ms","start":"2026-05-07T23:22:10.907848Z","end":"2026-05-07T23:22:11.141191Z","steps":["trace[1886238558] 'agreement among raft nodes before linearized reading' (duration: 233.036925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:11.141659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.9451ms","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-05-07T23:22:11.141785Z","caller":"traceutil/trace.go:171","msg":"trace[2088695824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1324; }","duration":"377.306358ms","start":"2026-05-07T23:22:10.764457Z","end":"2026-05-07T23:22:11.141763Z","steps":["trace[2088695824] 'range keys from in-memory index tree' (duration: 375.723587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:11.141841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T23:22:10.764429Z","time spent":"377.394699ms","remote":"127.0.0.1:52752","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-05-07T23:22:49.523075Z","caller":"traceutil/trace.go:171","msg":"trace[402173779] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"130.842785ms","start":"2026-05-07T23:22:49.392181Z","end":"2026-05-07T23:22:49.523024Z","steps":["trace[402173779] 'process raft request' (duration: 115.521667ms)","trace[402173779] 'compare' (duration: 15.052335ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T23:22:49.523124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.390131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qjmhq\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-05-07T23:22:49.523218Z","caller":"traceutil/trace.go:171","msg":"trace[1445960157] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qjmhq; range_end:; response_count:1; response_revision:1457; }","duration":"109.523833ms","start":"2026-05-07T23:22:49.413671Z","end":"2026-05-07T23:22:49.523195Z","steps":["trace[1445960157] 'agreement among raft nodes before linearized reading' (duration: 109.28864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:49.523293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.286ms","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-05-07T23:22:49.523396Z","caller":"traceutil/trace.go:171","msg":"trace[1059392556] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1457; }","duration":"122.432632ms","start":"2026-05-07T23:22:49.400945Z","end":"2026-05-07T23:22:49.523378Z","steps":["trace[1059392556] 'agreement among raft nodes before linearized reading' (duration: 122.179379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T23:22:49.523653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.380433ms","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-05-07T23:22:49.523796Z","caller":"traceutil/trace.go:171","msg":"trace[142088958] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1457; }","duration":"127.583706ms","start":"2026-05-07T23:22:49.396188Z","end":"2026-05-07T23:22:49.523772Z","steps":["trace[142088958] 'agreement among raft nodes before linearized reading' (duration: 126.876787ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T23:22:49.572745Z","caller":"traceutil/trace.go:171","msg":"trace[647021866] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"142.293907ms","start":"2026-05-07T23:22:49.430425Z","end":"2026-05-07T23:22:49.572719Z","steps":["trace[647021866] 'process raft request' (duration: 142.075085ms)"],"step_count":1}