{"level":"warn","ts":"2026-02-25T01:37:14.184492Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T01:37:14.184609Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169: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.204.45.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-02-25T01:37:14.184679Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-25T01:37:14.184694Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-25T01:37:14.184711Z","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-02-25T01:37:14.185382Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-02-25T01:37:14.185453Z","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.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-02-25T01:37:14.19028Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.60092ms"} {"level":"info","ts":"2026-02-25T01:37:14.19979Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-02-25T01:37:14.199864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T01:37:14.199885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-02-25T01:37:14.199896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T01:37:14.199907Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-02-25T01:37:14.199942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-02-25T01:37:14.214829Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T01:37:14.216347Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T01:37:14.217591Z","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-02-25T01:37:14.221312Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T01:37:14.221434Z","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-02-25T01:37:14.221524Z","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-02-25T01:37:14.221532Z","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-02-25T01:37:14.221638Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T01:37:14.2223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-02-25T01:37:14.222418Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-25T01:37:14.233167Z","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-02-25T01:37:14.233379Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T01:37:14.233403Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T01:37:14.233472Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-25T01:37:14.233486Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-25T01:37:15.200451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T01:37:15.200506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T01:37:15.20053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-02-25T01:37:15.200542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-02-25T01:37:15.200547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-25T01:37:15.200555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-02-25T01:37:15.200563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-25T01:37:15.201631Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:37:15.202247Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T01:37:15.202249Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:37:15.202435Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T01:37:15.202619Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:37:15.202737Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:37:15.202766Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T01:37:15.203055Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T01:37:15.203071Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T01:37:15.205862Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-02-25T01:37:15.205895Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T01:37:18.376705Z","caller":"traceutil/trace.go:171","msg":"trace[265525560] transaction","detail":"{read_only:false; response_revision:99; number_of_response:1; }","duration":"126.247212ms","start":"2026-02-25T01:37:18.250431Z","end":"2026-02-25T01:37:18.376678Z","steps":["trace[265525560] 'process raft request' (duration: 91.250112ms)","trace[265525560] 'compare' (duration: 34.866226ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:18.41731Z","caller":"traceutil/trace.go:171","msg":"trace[741556621] transaction","detail":"{read_only:false; response_revision:100; number_of_response:1; }","duration":"103.675873ms","start":"2026-02-25T01:37:18.313611Z","end":"2026-02-25T01:37:18.417287Z","steps":["trace[741556621] 'process raft request' (duration: 103.537579ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:20.178806Z","caller":"traceutil/trace.go:171","msg":"trace[297464015] transaction","detail":"{read_only:false; response_revision:156; number_of_response:1; }","duration":"123.818184ms","start":"2026-02-25T01:37:20.05496Z","end":"2026-02-25T01:37:20.178778Z","steps":["trace[297464015] 'process raft request' (duration: 123.767272ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:20.178897Z","caller":"traceutil/trace.go:171","msg":"trace[1215401424] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"125.104481ms","start":"2026-02-25T01:37:20.053779Z","end":"2026-02-25T01:37:20.178883Z","steps":["trace[1215401424] 'process raft request' (duration: 124.849743ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:20.178791Z","caller":"traceutil/trace.go:171","msg":"trace[292285860] transaction","detail":"{read_only:false; response_revision:154; number_of_response:1; }","duration":"125.495232ms","start":"2026-02-25T01:37:20.053252Z","end":"2026-02-25T01:37:20.178747Z","steps":["trace[292285860] 'process raft request' (duration: 100.434822ms)","trace[292285860] 'compare' (duration: 24.795202ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:20.955205Z","caller":"traceutil/trace.go:171","msg":"trace[528734306] transaction","detail":"{read_only:false; response_revision:191; number_of_response:1; }","duration":"128.752814ms","start":"2026-02-25T01:37:20.826429Z","end":"2026-02-25T01:37:20.955182Z","steps":["trace[528734306] 'process raft request' (duration: 128.62178ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:20.955222Z","caller":"traceutil/trace.go:171","msg":"trace[319026910] transaction","detail":"{read_only:false; response_revision:190; number_of_response:1; }","duration":"129.865676ms","start":"2026-02-25T01:37:20.825308Z","end":"2026-02-25T01:37:20.955173Z","steps":["trace[319026910] 'process raft request' (duration: 62.561211ms)","trace[319026910] 'compare' (duration: 66.935984ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:21.163606Z","caller":"traceutil/trace.go:171","msg":"trace[607188208] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"119.238165ms","start":"2026-02-25T01:37:21.044332Z","end":"2026-02-25T01:37:21.16357Z","steps":["trace[607188208] 'process raft request' (duration: 119.179303ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:21.163929Z","caller":"traceutil/trace.go:171","msg":"trace[2026228635] transaction","detail":"{read_only:false; response_revision:194; number_of_response:1; }","duration":"119.755209ms","start":"2026-02-25T01:37:21.044144Z","end":"2026-02-25T01:37:21.163899Z","steps":["trace[2026228635] 'process raft request' (duration: 26.898581ms)","trace[2026228635] 'compare' (duration: 92.334283ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:21.559523Z","caller":"traceutil/trace.go:171","msg":"trace[1736158451] linearizableReadLoop","detail":"{readStateIndex:210; appliedIndex:209; }","duration":"187.616189ms","start":"2026-02-25T01:37:21.371815Z","end":"2026-02-25T01:37:21.559431Z","steps":["trace[1736158451] 'read index received' (duration: 133.20388ms)","trace[1736158451] 'applied index is now lower than readState.Index' (duration: 54.411349ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:21.559578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.716632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:replicaset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:21.559678Z","caller":"traceutil/trace.go:171","msg":"trace[1718270691] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:replicaset-controller; range_end:; response_count:0; response_revision:205; }","duration":"187.873917ms","start":"2026-02-25T01:37:21.371783Z","end":"2026-02-25T01:37:21.559657Z","steps":["trace[1718270691] 'agreement among raft nodes before linearized reading' (duration: 187.666121ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:21.559678Z","caller":"traceutil/trace.go:171","msg":"trace[1357461996] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"190.781679ms","start":"2026-02-25T01:37:21.368777Z","end":"2026-02-25T01:37:21.559559Z","steps":["trace[1357461996] 'process raft request' (duration: 136.247056ms)","trace[1357461996] 'compare' (duration: 54.275416ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:21.764562Z","caller":"traceutil/trace.go:171","msg":"trace[1710511055] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"115.658184ms","start":"2026-02-25T01:37:21.648873Z","end":"2026-02-25T01:37:21.764531Z","steps":["trace[1710511055] 'process raft request' (duration: 115.593462ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:21.764654Z","caller":"traceutil/trace.go:171","msg":"trace[229135925] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"116.840786ms","start":"2026-02-25T01:37:21.647779Z","end":"2026-02-25T01:37:21.764619Z","steps":["trace[229135925] 'process raft request' (duration: 21.028915ms)","trace[229135925] 'compare' (duration: 95.514553ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:21.970034Z","caller":"traceutil/trace.go:171","msg":"trace[1379583485] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"118.131203ms","start":"2026-02-25T01:37:21.851885Z","end":"2026-02-25T01:37:21.970016Z","steps":["trace[1379583485] 'process raft request' (duration: 71.260516ms)","trace[1379583485] 'compare' (duration: 46.671111ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:21.970089Z","caller":"traceutil/trace.go:171","msg":"trace[1952984872] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"116.333292ms","start":"2026-02-25T01:37:21.853724Z","end":"2026-02-25T01:37:21.970057Z","steps":["trace[1952984872] 'process raft request' (duration: 116.27153ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:22.189878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.096933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:37:22.189986Z","caller":"traceutil/trace.go:171","msg":"trace[1624492187] linearizableReadLoop","detail":"{readStateIndex:224; appliedIndex:223; }","duration":"137.340677ms","start":"2026-02-25T01:37:22.052619Z","end":"2026-02-25T01:37:22.18996Z","steps":["trace[1624492187] 'read index received' (duration: 19.630256ms)","trace[1624492187] 'applied index is now lower than readState.Index' (duration: 117.709201ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:22.190117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.499211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:22.190154Z","caller":"traceutil/trace.go:171","msg":"trace[660979096] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:statefulset-controller; range_end:; response_count:0; response_revision:219; }","duration":"137.560393ms","start":"2026-02-25T01:37:22.052579Z","end":"2026-02-25T01:37:22.190139Z","steps":["trace[660979096] 'agreement among raft nodes before linearized reading' (duration: 137.483211ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:22.190151Z","caller":"traceutil/trace.go:171","msg":"trace[963137208] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"201.358028ms","start":"2026-02-25T01:37:21.988678Z","end":"2026-02-25T01:37:22.190036Z","steps":["trace[963137208] 'process raft request' (duration: 83.645407ms)","trace[963137208] 'compare' (duration: 116.939979ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:22.576929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.121018ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:37:22.577247Z","caller":"traceutil/trace.go:171","msg":"trace[1739798432] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"147.613517ms","start":"2026-02-25T01:37:22.429604Z","end":"2026-02-25T01:37:22.577218Z","steps":["trace[1739798432] 'compare' (duration: 143.989835ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:22.904193Z","caller":"traceutil/trace.go:171","msg":"trace[1223168551] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"110.396804ms","start":"2026-02-25T01:37:22.793762Z","end":"2026-02-25T01:37:22.904159Z","steps":["trace[1223168551] 'process raft request' (duration: 73.764398ms)","trace[1223168551] 'compare' (duration: 36.502093ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:23.140735Z","caller":"traceutil/trace.go:171","msg":"trace[884881038] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"154.473761ms","start":"2026-02-25T01:37:22.986234Z","end":"2026-02-25T01:37:23.140707Z","steps":["trace[884881038] 'process raft request' (duration: 104.578019ms)","trace[884881038] 'compare' (duration: 49.747128ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:23.642681Z","caller":"traceutil/trace.go:171","msg":"trace[904217608] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"104.287791ms","start":"2026-02-25T01:37:23.538359Z","end":"2026-02-25T01:37:23.642647Z","steps":["trace[904217608] 'process raft request' (duration: 69.157198ms)","trace[904217608] 'compare' (duration: 34.921428ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:24.661807Z","caller":"traceutil/trace.go:171","msg":"trace[1658734991] transaction","detail":"{read_only:false; response_revision:277; number_of_response:1; }","duration":"124.662037ms","start":"2026-02-25T01:37:24.537115Z","end":"2026-02-25T01:37:24.661777Z","steps":["trace[1658734991] 'process raft request' (duration: 61.414037ms)","trace[1658734991] 'compare' (duration: 62.834059ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:25.2835Z","caller":"traceutil/trace.go:171","msg":"trace[692691038] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"108.862651ms","start":"2026-02-25T01:37:25.17461Z","end":"2026-02-25T01:37:25.283472Z","steps":["trace[692691038] 'process raft request' (duration: 95.570025ms)","trace[692691038] 'compare' (duration: 12.183765ms)"],"step_count":2} 2026/02/25 01:37:25 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" 2026/02/25 01:37:25 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-25T01:37:26.11772Z","caller":"traceutil/trace.go:171","msg":"trace[1792893438] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:301; }","duration":"331.561232ms","start":"2026-02-25T01:37:25.786132Z","end":"2026-02-25T01:37:26.117693Z","steps":["trace[1792893438] 'read index received' (duration: 281.587148ms)","trace[1792893438] 'applied index is now lower than readState.Index' (duration: 49.973184ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:26.117748Z","caller":"traceutil/trace.go:171","msg":"trace[744404938] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"148.728879ms","start":"2026-02-25T01:37:25.968982Z","end":"2026-02-25T01:37:26.117711Z","steps":["trace[744404938] 'process raft request' (duration: 148.657227ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:26.117895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.740208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:26.117983Z","caller":"traceutil/trace.go:171","msg":"trace[314419902] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:298; }","duration":"331.860711ms","start":"2026-02-25T01:37:25.786104Z","end":"2026-02-25T01:37:26.117965Z","steps":["trace[314419902] 'agreement among raft nodes before linearized reading' (duration: 331.731387ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:26.118034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:25.786092Z","time spent":"331.933964ms","remote":"127.0.0.1:40494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-02-25T01:37:26.121915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.960358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/\" range_end:\"/registry/deployments/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:26.121953Z","caller":"traceutil/trace.go:171","msg":"trace[950561031] range","detail":"{range_begin:/registry/deployments/kube-system/; range_end:/registry/deployments/kube-system0; response_count:0; response_revision:300; }","duration":"230.0427ms","start":"2026-02-25T01:37:25.891897Z","end":"2026-02-25T01:37:26.12194Z","steps":["trace[950561031] 'agreement among raft nodes before linearized reading' (duration: 229.966648ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:26.122059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.318109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-25T01:37:26.122079Z","caller":"traceutil/trace.go:171","msg":"trace[1385703809] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:300; }","duration":"153.386831ms","start":"2026-02-25T01:37:25.968687Z","end":"2026-02-25T01:37:26.122074Z","steps":["trace[1385703809] 'agreement among raft nodes before linearized reading' (duration: 153.340579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:26.121978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.328726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:26.122246Z","caller":"traceutil/trace.go:171","msg":"trace[1857384554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:300; }","duration":"290.641205ms","start":"2026-02-25T01:37:25.831561Z","end":"2026-02-25T01:37:26.122203Z","steps":["trace[1857384554] 'agreement among raft nodes before linearized reading' (duration: 290.354967ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:26.122245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.545365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:26.122316Z","caller":"traceutil/trace.go:171","msg":"trace[1211885643] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:300; }","duration":"153.662959ms","start":"2026-02-25T01:37:25.968631Z","end":"2026-02-25T01:37:26.122294Z","steps":["trace[1211885643] 'agreement among raft nodes before linearized reading' (duration: 153.554566ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:26.307306Z","caller":"traceutil/trace.go:171","msg":"trace[2062459681] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:314; }","duration":"174.153099ms","start":"2026-02-25T01:37:26.133127Z","end":"2026-02-25T01:37:26.30728Z","steps":["trace[2062459681] 'read index received' (duration: 165.304058ms)","trace[2062459681] 'applied index is now lower than readState.Index' (duration: 8.847811ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:26.307332Z","caller":"traceutil/trace.go:171","msg":"trace[323137719] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"175.057984ms","start":"2026-02-25T01:37:26.132235Z","end":"2026-02-25T01:37:26.307293Z","steps":["trace[323137719] 'process raft request' (duration: 166.178513ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:26.307475Z","caller":"traceutil/trace.go:171","msg":"trace[1737141276] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"173.516321ms","start":"2026-02-25T01:37:26.133941Z","end":"2026-02-25T01:37:26.307457Z","steps":["trace[1737141276] 'process raft request' (duration: 173.412698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:26.3075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.343544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:26.307535Z","caller":"traceutil/trace.go:171","msg":"trace[988943752] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:306; }","duration":"174.412986ms","start":"2026-02-25T01:37:26.133112Z","end":"2026-02-25T01:37:26.307525Z","steps":["trace[988943752] 'agreement among raft nodes before linearized reading' (duration: 174.297002ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:26.307904Z","caller":"traceutil/trace.go:171","msg":"trace[740733122] transaction","detail":"{read_only:false; response_revision:307; number_of_response:1; }","duration":"173.828909ms","start":"2026-02-25T01:37:26.134034Z","end":"2026-02-25T01:37:26.307863Z","steps":["trace[740733122] 'process raft request' (duration: 173.394147ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:26.30797Z","caller":"traceutil/trace.go:171","msg":"trace[678534412] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"173.454859ms","start":"2026-02-25T01:37:26.134501Z","end":"2026-02-25T01:37:26.307956Z","steps":["trace[678534412] 'process raft request' (duration: 173.320325ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:26.30804Z","caller":"traceutil/trace.go:171","msg":"trace[523961697] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"157.076795ms","start":"2026-02-25T01:37:26.15093Z","end":"2026-02-25T01:37:26.308007Z","steps":["trace[523961697] 'process raft request' (duration: 156.961782ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:26.308137Z","caller":"traceutil/trace.go:171","msg":"trace[1202441197] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"173.828429ms","start":"2026-02-25T01:37:26.134293Z","end":"2026-02-25T01:37:26.308122Z","steps":["trace[1202441197] 'process raft request' (duration: 173.447779ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:26.674214Z","caller":"traceutil/trace.go:171","msg":"trace[1950833831] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"115.013455ms","start":"2026-02-25T01:37:26.559177Z","end":"2026-02-25T01:37:26.674191Z","steps":["trace[1950833831] 'process raft request' (duration: 114.967293ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:26.674268Z","caller":"traceutil/trace.go:171","msg":"trace[905443020] transaction","detail":"{read_only:false; response_revision:322; number_of_response:1; }","duration":"116.340992ms","start":"2026-02-25T01:37:26.557895Z","end":"2026-02-25T01:37:26.674236Z","steps":["trace[905443020] 'process raft request' (duration: 47.546675ms)","trace[905443020] 'compare' (duration: 68.54813ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:26.907654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.388655ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:37:26.908369Z","caller":"traceutil/trace.go:171","msg":"trace[209734667] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"150.306214ms","start":"2026-02-25T01:37:26.758027Z","end":"2026-02-25T01:37:26.908333Z","steps":["trace[209734667] 'process raft request' (duration: 16.109446ms)","trace[209734667] 'compare' (duration: 133.252621ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:26.908353Z","caller":"traceutil/trace.go:171","msg":"trace[1926942495] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:338; }","duration":"122.94742ms","start":"2026-02-25T01:37:26.785352Z","end":"2026-02-25T01:37:26.908299Z","steps":["trace[1926942495] 'read index received' (duration: 41.542µs)","trace[1926942495] 'applied index is now lower than readState.Index' (duration: 122.899818ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:26.908489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.128335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-02-25T01:37:26.908569Z","caller":"traceutil/trace.go:171","msg":"trace[268933692] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:330; }","duration":"123.247578ms","start":"2026-02-25T01:37:26.785304Z","end":"2026-02-25T01:37:26.908552Z","steps":["trace[268933692] 'agreement among raft nodes before linearized reading' (duration: 123.108004ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:27.287061Z","caller":"traceutil/trace.go:171","msg":"trace[1638448208] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:347; }","duration":"151.815217ms","start":"2026-02-25T01:37:27.135217Z","end":"2026-02-25T01:37:27.287032Z","steps":["trace[1638448208] 'read index received' (duration: 72.097321ms)","trace[1638448208] 'applied index is now lower than readState.Index' (duration: 79.716606ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:27.287264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.019702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:27.287304Z","caller":"traceutil/trace.go:171","msg":"trace[499522719] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:0; response_revision:336; }","duration":"152.116835ms","start":"2026-02-25T01:37:27.135176Z","end":"2026-02-25T01:37:27.287293Z","steps":["trace[499522719] 'agreement among raft nodes before linearized reading' (duration: 151.966651ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:27.28745Z","caller":"traceutil/trace.go:171","msg":"trace[470747243] transaction","detail":"{read_only:false; response_revision:336; number_of_response:1; }","duration":"191.612612ms","start":"2026-02-25T01:37:27.095794Z","end":"2026-02-25T01:37:27.287407Z","steps":["trace[470747243] 'process raft request' (duration: 111.481325ms)","trace[470747243] 'compare' (duration: 79.630963ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:27.776007Z","caller":"traceutil/trace.go:171","msg":"trace[1682237304] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"129.262548ms","start":"2026-02-25T01:37:27.646712Z","end":"2026-02-25T01:37:27.775974Z","steps":["trace[1682237304] 'process raft request' (duration: 101.194654ms)","trace[1682237304] 'compare' (duration: 27.827387ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:28.505053Z","caller":"traceutil/trace.go:171","msg":"trace[1801699137] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:362; }","duration":"119.499521ms","start":"2026-02-25T01:37:28.385529Z","end":"2026-02-25T01:37:28.505029Z","steps":["trace[1801699137] 'read index received' (duration: 14.725597ms)","trace[1801699137] 'applied index is now lower than readState.Index' (duration: 104.772634ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:28.505319Z","caller":"traceutil/trace.go:171","msg":"trace[2013106308] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"154.096471ms","start":"2026-02-25T01:37:28.351181Z","end":"2026-02-25T01:37:28.505277Z","steps":["trace[2013106308] 'process raft request' (duration: 152.350251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:28.5054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.835721ms","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-02-25T01:37:28.505526Z","caller":"traceutil/trace.go:171","msg":"trace[1175821221] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:352; }","duration":"120.018416ms","start":"2026-02-25T01:37:28.38548Z","end":"2026-02-25T01:37:28.505498Z","steps":["trace[1175821221] 'agreement among raft nodes before linearized reading' (duration: 119.695517ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:28.697685Z","caller":"traceutil/trace.go:171","msg":"trace[332250170] transaction","detail":"{read_only:false; response_revision:354; number_of_response:1; }","duration":"186.58194ms","start":"2026-02-25T01:37:28.511072Z","end":"2026-02-25T01:37:28.697654Z","steps":["trace[332250170] 'process raft request' (duration: 186.020664ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:28.69773Z","caller":"traceutil/trace.go:171","msg":"trace[1051371301] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"184.09892ms","start":"2026-02-25T01:37:28.513606Z","end":"2026-02-25T01:37:28.697705Z","steps":["trace[1051371301] 'process raft request' (duration: 184.019498ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:28.698071Z","caller":"traceutil/trace.go:171","msg":"trace[1302593032] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"182.862855ms","start":"2026-02-25T01:37:28.515195Z","end":"2026-02-25T01:37:28.698058Z","steps":["trace[1302593032] 'process raft request' (duration: 182.708961ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:28.698174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.660723ms","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-02-25T01:37:28.698165Z","caller":"traceutil/trace.go:171","msg":"trace[1854903199] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"150.782807ms","start":"2026-02-25T01:37:28.547273Z","end":"2026-02-25T01:37:28.698055Z","steps":["trace[1854903199] 'process raft request' (duration: 150.740446ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:28.698211Z","caller":"traceutil/trace.go:171","msg":"trace[1518323222] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:359; }","duration":"162.760006ms","start":"2026-02-25T01:37:28.535441Z","end":"2026-02-25T01:37:28.698201Z","steps":["trace[1518323222] 'agreement among raft nodes before linearized reading' (duration: 162.653483ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:28.698326Z","caller":"traceutil/trace.go:171","msg":"trace[925167549] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"184.587914ms","start":"2026-02-25T01:37:28.513705Z","end":"2026-02-25T01:37:28.698293Z","steps":["trace[925167549] 'process raft request' (duration: 183.958026ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:28.698073Z","caller":"traceutil/trace.go:171","msg":"trace[1702696791] linearizableReadLoop","detail":"{readStateIndex:370; appliedIndex:364; }","duration":"162.55235ms","start":"2026-02-25T01:37:28.535508Z","end":"2026-02-25T01:37:28.69806Z","steps":["trace[1702696791] 'read index received' (duration: 7.205614ms)","trace[1702696791] 'applied index is now lower than readState.Index' (duration: 155.345636ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:28.697807Z","caller":"traceutil/trace.go:171","msg":"trace[574618205] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"184.194472ms","start":"2026-02-25T01:37:28.51355Z","end":"2026-02-25T01:37:28.697744Z","steps":["trace[574618205] 'process raft request' (duration: 184.004607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:28.698583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.313438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T01:37:28.699063Z","caller":"traceutil/trace.go:171","msg":"trace[1226575918] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:359; }","duration":"112.812392ms","start":"2026-02-25T01:37:28.586201Z","end":"2026-02-25T01:37:28.699014Z","steps":["trace[1226575918] 'agreement among raft nodes before linearized reading' (duration: 112.314588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:28.906708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.011177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-5l4zt\" ","response":"range_response_count:1 size:3416"} {"level":"info","ts":"2026-02-25T01:37:28.906795Z","caller":"traceutil/trace.go:171","msg":"trace[1905066765] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-5l4zt; range_end:; response_count:1; response_revision:370; }","duration":"143.150531ms","start":"2026-02-25T01:37:28.763625Z","end":"2026-02-25T01:37:28.906775Z","steps":["trace[1905066765] 'agreement among raft nodes before linearized reading' (duration: 34.176317ms)","trace[1905066765] 'range keys from in-memory index tree' (duration: 108.808769ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:28.906855Z","caller":"traceutil/trace.go:171","msg":"trace[1703076344] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"142.06537ms","start":"2026-02-25T01:37:28.76476Z","end":"2026-02-25T01:37:28.906825Z","steps":["trace[1703076344] 'process raft request' (duration: 104.173508ms)","trace[1703076344] 'compare' (duration: 37.649705ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:28.907007Z","caller":"traceutil/trace.go:171","msg":"trace[816892490] linearizableReadLoop","detail":"{readStateIndex:388; appliedIndex:382; }","duration":"109.293953ms","start":"2026-02-25T01:37:28.797697Z","end":"2026-02-25T01:37:28.906991Z","steps":["trace[816892490] 'read index received' (duration: 71.182504ms)","trace[816892490] 'applied index is now lower than readState.Index' (duration: 38.110269ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:28.907096Z","caller":"traceutil/trace.go:171","msg":"trace[1865489842] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"140.393823ms","start":"2026-02-25T01:37:28.766688Z","end":"2026-02-25T01:37:28.907083Z","steps":["trace[1865489842] 'process raft request' (duration: 140.143976ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:28.907152Z","caller":"traceutil/trace.go:171","msg":"trace[1893816536] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"139.427606ms","start":"2026-02-25T01:37:28.767714Z","end":"2026-02-25T01:37:28.907142Z","steps":["trace[1893816536] 'process raft request' (duration: 139.150168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:28.90727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.425219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-02-25T01:37:28.907318Z","caller":"traceutil/trace.go:171","msg":"trace[1104238065] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:376; }","duration":"143.501141ms","start":"2026-02-25T01:37:28.763805Z","end":"2026-02-25T01:37:28.907306Z","steps":["trace[1104238065] 'agreement among raft nodes before linearized reading' (duration: 143.404888ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:28.907316Z","caller":"traceutil/trace.go:171","msg":"trace[2087431361] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"137.002557ms","start":"2026-02-25T01:37:28.770301Z","end":"2026-02-25T01:37:28.907304Z","steps":["trace[2087431361] 'process raft request' (duration: 136.607155ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:28.907069Z","caller":"traceutil/trace.go:171","msg":"trace[361601237] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"141.079422ms","start":"2026-02-25T01:37:28.765974Z","end":"2026-02-25T01:37:28.907053Z","steps":["trace[361601237] 'process raft request' (duration: 140.811655ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:28.907523Z","caller":"traceutil/trace.go:171","msg":"trace[362726878] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"142.06229ms","start":"2026-02-25T01:37:28.764931Z","end":"2026-02-25T01:37:28.906993Z","steps":["trace[362726878] 'process raft request' (duration: 141.804802ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:29.099528Z","caller":"traceutil/trace.go:171","msg":"trace[558221283] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"142.74558ms","start":"2026-02-25T01:37:28.956765Z","end":"2026-02-25T01:37:29.099511Z","steps":["trace[558221283] 'process raft request' (duration: 142.705999ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:29.099558Z","caller":"traceutil/trace.go:171","msg":"trace[290072322] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"179.560571ms","start":"2026-02-25T01:37:28.919961Z","end":"2026-02-25T01:37:29.099522Z","steps":["trace[290072322] 'process raft request' (duration: 179.400087ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:29.099787Z","caller":"traceutil/trace.go:171","msg":"trace[613298738] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"179.066347ms","start":"2026-02-25T01:37:28.92071Z","end":"2026-02-25T01:37:29.099776Z","steps":["trace[613298738] 'process raft request' (duration: 178.705327ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:29.099517Z","caller":"traceutil/trace.go:171","msg":"trace[336605243] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"182.087133ms","start":"2026-02-25T01:37:28.9174Z","end":"2026-02-25T01:37:29.099487Z","steps":["trace[336605243] 'process raft request' (duration: 166.282086ms)","trace[336605243] 'compare' (duration: 15.578441ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:29.271761Z","caller":"traceutil/trace.go:171","msg":"trace[1692392676] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"151.95462ms","start":"2026-02-25T01:37:29.119784Z","end":"2026-02-25T01:37:29.271739Z","steps":["trace[1692392676] 'process raft request' (duration: 151.906968ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:29.271803Z","caller":"traceutil/trace.go:171","msg":"trace[2102816786] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"165.760581ms","start":"2026-02-25T01:37:29.106008Z","end":"2026-02-25T01:37:29.271768Z","steps":["trace[2102816786] 'process raft request' (duration: 164.951658ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:29.271927Z","caller":"traceutil/trace.go:171","msg":"trace[440660676] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"163.411365ms","start":"2026-02-25T01:37:29.108436Z","end":"2026-02-25T01:37:29.271847Z","steps":["trace[440660676] 'process raft request' (duration: 163.206289ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:29.452411Z","caller":"traceutil/trace.go:171","msg":"trace[283373680] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"173.590681ms","start":"2026-02-25T01:37:29.27862Z","end":"2026-02-25T01:37:29.452211Z","steps":["trace[283373680] 'process raft request' (duration: 95.491881ms)","trace[283373680] 'compare' (duration: 77.806272ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:29.452449Z","caller":"traceutil/trace.go:171","msg":"trace[845756137] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"164.687429ms","start":"2026-02-25T01:37:29.287734Z","end":"2026-02-25T01:37:29.452422Z","steps":["trace[845756137] 'process raft request' (duration: 164.571616ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:29.452419Z","caller":"traceutil/trace.go:171","msg":"trace[47655737] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"166.092399ms","start":"2026-02-25T01:37:29.286276Z","end":"2026-02-25T01:37:29.452368Z","steps":["trace[47655737] 'process raft request' (duration: 165.929535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:29.681199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.635219ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:37:29.681371Z","caller":"traceutil/trace.go:171","msg":"trace[1718907606] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"148.675268ms","start":"2026-02-25T01:37:29.532678Z","end":"2026-02-25T01:37:29.681354Z","steps":["trace[1718907606] 'process raft request' (duration: 148.623356ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:29.681495Z","caller":"traceutil/trace.go:171","msg":"trace[1879604564] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"178.375578ms","start":"2026-02-25T01:37:29.503084Z","end":"2026-02-25T01:37:29.681459Z","steps":["trace[1879604564] 'process raft request' (duration: 66.234004ms)","trace[1879604564] 'compare' (duration: 111.503625ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:30.046739Z","caller":"traceutil/trace.go:171","msg":"trace[1436880843] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"104.635102ms","start":"2026-02-25T01:37:29.942076Z","end":"2026-02-25T01:37:30.046711Z","steps":["trace[1436880843] 'process raft request' (duration: 104.461907ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:30.205661Z","caller":"traceutil/trace.go:171","msg":"trace[1677723854] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"125.008037ms","start":"2026-02-25T01:37:30.080601Z","end":"2026-02-25T01:37:30.205609Z","steps":["trace[1677723854] 'process raft request' (duration: 100.02994ms)","trace[1677723854] 'compare' (duration: 24.781262ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:30.443982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.670368ms","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-02-25T01:37:30.444087Z","caller":"traceutil/trace.go:171","msg":"trace[1393229217] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:397; }","duration":"100.852434ms","start":"2026-02-25T01:37:30.343212Z","end":"2026-02-25T01:37:30.444064Z","steps":["trace[1393229217] 'range keys from in-memory index tree' (duration: 100.552036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:31.247637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.700317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:37:31.247774Z","caller":"traceutil/trace.go:171","msg":"trace[1946168958] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"224.994297ms","start":"2026-02-25T01:37:31.02276Z","end":"2026-02-25T01:37:31.247754Z","steps":["trace[1946168958] 'process raft request' (duration: 40.087414ms)","trace[1946168958] 'compare' (duration: 184.604474ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:32.813205Z","caller":"traceutil/trace.go:171","msg":"trace[218181379] linearizableReadLoop","detail":"{readStateIndex:417; appliedIndex:416; }","duration":"272.065879ms","start":"2026-02-25T01:37:32.541104Z","end":"2026-02-25T01:37:32.81317Z","steps":["trace[218181379] 'read index received' (duration: 271.832143ms)","trace[218181379] 'applied index is now lower than readState.Index' (duration: 232.066µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:32.813254Z","caller":"traceutil/trace.go:171","msg":"trace[1924593567] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"328.886577ms","start":"2026-02-25T01:37:32.484322Z","end":"2026-02-25T01:37:32.813209Z","steps":["trace[1924593567] 'process raft request' (duration: 328.61968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:32.813396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.269295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T01:37:32.813455Z","caller":"traceutil/trace.go:171","msg":"trace[1881598538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:404; }","duration":"272.359718ms","start":"2026-02-25T01:37:32.541076Z","end":"2026-02-25T01:37:32.813436Z","steps":["trace[1881598538] 'agreement among raft nodes before linearized reading' (duration: 272.238255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:32.813456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:32.484312Z","time spent":"329.048902ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:37:33.355123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.816714ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:37:33.355323Z","caller":"traceutil/trace.go:171","msg":"trace[344549537] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"536.940624ms","start":"2026-02-25T01:37:32.818338Z","end":"2026-02-25T01:37:33.355279Z","steps":["trace[344549537] 'process raft request' (duration: 218.888293ms)","trace[344549537] 'compare' (duration: 317.604188ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:33.355237Z","caller":"traceutil/trace.go:171","msg":"trace[1537773719] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:417; }","duration":"497.593551ms","start":"2026-02-25T01:37:32.857627Z","end":"2026-02-25T01:37:33.35522Z","steps":["trace[1537773719] 'read index received' (duration: 179.603192ms)","trace[1537773719] 'applied index is now lower than readState.Index' (duration: 317.988839ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:33.355472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.834207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:37:33.355566Z","caller":"traceutil/trace.go:171","msg":"trace[746341717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"497.997162ms","start":"2026-02-25T01:37:32.857548Z","end":"2026-02-25T01:37:33.355545Z","steps":["trace[746341717] 'agreement among raft nodes before linearized reading' (duration: 497.836748ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:33.355624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:32.857509Z","time spent":"498.106475ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:37:33.355582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:32.818315Z","time spent":"537.097789ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:37:33.355735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.983763ms","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-02-25T01:37:33.355782Z","caller":"traceutil/trace.go:171","msg":"trace[1312925173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:405; }","duration":"392.239591ms","start":"2026-02-25T01:37:32.963529Z","end":"2026-02-25T01:37:33.355769Z","steps":["trace[1312925173] 'agreement among raft nodes before linearized reading' (duration: 391.994394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:33.355892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:32.9635Z","time spent":"392.380135ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T01:37:33.515231Z","caller":"traceutil/trace.go:171","msg":"trace[37973037] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"152.070744ms","start":"2026-02-25T01:37:33.36313Z","end":"2026-02-25T01:37:33.515201Z","steps":["trace[37973037] 'process raft request' (duration: 128.356202ms)","trace[37973037] 'compare' (duration: 23.379592ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:33.515646Z","caller":"traceutil/trace.go:171","msg":"trace[1691803047] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:418; }","duration":"112.054751ms","start":"2026-02-25T01:37:33.403578Z","end":"2026-02-25T01:37:33.515633Z","steps":["trace[1691803047] 'read index received' (duration: 87.919148ms)","trace[1691803047] 'applied index is now lower than readState.Index' (duration: 24.134483ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:33.515826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.231756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T01:37:33.515869Z","caller":"traceutil/trace.go:171","msg":"trace[1512610233] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:407; }","duration":"112.36325ms","start":"2026-02-25T01:37:33.403495Z","end":"2026-02-25T01:37:33.515858Z","steps":["trace[1512610233] 'agreement among raft nodes before linearized reading' (duration: 112.190405ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:33.5159Z","caller":"traceutil/trace.go:171","msg":"trace[1663010010] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"152.576108ms","start":"2026-02-25T01:37:33.363297Z","end":"2026-02-25T01:37:33.515873Z","steps":["trace[1663010010] 'process raft request' (duration: 151.843877ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:35.32046Z","caller":"traceutil/trace.go:171","msg":"trace[1559282142] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"207.717398ms","start":"2026-02-25T01:37:35.112717Z","end":"2026-02-25T01:37:35.320434Z","steps":["trace[1559282142] 'process raft request' (duration: 207.555353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:35.814614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.687194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:35.814758Z","caller":"traceutil/trace.go:171","msg":"trace[1711119152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"150.890929ms","start":"2026-02-25T01:37:35.66384Z","end":"2026-02-25T01:37:35.814731Z","steps":["trace[1711119152] 'range keys from in-memory index tree' (duration: 150.605231ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:36.235557Z","caller":"traceutil/trace.go:171","msg":"trace[62516873] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"107.899424ms","start":"2026-02-25T01:37:36.127634Z","end":"2026-02-25T01:37:36.235533Z","steps":["trace[62516873] 'process raft request' (duration: 24.114603ms)","trace[62516873] 'compare' (duration: 83.656947ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:39.346726Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826906228120502,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:37:40.325293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.099521ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T01:37:40.32546Z","caller":"traceutil/trace.go:171","msg":"trace[610560244] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"1.478775596s","start":"2026-02-25T01:37:38.846632Z","end":"2026-02-25T01:37:40.325407Z","steps":["trace[610560244] 'read index received' (duration: 573.228282ms)","trace[610560244] 'applied index is now lower than readState.Index' (duration: 905.545184ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:40.325661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.478998193s","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-02-25T01:37:40.325733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.961717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-25T01:37:40.325732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.804419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:37:40.325749Z","caller":"traceutil/trace.go:171","msg":"trace[2143472560] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:424; }","duration":"1.479136126s","start":"2026-02-25T01:37:38.846591Z","end":"2026-02-25T01:37:40.325727Z","steps":["trace[2143472560] 'agreement among raft nodes before linearized reading' (duration: 1.47891795s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:40.32577Z","caller":"traceutil/trace.go:171","msg":"trace[1407789223] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"823.040949ms","start":"2026-02-25T01:37:39.502717Z","end":"2026-02-25T01:37:40.325758Z","steps":["trace[1407789223] 'agreement among raft nodes before linearized reading' (duration: 822.942756ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:40.32578Z","caller":"traceutil/trace.go:171","msg":"trace[2090206860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:424; }","duration":"689.85734ms","start":"2026-02-25T01:37:39.635907Z","end":"2026-02-25T01:37:40.325764Z","steps":["trace[2090206860] 'agreement among raft nodes before linearized reading' (duration: 689.739557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:40.325799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:39.502693Z","time spent":"823.099971ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:37:40.325798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:38.846569Z","time spent":"1.479219749s","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T01:37:40.325819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:39.635862Z","time spent":"689.948334ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:37:40.325775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.262706402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:40.325898Z","caller":"traceutil/trace.go:171","msg":"trace[1299523255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:424; }","duration":"1.262880867s","start":"2026-02-25T01:37:39.062984Z","end":"2026-02-25T01:37:40.325864Z","steps":["trace[1299523255] 'agreement among raft nodes before linearized reading' (duration: 1.262648921s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:40.325961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.646783ms","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-02-25T01:37:40.325966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:39.062959Z","time spent":"1.26299683s","remote":"127.0.0.1:40274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:37:40.325995Z","caller":"traceutil/trace.go:171","msg":"trace[447988163] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:424; }","duration":"608.678054ms","start":"2026-02-25T01:37:39.717306Z","end":"2026-02-25T01:37:40.325984Z","steps":["trace[447988163] 'agreement among raft nodes before linearized reading' (duration: 608.322344ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:40.326014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:39.71727Z","time spent":"608.739645ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T01:37:40.570799Z","caller":"traceutil/trace.go:171","msg":"trace[853267465] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"240.999961ms","start":"2026-02-25T01:37:40.329779Z","end":"2026-02-25T01:37:40.570779Z","steps":["trace[853267465] 'process raft request' (duration: 240.847056ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:40.708394Z","caller":"traceutil/trace.go:171","msg":"trace[419283676] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"377.540774ms","start":"2026-02-25T01:37:40.330825Z","end":"2026-02-25T01:37:40.708366Z","steps":["trace[419283676] 'process raft request' (duration: 377.236485ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:40.708401Z","caller":"traceutil/trace.go:171","msg":"trace[2025991047] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"377.537474ms","start":"2026-02-25T01:37:40.330831Z","end":"2026-02-25T01:37:40.708369Z","steps":["trace[2025991047] 'process raft request' (duration: 377.428331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:40.708559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:40.330806Z","time spent":"377.661078ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:37:40.708587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:40.330803Z","time spent":"377.680398ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:37:40.708589Z","caller":"traceutil/trace.go:171","msg":"trace[258016] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:439; }","duration":"373.13006ms","start":"2026-02-25T01:37:40.335438Z","end":"2026-02-25T01:37:40.708568Z","steps":["trace[258016] 'read index received' (duration: 235.190026ms)","trace[258016] 'applied index is now lower than readState.Index' (duration: 137.938994ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:40.70869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.249343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:40.708723Z","caller":"traceutil/trace.go:171","msg":"trace[317795127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"373.333995ms","start":"2026-02-25T01:37:40.335377Z","end":"2026-02-25T01:37:40.708711Z","steps":["trace[317795127] 'agreement among raft nodes before linearized reading' (duration: 373.257202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:40.708752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:40.335367Z","time spent":"373.378727ms","remote":"127.0.0.1:40274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:37:40.708844Z","caller":"traceutil/trace.go:171","msg":"trace[298743296] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"377.939875ms","start":"2026-02-25T01:37:40.330886Z","end":"2026-02-25T01:37:40.708826Z","steps":["trace[298743296] 'process raft request' (duration: 377.433621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:40.70892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:40.330868Z","time spent":"378.009307ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:37:45.171174Z","caller":"traceutil/trace.go:171","msg":"trace[1296684539] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"319.603785ms","start":"2026-02-25T01:37:44.851548Z","end":"2026-02-25T01:37:45.171151Z","steps":["trace[1296684539] 'process raft request' (duration: 319.45534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:45.171424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:44.851518Z","time spent":"319.725567ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:37:45.171874Z","caller":"traceutil/trace.go:171","msg":"trace[1832429514] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"320.260212ms","start":"2026-02-25T01:37:44.851588Z","end":"2026-02-25T01:37:45.171848Z","steps":["trace[1832429514] 'process raft request' (duration: 320.104798ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:45.171923Z","caller":"traceutil/trace.go:171","msg":"trace[1718964029] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:450; }","duration":"109.890859ms","start":"2026-02-25T01:37:45.062016Z","end":"2026-02-25T01:37:45.171907Z","steps":["trace[1718964029] 'read index received' (duration: 109.011994ms)","trace[1718964029] 'applied index is now lower than readState.Index' (duration: 877.765µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:45.172032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:44.851565Z","time spent":"320.384036ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:37:45.172034Z","caller":"traceutil/trace.go:171","msg":"trace[1233532937] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"320.293504ms","start":"2026-02-25T01:37:44.851724Z","end":"2026-02-25T01:37:45.172017Z","steps":["trace[1233532937] 'process raft request' (duration: 320.084698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:45.172102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.051304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:45.172167Z","caller":"traceutil/trace.go:171","msg":"trace[1793236002] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:437; }","duration":"110.154737ms","start":"2026-02-25T01:37:45.062002Z","end":"2026-02-25T01:37:45.172157Z","steps":["trace[1793236002] 'agreement among raft nodes before linearized reading' (duration: 110.029434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:45.172118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:44.851702Z","time spent":"320.377276ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:37:45.54115Z","caller":"traceutil/trace.go:171","msg":"trace[714062059] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"142.917354ms","start":"2026-02-25T01:37:45.398213Z","end":"2026-02-25T01:37:45.54113Z","steps":["trace[714062059] 'process raft request' (duration: 142.799091ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:47.030325Z","caller":"traceutil/trace.go:171","msg":"trace[338551240] linearizableReadLoop","detail":"{readStateIndex:455; appliedIndex:454; }","duration":"312.984946ms","start":"2026-02-25T01:37:46.717317Z","end":"2026-02-25T01:37:47.030302Z","steps":["trace[338551240] 'read index received' (duration: 312.845032ms)","trace[338551240] 'applied index is now lower than readState.Index' (duration: 139.024µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:47.030379Z","caller":"traceutil/trace.go:171","msg":"trace[744702359] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"381.254708ms","start":"2026-02-25T01:37:46.649095Z","end":"2026-02-25T01:37:47.03035Z","steps":["trace[744702359] 'process raft request' (duration: 381.076603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:47.030446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.115639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T01:37:47.03048Z","caller":"traceutil/trace.go:171","msg":"trace[1039240670] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:439; }","duration":"313.201302ms","start":"2026-02-25T01:37:46.717269Z","end":"2026-02-25T01:37:47.03047Z","steps":["trace[1039240670] 'agreement among raft nodes before linearized reading' (duration: 313.12058ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:47.030512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:46.717246Z","time spent":"313.258663ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:37:47.030527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:37:46.649072Z","time spent":"381.373042ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:37:47.176967Z","caller":"traceutil/trace.go:171","msg":"trace[701368167] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"106.950952ms","start":"2026-02-25T01:37:47.069988Z","end":"2026-02-25T01:37:47.176939Z","steps":["trace[701368167] 'process raft request' (duration: 106.635003ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:47.312241Z","caller":"traceutil/trace.go:171","msg":"trace[1118179445] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"130.736239ms","start":"2026-02-25T01:37:47.181483Z","end":"2026-02-25T01:37:47.312219Z","steps":["trace[1118179445] 'process raft request' (duration: 130.585155ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:47.314194Z","caller":"traceutil/trace.go:171","msg":"trace[1634812329] linearizableReadLoop","detail":"{readStateIndex:460; appliedIndex:457; }","duration":"128.90752ms","start":"2026-02-25T01:37:47.185273Z","end":"2026-02-25T01:37:47.314181Z","steps":["trace[1634812329] 'read index received' (duration: 126.831684ms)","trace[1634812329] 'applied index is now lower than readState.Index' (duration: 2.075206ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:47.31424Z","caller":"traceutil/trace.go:171","msg":"trace[1684694862] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"131.779368ms","start":"2026-02-25T01:37:47.182434Z","end":"2026-02-25T01:37:47.314214Z","steps":["trace[1684694862] 'process raft request' (duration: 131.640424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:47.314314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.027774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:37:47.314358Z","caller":"traceutil/trace.go:171","msg":"trace[1381469504] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:444; }","duration":"129.104956ms","start":"2026-02-25T01:37:47.185241Z","end":"2026-02-25T01:37:47.314346Z","steps":["trace[1381469504] 'agreement among raft nodes before linearized reading' (duration: 128.995323ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:47.314292Z","caller":"traceutil/trace.go:171","msg":"trace[1612944095] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"131.1459ms","start":"2026-02-25T01:37:47.183109Z","end":"2026-02-25T01:37:47.314255Z","steps":["trace[1612944095] 'process raft request' (duration: 131.039477ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:47.60034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.179726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:37:47.60054Z","caller":"traceutil/trace.go:171","msg":"trace[1643318912] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"282.771113ms","start":"2026-02-25T01:37:47.317756Z","end":"2026-02-25T01:37:47.600527Z","steps":["trace[1643318912] 'process raft request' (duration: 282.705362ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:47.60063Z","caller":"traceutil/trace.go:171","msg":"trace[210483509] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"284.046067ms","start":"2026-02-25T01:37:47.316546Z","end":"2026-02-25T01:37:47.600592Z","steps":["trace[210483509] 'process raft request' (duration: 172.560854ms)","trace[210483509] 'compare' (duration: 110.882647ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:47.954319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.013047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-02-25T01:37:47.954532Z","caller":"traceutil/trace.go:171","msg":"trace[1714918877] transaction","detail":"{read_only:false; number_of_response:1; response_revision:457; }","duration":"163.016568ms","start":"2026-02-25T01:37:47.791488Z","end":"2026-02-25T01:37:47.954504Z","steps":["trace[1714918877] 'process raft request' (duration: 53.704782ms)","trace[1714918877] 'compare' (duration: 108.948546ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:47.954593Z","caller":"traceutil/trace.go:171","msg":"trace[471399112] linearizableReadLoop","detail":"{readStateIndex:475; appliedIndex:472; }","duration":"155.545348ms","start":"2026-02-25T01:37:47.799033Z","end":"2026-02-25T01:37:47.954579Z","steps":["trace[471399112] 'read index received' (duration: 46.131919ms)","trace[471399112] 'applied index is now lower than readState.Index' (duration: 109.411928ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:37:47.954824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.760963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-77cccfdc44\" ","response":"range_response_count:1 size:3833"} {"level":"info","ts":"2026-02-25T01:37:47.954855Z","caller":"traceutil/trace.go:171","msg":"trace[2091537490] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-77cccfdc44; range_end:; response_count:1; response_revision:459; }","duration":"155.839905ms","start":"2026-02-25T01:37:47.799006Z","end":"2026-02-25T01:37:47.954846Z","steps":["trace[2091537490] 'agreement among raft nodes before linearized reading' (duration: 155.615039ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:47.954907Z","caller":"traceutil/trace.go:171","msg":"trace[1150996814] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"157.934661ms","start":"2026-02-25T01:37:47.79696Z","end":"2026-02-25T01:37:47.954895Z","steps":["trace[1150996814] 'process raft request' (duration: 157.475339ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:47.954974Z","caller":"traceutil/trace.go:171","msg":"trace[1914392962] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"160.913941ms","start":"2026-02-25T01:37:47.794034Z","end":"2026-02-25T01:37:47.954947Z","steps":["trace[1914392962] 'process raft request' (duration: 160.362637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:37:48.353221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.532432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:37:48.353514Z","caller":"traceutil/trace.go:171","msg":"trace[557365213] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"205.16835ms","start":"2026-02-25T01:37:48.148322Z","end":"2026-02-25T01:37:48.35349Z","steps":["trace[557365213] 'process raft request' (duration: 95.192046ms)","trace[557365213] 'compare' (duration: 109.376738ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:48.353628Z","caller":"traceutil/trace.go:171","msg":"trace[1954617414] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"204.0678ms","start":"2026-02-25T01:37:48.149549Z","end":"2026-02-25T01:37:48.353616Z","steps":["trace[1954617414] 'process raft request' (duration: 203.899026ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:48.353643Z","caller":"traceutil/trace.go:171","msg":"trace[978650662] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"201.811279ms","start":"2026-02-25T01:37:48.151806Z","end":"2026-02-25T01:37:48.353617Z","steps":["trace[978650662] 'process raft request' (duration: 201.742327ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:48.353658Z","caller":"traceutil/trace.go:171","msg":"trace[426950934] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"205.290193ms","start":"2026-02-25T01:37:48.148333Z","end":"2026-02-25T01:37:48.353623Z","steps":["trace[426950934] 'process raft request' (duration: 205.027796ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:37:48.56515Z","caller":"traceutil/trace.go:171","msg":"trace[1287883832] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"202.19255ms","start":"2026-02-25T01:37:48.36293Z","end":"2026-02-25T01:37:48.565122Z","steps":["trace[1287883832] 'process raft request' (duration: 134.002949ms)","trace[1287883832] 'compare' (duration: 68.068448ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:37:48.726208Z","caller":"traceutil/trace.go:171","msg":"trace[580453291] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"147.363018ms","start":"2026-02-25T01:37:48.578812Z","end":"2026-02-25T01:37:48.726175Z","steps":["trace[580453291] 'process raft request' (duration: 102.461882ms)","trace[580453291] 'compare' (duration: 44.756542ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:01.781312Z","caller":"traceutil/trace.go:171","msg":"trace[1653714810] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"405.768065ms","start":"2026-02-25T01:38:01.375516Z","end":"2026-02-25T01:38:01.781284Z","steps":["trace[1653714810] 'read index received' (duration: 405.542139ms)","trace[1653714810] 'applied index is now lower than readState.Index' (duration: 224.806µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:01.781411Z","caller":"traceutil/trace.go:171","msg":"trace[1332617002] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"661.287367ms","start":"2026-02-25T01:38:01.120095Z","end":"2026-02-25T01:38:01.781383Z","steps":["trace[1332617002] 'process raft request' (duration: 661.053501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:01.781539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.024993ms","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-02-25T01:38:01.78154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.311522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-25T01:38:01.781558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:01.120079Z","time spent":"661.401941ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:01.781587Z","caller":"traceutil/trace.go:171","msg":"trace[193517465] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:537; }","duration":"406.129945ms","start":"2026-02-25T01:38:01.375444Z","end":"2026-02-25T01:38:01.781574Z","steps":["trace[193517465] 'agreement among raft nodes before linearized reading' (duration: 405.973811ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:01.781614Z","caller":"traceutil/trace.go:171","msg":"trace[1761072797] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"402.417756ms","start":"2026-02-25T01:38:01.379176Z","end":"2026-02-25T01:38:01.781593Z","steps":["trace[1761072797] 'agreement among raft nodes before linearized reading' (duration: 402.277232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:01.781626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:01.375404Z","time spent":"406.213407ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:38:01.781629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.451362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"warn","ts":"2026-02-25T01:38:01.781675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:01.379166Z","time spent":"402.496208ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:38:01.781613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.379043ms","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-02-25T01:38:01.781738Z","caller":"traceutil/trace.go:171","msg":"trace[1075597604] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:537; }","duration":"308.603017ms","start":"2026-02-25T01:38:01.473101Z","end":"2026-02-25T01:38:01.781704Z","steps":["trace[1075597604] 'agreement among raft nodes before linearized reading' (duration: 308.378651ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:01.781754Z","caller":"traceutil/trace.go:171","msg":"trace[1214744119] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:537; }","duration":"396.569759ms","start":"2026-02-25T01:38:01.385173Z","end":"2026-02-25T01:38:01.781743Z","steps":["trace[1214744119] 'agreement among raft nodes before linearized reading' (duration: 396.380954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:01.781786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:01.385138Z","time spent":"396.640191ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T01:38:01.781787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:01.473075Z","time spent":"308.703229ms","remote":"127.0.0.1:40454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4044,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-25T01:38:01.962849Z","caller":"traceutil/trace.go:171","msg":"trace[486805302] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"177.389413ms","start":"2026-02-25T01:38:01.785435Z","end":"2026-02-25T01:38:01.962825Z","steps":["trace[486805302] 'process raft request' (duration: 94.615071ms)","trace[486805302] 'compare' (duration: 82.67159ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:02.030715Z","caller":"traceutil/trace.go:171","msg":"trace[1741481864] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"244.849606ms","start":"2026-02-25T01:38:01.785791Z","end":"2026-02-25T01:38:02.030641Z","steps":["trace[1741481864] 'process raft request' (duration: 244.507166ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:02.030833Z","caller":"traceutil/trace.go:171","msg":"trace[1933817925] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"244.437634ms","start":"2026-02-25T01:38:01.786342Z","end":"2026-02-25T01:38:02.03078Z","steps":["trace[1933817925] 'process raft request' (duration: 244.164817ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:02.030905Z","caller":"traceutil/trace.go:171","msg":"trace[911097850] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"240.378045ms","start":"2026-02-25T01:38:01.790501Z","end":"2026-02-25T01:38:02.030879Z","steps":["trace[911097850] 'process raft request' (duration: 240.054366ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:07.691539Z","caller":"traceutil/trace.go:171","msg":"trace[1510027208] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"145.256341ms","start":"2026-02-25T01:38:07.546261Z","end":"2026-02-25T01:38:07.691517Z","steps":["trace[1510027208] 'process raft request' (duration: 144.997634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:08.085163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.43272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T01:38:08.085293Z","caller":"traceutil/trace.go:171","msg":"trace[1111895414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:551; }","duration":"277.617305ms","start":"2026-02-25T01:38:07.807661Z","end":"2026-02-25T01:38:08.085278Z","steps":["trace[1111895414] 'range keys from in-memory index tree' (duration: 277.272526ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:08.085308Z","caller":"traceutil/trace.go:171","msg":"trace[325834305] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"184.831663ms","start":"2026-02-25T01:38:07.900441Z","end":"2026-02-25T01:38:08.085272Z","steps":["trace[325834305] 'process raft request' (duration: 184.005651ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:09.395764Z","caller":"traceutil/trace.go:171","msg":"trace[403154695] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"265.360827ms","start":"2026-02-25T01:38:09.130374Z","end":"2026-02-25T01:38:09.395735Z","steps":["trace[403154695] 'process raft request' (duration: 265.162671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:10.603035Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826906228120786,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:38:10.829197Z","caller":"traceutil/trace.go:171","msg":"trace[728091495] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:581; }","duration":"727.011263ms","start":"2026-02-25T01:38:10.102159Z","end":"2026-02-25T01:38:10.82917Z","steps":["trace[728091495] 'read index received' (duration: 726.783257ms)","trace[728091495] 'applied index is now lower than readState.Index' (duration: 226.996µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:10.829398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.213808ms","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-02-25T01:38:10.829359Z","caller":"traceutil/trace.go:171","msg":"trace[1902413686] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"728.100553ms","start":"2026-02-25T01:38:10.101213Z","end":"2026-02-25T01:38:10.829313Z","steps":["trace[1902413686] 'process raft request' (duration: 727.794245ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:10.829457Z","caller":"traceutil/trace.go:171","msg":"trace[2050918622] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"727.318261ms","start":"2026-02-25T01:38:10.102125Z","end":"2026-02-25T01:38:10.829443Z","steps":["trace[2050918622] 'agreement among raft nodes before linearized reading' (duration: 727.189628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:10.829498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:10.102113Z","time spent":"727.376643ms","remote":"127.0.0.1:40562","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-02-25T01:38:10.829605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:10.101178Z","time spent":"728.298057ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:10.830061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.781594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-25T01:38:10.830121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.569828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T01:38:10.830146Z","caller":"traceutil/trace.go:171","msg":"trace[1818690316] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:561; }","duration":"727.884527ms","start":"2026-02-25T01:38:10.102241Z","end":"2026-02-25T01:38:10.830126Z","steps":["trace[1818690316] 'agreement among raft nodes before linearized reading' (duration: 727.605869ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:10.830168Z","caller":"traceutil/trace.go:171","msg":"trace[836595209] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:561; }","duration":"727.66468ms","start":"2026-02-25T01:38:10.10249Z","end":"2026-02-25T01:38:10.830154Z","steps":["trace[836595209] 'agreement among raft nodes before linearized reading' (duration: 727.407903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:10.830204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:10.102214Z","time spent":"727.981369ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:38:10.83021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:10.102466Z","time spent":"727.736533ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:38:11.562876Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826906228120789,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:38:11.87736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.033901665s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:38:11.877509Z","caller":"traceutil/trace.go:171","msg":"trace[1365945821] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:561; }","duration":"1.03408101s","start":"2026-02-25T01:38:10.843408Z","end":"2026-02-25T01:38:11.877489Z","steps":["trace[1365945821] 'range keys from in-memory index tree' (duration: 1.033879715s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:11.877578Z","caller":"traceutil/trace.go:171","msg":"trace[2087683008] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.024239555s","start":"2026-02-25T01:38:10.853304Z","end":"2026-02-25T01:38:11.877543Z","steps":["trace[2087683008] 'process raft request' (duration: 943.421725ms)","trace[2087683008] 'compare' (duration: 80.364858ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:11.877592Z","caller":"traceutil/trace.go:171","msg":"trace[427082712] linearizableReadLoop","detail":"{readStateIndex:585; appliedIndex:582; }","duration":"815.569292ms","start":"2026-02-25T01:38:11.061994Z","end":"2026-02-25T01:38:11.877563Z","steps":["trace[427082712] 'read index received' (duration: 734.670029ms)","trace[427082712] 'applied index is now lower than readState.Index' (duration: 80.898003ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:11.877636Z","caller":"traceutil/trace.go:171","msg":"trace[718452170] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.024245865s","start":"2026-02-25T01:38:10.853365Z","end":"2026-02-25T01:38:11.87761Z","steps":["trace[718452170] 'process raft request' (duration: 1.024082151s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:11.877742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:10.853273Z","time spent":"1.024380369s","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:11.877746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.735696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:38:11.877795Z","caller":"traceutil/trace.go:171","msg":"trace[627720812] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"815.846769ms","start":"2026-02-25T01:38:11.061938Z","end":"2026-02-25T01:38:11.877784Z","steps":["trace[627720812] 'agreement among raft nodes before linearized reading' (duration: 815.723546ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:11.877817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:10.853339Z","time spent":"1.024377869s","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:11.877848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:11.061911Z","time spent":"815.929581ms","remote":"127.0.0.1:40274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-25T01:38:11.877738Z","caller":"traceutil/trace.go:171","msg":"trace[1432709787] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.02404397s","start":"2026-02-25T01:38:10.853594Z","end":"2026-02-25T01:38:11.877638Z","steps":["trace[1432709787] 'process raft request' (duration: 1.023916286s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:11.878497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:10.853587Z","time spent":"1.0247918s","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:13.741248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.818207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T01:38:13.741329Z","caller":"traceutil/trace.go:171","msg":"trace[1395165664] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:575; }","duration":"335.957262ms","start":"2026-02-25T01:38:13.405354Z","end":"2026-02-25T01:38:13.741311Z","steps":["trace[1395165664] 'range keys from in-memory index tree' (duration: 335.574121ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:13.741373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:13.405329Z","time spent":"336.032123ms","remote":"127.0.0.1:40306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.169\" "} {"level":"warn","ts":"2026-02-25T01:38:24.278563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.111045ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:38:24.278834Z","caller":"traceutil/trace.go:171","msg":"trace[145883372] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"331.509322ms","start":"2026-02-25T01:38:23.947295Z","end":"2026-02-25T01:38:24.278805Z","steps":["trace[145883372] 'process raft request' (duration: 33.018827ms)","trace[145883372] 'compare' (duration: 297.960021ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:24.278921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:23.94728Z","time spent":"331.605795ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:24.284763Z","caller":"traceutil/trace.go:171","msg":"trace[1192162889] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:747; }","duration":"222.772383ms","start":"2026-02-25T01:38:24.061957Z","end":"2026-02-25T01:38:24.284729Z","steps":["trace[1192162889] 'read index received' (duration: 222.580048ms)","trace[1192162889] 'applied index is now lower than readState.Index' (duration: 191.195µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:24.2848Z","caller":"traceutil/trace.go:171","msg":"trace[1111503717] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"334.801761ms","start":"2026-02-25T01:38:23.949963Z","end":"2026-02-25T01:38:24.284764Z","steps":["trace[1111503717] 'process raft request' (duration: 334.597015ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:24.284838Z","caller":"traceutil/trace.go:171","msg":"trace[990543212] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"332.962492ms","start":"2026-02-25T01:38:23.951845Z","end":"2026-02-25T01:38:24.284808Z","steps":["trace[990543212] 'process raft request' (duration: 332.841229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:24.284914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.937507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:38:24.284953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:23.951827Z","time spent":"333.060434ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:24.284971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:23.949942Z","time spent":"334.916384ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:24.284959Z","caller":"traceutil/trace.go:171","msg":"trace[476058111] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:723; }","duration":"223.019899ms","start":"2026-02-25T01:38:24.061927Z","end":"2026-02-25T01:38:24.284947Z","steps":["trace[476058111] 'agreement among raft nodes before linearized reading' (duration: 222.940296ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:30.481626Z","caller":"traceutil/trace.go:171","msg":"trace[342151881] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:773; }","duration":"419.35192ms","start":"2026-02-25T01:38:30.062253Z","end":"2026-02-25T01:38:30.481605Z","steps":["trace[342151881] 'read index received' (duration: 419.273628ms)","trace[342151881] 'applied index is now lower than readState.Index' (duration: 77.312µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:30.481706Z","caller":"traceutil/trace.go:171","msg":"trace[1273772726] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"522.157721ms","start":"2026-02-25T01:38:29.959517Z","end":"2026-02-25T01:38:30.481675Z","steps":["trace[1273772726] 'process raft request' (duration: 521.908844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:30.481776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.504184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:38:30.481811Z","caller":"traceutil/trace.go:171","msg":"trace[681731187] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"419.580066ms","start":"2026-02-25T01:38:30.062221Z","end":"2026-02-25T01:38:30.481801Z","steps":["trace[681731187] 'agreement among raft nodes before linearized reading' (duration: 419.511654ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:30.481834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:29.959505Z","time spent":"522.252484ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:30.481852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:30.062208Z","time spent":"419.637357ms","remote":"127.0.0.1:40274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:38:30.481994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.914677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T01:38:30.482053Z","caller":"traceutil/trace.go:171","msg":"trace[1515355286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:747; }","duration":"178.049162ms","start":"2026-02-25T01:38:30.303989Z","end":"2026-02-25T01:38:30.482039Z","steps":["trace[1515355286] 'agreement among raft nodes before linearized reading' (duration: 177.854656ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:30.482254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.996355ms","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-02-25T01:38:30.48229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.15468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:38:30.482295Z","caller":"traceutil/trace.go:171","msg":"trace[1889196230] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:747; }","duration":"173.077907ms","start":"2026-02-25T01:38:30.309206Z","end":"2026-02-25T01:38:30.482284Z","steps":["trace[1889196230] 'agreement among raft nodes before linearized reading' (duration: 172.953894ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:30.482326Z","caller":"traceutil/trace.go:171","msg":"trace[132130447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:747; }","duration":"173.191581ms","start":"2026-02-25T01:38:30.309125Z","end":"2026-02-25T01:38:30.482316Z","steps":["trace[132130447] 'agreement among raft nodes before linearized reading' (duration: 173.080338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:31.439996Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826906228121166,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:38:31.602271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"758.410617ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:38:31.602434Z","caller":"traceutil/trace.go:171","msg":"trace[2064519932] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:747; }","duration":"758.587121ms","start":"2026-02-25T01:38:30.843821Z","end":"2026-02-25T01:38:31.602408Z","steps":["trace[2064519932] 'range keys from in-memory index tree' (duration: 758.387836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:31.612494Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.103593676s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T01:38:31.612988Z","caller":"traceutil/trace.go:171","msg":"trace[1140017822] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"1.124307362s","start":"2026-02-25T01:38:30.488659Z","end":"2026-02-25T01:38:31.612966Z","steps":["trace[1140017822] 'process raft request' (duration: 1.124137098s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:31.61318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:30.488646Z","time spent":"1.124399555s","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:31.671389Z","caller":"traceutil/trace.go:171","msg":"trace[466291139] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"1.182504905s","start":"2026-02-25T01:38:30.488852Z","end":"2026-02-25T01:38:31.671357Z","steps":["trace[466291139] 'process raft request' (duration: 1.182189947s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:31.671441Z","caller":"traceutil/trace.go:171","msg":"trace[258526768] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:774; }","duration":"731.929036ms","start":"2026-02-25T01:38:30.939489Z","end":"2026-02-25T01:38:31.671418Z","steps":["trace[258526768] 'read index received' (duration: 673.277141ms)","trace[258526768] 'applied index is now lower than readState.Index' (duration: 58.651105ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:31.671566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:30.488843Z","time spent":"1.182633568s","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:31.671686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.188504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:38:31.671738Z","caller":"traceutil/trace.go:171","msg":"trace[85873710] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:750; }","duration":"188.266566ms","start":"2026-02-25T01:38:31.483462Z","end":"2026-02-25T01:38:31.671729Z","steps":["trace[85873710] 'agreement among raft nodes before linearized reading' (duration: 188.139973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:31.671852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.349417ms","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-02-25T01:38:31.671959Z","caller":"traceutil/trace.go:171","msg":"trace[635362863] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"1.181904689s","start":"2026-02-25T01:38:30.490042Z","end":"2026-02-25T01:38:31.671947Z","steps":["trace[635362863] 'process raft request' (duration: 1.181136298s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:31.671969Z","caller":"traceutil/trace.go:171","msg":"trace[1696893665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:750; }","duration":"732.528411ms","start":"2026-02-25T01:38:30.93942Z","end":"2026-02-25T01:38:31.671949Z","steps":["trace[1696893665] 'agreement among raft nodes before linearized reading' (duration: 732.139061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:31.67204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.721321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:38:31.672113Z","caller":"traceutil/trace.go:171","msg":"trace[914785757] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:750; }","duration":"133.847324ms","start":"2026-02-25T01:38:31.538249Z","end":"2026-02-25T01:38:31.672096Z","steps":["trace[914785757] 'agreement among raft nodes before linearized reading' (duration: 133.69742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:31.672054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:30.490035Z","time spent":"1.181954s","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:38:31.672124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:30.939393Z","time spent":"732.682015ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T01:38:31.672637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.968599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:38:31.672708Z","caller":"traceutil/trace.go:171","msg":"trace[1098117054] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:750; }","duration":"178.72477ms","start":"2026-02-25T01:38:31.493966Z","end":"2026-02-25T01:38:31.672691Z","steps":["trace[1098117054] 'agreement among raft nodes before linearized reading' (duration: 177.936779ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:33.812845Z","caller":"traceutil/trace.go:171","msg":"trace[1519592056] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"163.444158ms","start":"2026-02-25T01:38:33.649372Z","end":"2026-02-25T01:38:33.812817Z","steps":["trace[1519592056] 'process raft request' (duration: 103.128079ms)","trace[1519592056] 'compare' (duration: 60.165365ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:33.812854Z","caller":"traceutil/trace.go:171","msg":"trace[54084163] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:780; }","duration":"136.892675ms","start":"2026-02-25T01:38:33.675941Z","end":"2026-02-25T01:38:33.812834Z","steps":["trace[54084163] 'read index received' (duration: 76.546795ms)","trace[54084163] 'applied index is now lower than readState.Index' (duration: 60.34514ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:38:33.812912Z","caller":"traceutil/trace.go:171","msg":"trace[874118386] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"160.375155ms","start":"2026-02-25T01:38:33.652525Z","end":"2026-02-25T01:38:33.8129Z","steps":["trace[874118386] 'process raft request' (duration: 160.267652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:33.813016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.87277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:38:33.813048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.09042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:38:33.81307Z","caller":"traceutil/trace.go:171","msg":"trace[1924264062] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:754; }","duration":"109.958432ms","start":"2026-02-25T01:38:33.703098Z","end":"2026-02-25T01:38:33.813056Z","steps":["trace[1924264062] 'agreement among raft nodes before linearized reading' (duration: 109.870129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:33.813086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.659248ms","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-02-25T01:38:33.813097Z","caller":"traceutil/trace.go:171","msg":"trace[157234678] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:754; }","duration":"137.169762ms","start":"2026-02-25T01:38:33.675916Z","end":"2026-02-25T01:38:33.813085Z","steps":["trace[157234678] 'agreement among raft nodes before linearized reading' (duration: 137.000178ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:33.813114Z","caller":"traceutil/trace.go:171","msg":"trace[1097069844] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:754; }","duration":"136.743111ms","start":"2026-02-25T01:38:33.676362Z","end":"2026-02-25T01:38:33.813106Z","steps":["trace[1097069844] 'agreement among raft nodes before linearized reading' (duration: 136.603437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:33.813117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.033766ms","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-02-25T01:38:33.813151Z","caller":"traceutil/trace.go:171","msg":"trace[2051590379] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:754; }","duration":"122.117218ms","start":"2026-02-25T01:38:33.691026Z","end":"2026-02-25T01:38:33.813144Z","steps":["trace[2051590379] 'agreement among raft nodes before linearized reading' (duration: 121.964024ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:56.036262Z","caller":"traceutil/trace.go:171","msg":"trace[940316712] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"110.041137ms","start":"2026-02-25T01:38:55.926165Z","end":"2026-02-25T01:38:56.036207Z","steps":["trace[940316712] 'process raft request' (duration: 109.753759ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:38:57.684505Z","caller":"traceutil/trace.go:171","msg":"trace[1682409756] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"947.552951ms","start":"2026-02-25T01:38:56.736907Z","end":"2026-02-25T01:38:57.68446Z","steps":["trace[1682409756] 'process raft request' (duration: 946.302547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:57.684897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:56.736886Z","time spent":"947.738685ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:59.185964Z","caller":"traceutil/trace.go:171","msg":"trace[29332533] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"456.803631ms","start":"2026-02-25T01:38:58.729138Z","end":"2026-02-25T01:38:59.185941Z","steps":["trace[29332533] 'process raft request' (duration: 456.617696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:59.186145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:58.729106Z","time spent":"456.953965ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:38:59.186292Z","caller":"traceutil/trace.go:171","msg":"trace[2073619851] linearizableReadLoop","detail":"{readStateIndex:862; appliedIndex:861; }","duration":"435.704428ms","start":"2026-02-25T01:38:58.750193Z","end":"2026-02-25T01:38:59.185898Z","steps":["trace[2073619851] 'read index received' (duration: 435.526463ms)","trace[2073619851] 'applied index is now lower than readState.Index' (duration: 176.855µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:38:59.186636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.404757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:38:59.186777Z","caller":"traceutil/trace.go:171","msg":"trace[1686506169] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:829; }","duration":"436.614303ms","start":"2026-02-25T01:38:58.750135Z","end":"2026-02-25T01:38:59.186749Z","steps":["trace[1686506169] 'agreement among raft nodes before linearized reading' (duration: 436.163931ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:38:59.187387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:58.750115Z","time spent":"437.146326ms","remote":"127.0.0.1:40594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-25T01:38:59.687229Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826906228121392,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T01:39:00.102739Z","caller":"traceutil/trace.go:171","msg":"trace[256627013] linearizableReadLoop","detail":"{readStateIndex:863; appliedIndex:862; }","duration":"916.378218ms","start":"2026-02-25T01:38:59.186334Z","end":"2026-02-25T01:39:00.102713Z","steps":["trace[256627013] 'read index received' (duration: 915.854244ms)","trace[256627013] 'applied index is now lower than readState.Index' (duration: 522.834µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:00.102897Z","caller":"traceutil/trace.go:171","msg":"trace[2082263857] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"1.205629328s","start":"2026-02-25T01:38:58.89726Z","end":"2026-02-25T01:39:00.102889Z","steps":["trace[2082263857] 'process raft request' (duration: 1.205215947s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:00.103011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:58.897233Z","time spent":"1.20570167s","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:39:00.103186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.677984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T01:39:00.103192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.042231466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:39:00.103269Z","caller":"traceutil/trace.go:171","msg":"trace[1355289781] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:830; }","duration":"406.771176ms","start":"2026-02-25T01:38:59.696476Z","end":"2026-02-25T01:39:00.103247Z","steps":["trace[1355289781] 'agreement among raft nodes before linearized reading' (duration: 406.562661ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:00.103443Z","caller":"traceutil/trace.go:171","msg":"trace[944138624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:830; }","duration":"1.042492173s","start":"2026-02-25T01:38:59.0609Z","end":"2026-02-25T01:39:00.103392Z","steps":["trace[944138624] 'agreement among raft nodes before linearized reading' (duration: 1.042156504s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:00.103526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:59.060831Z","time spent":"1.042680038s","remote":"127.0.0.1:40274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:39:00.103538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:38:59.696431Z","time spent":"407.093925ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T01:39:00.436654Z","caller":"traceutil/trace.go:171","msg":"trace[703114819] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"328.442697ms","start":"2026-02-25T01:39:00.108156Z","end":"2026-02-25T01:39:00.436598Z","steps":["trace[703114819] 'process raft request' (duration: 309.351707ms)","trace[703114819] 'compare' (duration: 18.670869ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:00.437041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:00.108144Z","time spent":"328.639412ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:00.455296Z","caller":"traceutil/trace.go:171","msg":"trace[448207116] linearizableReadLoop","detail":"{readStateIndex:865; appliedIndex:863; }","duration":"342.43845ms","start":"2026-02-25T01:39:00.112826Z","end":"2026-02-25T01:39:00.455264Z","steps":["trace[448207116] 'read index received' (duration: 304.687262ms)","trace[448207116] 'applied index is now lower than readState.Index' (duration: 37.749868ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:00.455434Z","caller":"traceutil/trace.go:171","msg":"trace[1014893849] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"346.712524ms","start":"2026-02-25T01:39:00.108677Z","end":"2026-02-25T01:39:00.455389Z","steps":["trace[1014893849] 'process raft request' (duration: 346.434527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:00.455568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.712347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:39:00.456016Z","caller":"traceutil/trace.go:171","msg":"trace[918001127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:832; }","duration":"343.191769ms","start":"2026-02-25T01:39:00.112808Z","end":"2026-02-25T01:39:00.456Z","steps":["trace[918001127] 'agreement among raft nodes before linearized reading' (duration: 342.650695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:00.455933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:00.108669Z","time spent":"347.078184ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:39:00.456176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:00.112802Z","time spent":"343.292712ms","remote":"127.0.0.1:40274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:39:00.456202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.062335ms","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-02-25T01:39:00.456433Z","caller":"traceutil/trace.go:171","msg":"trace[47301269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:832; }","duration":"304.324782ms","start":"2026-02-25T01:39:00.152089Z","end":"2026-02-25T01:39:00.456414Z","steps":["trace[47301269] 'agreement among raft nodes before linearized reading' (duration: 303.900031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:00.456443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.448405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T01:39:00.456488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:00.152062Z","time spent":"304.413955ms","remote":"127.0.0.1:40562","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-02-25T01:39:00.456503Z","caller":"traceutil/trace.go:171","msg":"trace[1351859234] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:832; }","duration":"304.543518ms","start":"2026-02-25T01:39:00.151944Z","end":"2026-02-25T01:39:00.456488Z","steps":["trace[1351859234] 'agreement among raft nodes before linearized reading' (duration: 304.304532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:00.456624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:00.151934Z","time spent":"304.680302ms","remote":"127.0.0.1:40562","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-02-25T01:39:00.456708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.739821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T01:39:00.456773Z","caller":"traceutil/trace.go:171","msg":"trace[2095510394] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:832; }","duration":"317.826633ms","start":"2026-02-25T01:39:00.138929Z","end":"2026-02-25T01:39:00.456756Z","steps":["trace[2095510394] 'agreement among raft nodes before linearized reading' (duration: 316.948609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:00.456811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:00.138919Z","time spent":"317.881475ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T01:39:12.735596Z","caller":"traceutil/trace.go:171","msg":"trace[1147760484] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"226.517386ms","start":"2026-02-25T01:39:12.50906Z","end":"2026-02-25T01:39:12.735577Z","steps":["trace[1147760484] 'process raft request' (duration: 226.256279ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:12.735514Z","caller":"traceutil/trace.go:171","msg":"trace[948383316] linearizableReadLoop","detail":"{readStateIndex:951; appliedIndex:950; }","duration":"219.386496ms","start":"2026-02-25T01:39:12.5161Z","end":"2026-02-25T01:39:12.735487Z","steps":["trace[948383316] 'read index received' (duration: 218.986085ms)","trace[948383316] 'applied index is now lower than readState.Index' (duration: 398.991µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:12.735866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.757416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T01:39:12.735946Z","caller":"traceutil/trace.go:171","msg":"trace[1184642532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:915; }","duration":"219.913319ms","start":"2026-02-25T01:39:12.516011Z","end":"2026-02-25T01:39:12.735925Z","steps":["trace[1184642532] 'agreement among raft nodes before linearized reading' (duration: 219.682753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:12.736055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.478915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:39:12.736093Z","caller":"traceutil/trace.go:171","msg":"trace[1510515187] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:915; }","duration":"126.61699ms","start":"2026-02-25T01:39:12.609465Z","end":"2026-02-25T01:39:12.736082Z","steps":["trace[1510515187] 'agreement among raft nodes before linearized reading' (duration: 126.434445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:12.736164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.938921ms","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-02-25T01:39:12.736159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.022337ms","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-02-25T01:39:12.736251Z","caller":"traceutil/trace.go:171","msg":"trace[1814467503] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:915; }","duration":"126.049014ms","start":"2026-02-25T01:39:12.610162Z","end":"2026-02-25T01:39:12.736211Z","steps":["trace[1814467503] 'agreement among raft nodes before linearized reading' (duration: 125.879169ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:12.736268Z","caller":"traceutil/trace.go:171","msg":"trace[455312624] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:915; }","duration":"122.137609ms","start":"2026-02-25T01:39:12.614085Z","end":"2026-02-25T01:39:12.736222Z","steps":["trace[455312624] 'agreement among raft nodes before linearized reading' (duration: 121.931854ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:15.018278Z","caller":"traceutil/trace.go:171","msg":"trace[179553175] linearizableReadLoop","detail":"{readStateIndex:958; appliedIndex:957; }","duration":"244.824635ms","start":"2026-02-25T01:39:14.773436Z","end":"2026-02-25T01:39:15.018261Z","steps":["trace[179553175] 'read index received' (duration: 244.668501ms)","trace[179553175] 'applied index is now lower than readState.Index' (duration: 155.304µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:15.01833Z","caller":"traceutil/trace.go:171","msg":"trace[1969863980] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"273.695095ms","start":"2026-02-25T01:39:14.744618Z","end":"2026-02-25T01:39:15.018313Z","steps":["trace[1969863980] 'process raft request' (duration: 273.524721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:15.018569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.034814ms","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-02-25T01:39:15.018578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.843075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T01:39:15.018606Z","caller":"traceutil/trace.go:171","msg":"trace[1373117115] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:921; }","duration":"229.898666ms","start":"2026-02-25T01:39:14.788702Z","end":"2026-02-25T01:39:15.018601Z","steps":["trace[1373117115] 'agreement among raft nodes before linearized reading' (duration: 229.783203ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:15.018607Z","caller":"traceutil/trace.go:171","msg":"trace[1944698032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:921; }","duration":"244.083595ms","start":"2026-02-25T01:39:14.774514Z","end":"2026-02-25T01:39:15.018598Z","steps":["trace[1944698032] 'agreement among raft nodes before linearized reading' (duration: 243.994662ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:15.018597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.570448ms","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-02-25T01:39:15.018572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.241312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T01:39:15.018657Z","caller":"traceutil/trace.go:171","msg":"trace[141726897] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:921; }","duration":"244.66832ms","start":"2026-02-25T01:39:14.773974Z","end":"2026-02-25T01:39:15.018643Z","steps":["trace[141726897] 'agreement among raft nodes before linearized reading' (duration: 244.468185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:15.018867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.432411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:39:15.018841Z","caller":"traceutil/trace.go:171","msg":"trace[159377883] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:921; }","duration":"243.51858ms","start":"2026-02-25T01:39:14.775276Z","end":"2026-02-25T01:39:15.018795Z","steps":["trace[159377883] 'agreement among raft nodes before linearized reading' (duration: 243.200692ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:15.018887Z","caller":"traceutil/trace.go:171","msg":"trace[2108156954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:921; }","duration":"245.492202ms","start":"2026-02-25T01:39:14.773388Z","end":"2026-02-25T01:39:15.01888Z","steps":["trace[2108156954] 'agreement among raft nodes before linearized reading' (duration: 244.959328ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:15.954026Z","caller":"traceutil/trace.go:171","msg":"trace[1530537953] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"930.585198ms","start":"2026-02-25T01:39:15.023412Z","end":"2026-02-25T01:39:15.953997Z","steps":["trace[1530537953] 'process raft request' (duration: 929.387986ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:15.954183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:15.023391Z","time spent":"930.705811ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:15.973336Z","caller":"traceutil/trace.go:171","msg":"trace[479676360] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"949.460341ms","start":"2026-02-25T01:39:15.023832Z","end":"2026-02-25T01:39:15.973292Z","steps":["trace[479676360] 'process raft request' (duration: 949.225585ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:15.973383Z","caller":"traceutil/trace.go:171","msg":"trace[1691049063] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"948.421504ms","start":"2026-02-25T01:39:15.024912Z","end":"2026-02-25T01:39:15.973333Z","steps":["trace[1691049063] 'process raft request' (duration: 948.235819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:15.973706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:15.023806Z","time spent":"949.76999ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:39:15.973837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:15.024904Z","time spent":"948.808224ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:15.973418Z","caller":"traceutil/trace.go:171","msg":"trace[828322395] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"946.957895ms","start":"2026-02-25T01:39:15.026434Z","end":"2026-02-25T01:39:15.973392Z","steps":["trace[828322395] 'process raft request' (duration: 946.753979ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:15.974032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:15.026424Z","time spent":"947.572591ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:28.437582Z","caller":"traceutil/trace.go:171","msg":"trace[238380514] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"419.235179ms","start":"2026-02-25T01:39:28.018322Z","end":"2026-02-25T01:39:28.437557Z","steps":["trace[238380514] 'read index received' (duration: 419.037484ms)","trace[238380514] 'applied index is now lower than readState.Index' (duration: 196.615µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:28.437648Z","caller":"traceutil/trace.go:171","msg":"trace[1395073920] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"427.910661ms","start":"2026-02-25T01:39:28.009702Z","end":"2026-02-25T01:39:28.437612Z","steps":["trace[1395073920] 'process raft request' (duration: 427.711225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:28.437809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:28.009684Z","time spent":"428.038674ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:39:28.43789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.498835ms","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-02-25T01:39:28.437941Z","caller":"traceutil/trace.go:171","msg":"trace[1068948864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:955; }","duration":"404.591888ms","start":"2026-02-25T01:39:28.033336Z","end":"2026-02-25T01:39:28.437928Z","steps":["trace[1068948864] 'agreement among raft nodes before linearized reading' (duration: 404.390403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:28.437985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:28.033325Z","time spent":"404.652109ms","remote":"127.0.0.1:40562","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-02-25T01:39:28.438254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.493225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T01:39:28.438345Z","caller":"traceutil/trace.go:171","msg":"trace[1668832984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"386.623139ms","start":"2026-02-25T01:39:28.051701Z","end":"2026-02-25T01:39:28.438324Z","steps":["trace[1668832984] 'agreement among raft nodes before linearized reading' (duration: 386.041913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:28.438494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:28.051691Z","time spent":"386.768312ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:39:28.438971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.609506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T01:39:28.439165Z","caller":"traceutil/trace.go:171","msg":"trace[249294661] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:955; }","duration":"420.833781ms","start":"2026-02-25T01:39:28.018297Z","end":"2026-02-25T01:39:28.439131Z","steps":["trace[249294661] 'agreement among raft nodes before linearized reading' (duration: 419.387022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:28.439255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:28.018282Z","time spent":"420.957925ms","remote":"127.0.0.1:40562","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-02-25T01:39:29.228595Z","caller":"traceutil/trace.go:171","msg":"trace[1912955146] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"118.651397ms","start":"2026-02-25T01:39:29.109927Z","end":"2026-02-25T01:39:29.228579Z","steps":["trace[1912955146] 'process raft request' (duration: 118.529953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:33.670625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.680549ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:39:33.6709Z","caller":"traceutil/trace.go:171","msg":"trace[416112764] linearizableReadLoop","detail":"{readStateIndex:1031; appliedIndex:1028; }","duration":"609.24163ms","start":"2026-02-25T01:39:33.061641Z","end":"2026-02-25T01:39:33.670883Z","steps":["trace[416112764] 'read index received' (duration: 10.214182ms)","trace[416112764] 'applied index is now lower than readState.Index' (duration: 599.026388ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:33.670989Z","caller":"traceutil/trace.go:171","msg":"trace[953624728] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"1.164446719s","start":"2026-02-25T01:39:32.50653Z","end":"2026-02-25T01:39:33.670977Z","steps":["trace[953624728] 'process raft request' (duration: 565.317528ms)","trace[953624728] 'compare' (duration: 598.459563ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:33.671056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:32.506513Z","time spent":"1.164510281s","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:33.671066Z","caller":"traceutil/trace.go:171","msg":"trace[144671699] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"1.164398587s","start":"2026-02-25T01:39:32.506634Z","end":"2026-02-25T01:39:33.671033Z","steps":["trace[144671699] 'process raft request' (duration: 1.164207602s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:33.67128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:32.506629Z","time spent":"1.16450693s","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:39:33.671508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.118065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T01:39:33.671581Z","caller":"traceutil/trace.go:171","msg":"trace[905280323] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:990; }","duration":"389.228919ms","start":"2026-02-25T01:39:33.282333Z","end":"2026-02-25T01:39:33.671562Z","steps":["trace[905280323] 'agreement among raft nodes before linearized reading' (duration: 388.91506ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:33.671605Z","caller":"traceutil/trace.go:171","msg":"trace[1321340200] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"1.165020304s","start":"2026-02-25T01:39:32.506571Z","end":"2026-02-25T01:39:33.671591Z","steps":["trace[1321340200] 'process raft request' (duration: 1.164194182s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:33.671625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:33.282321Z","time spent":"389.29514ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T01:39:33.671637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.99291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:39:33.671672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:32.506548Z","time spent":"1.165086486s","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:33.671677Z","caller":"traceutil/trace.go:171","msg":"trace[473410487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:990; }","duration":"610.069672ms","start":"2026-02-25T01:39:33.061594Z","end":"2026-02-25T01:39:33.671664Z","steps":["trace[473410487] 'agreement among raft nodes before linearized reading' (duration: 610.009641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:33.671717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:33.061574Z","time spent":"610.137275ms","remote":"127.0.0.1:40274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:39:33.671781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.002429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-25T01:39:33.671826Z","caller":"traceutil/trace.go:171","msg":"trace[924861433] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:990; }","duration":"263.089891ms","start":"2026-02-25T01:39:33.408724Z","end":"2026-02-25T01:39:33.671814Z","steps":["trace[924861433] 'agreement among raft nodes before linearized reading' (duration: 262.934607ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:33.818801Z","caller":"traceutil/trace.go:171","msg":"trace[1773791524] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"139.948845ms","start":"2026-02-25T01:39:33.678833Z","end":"2026-02-25T01:39:33.818782Z","steps":["trace[1773791524] 'process raft request' (duration: 106.861332ms)","trace[1773791524] 'compare' (duration: 32.930619ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:33.818957Z","caller":"traceutil/trace.go:171","msg":"trace[872942274] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"108.403343ms","start":"2026-02-25T01:39:33.710548Z","end":"2026-02-25T01:39:33.818951Z","steps":["trace[872942274] 'process raft request' (duration: 108.199838ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:35.741763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.590471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:39:35.74185Z","caller":"traceutil/trace.go:171","msg":"trace[39670592] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"660.93148ms","start":"2026-02-25T01:39:35.080902Z","end":"2026-02-25T01:39:35.741833Z","steps":["trace[39670592] 'process raft request' (duration: 260.212105ms)","trace[39670592] 'compare' (duration: 400.293673ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:35.741935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:35.080878Z","time spent":"661.036133ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T01:39:35.959346Z","caller":"traceutil/trace.go:171","msg":"trace[927109209] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"283.837315ms","start":"2026-02-25T01:39:35.675482Z","end":"2026-02-25T01:39:35.959319Z","steps":["trace[927109209] 'read index received' (duration: 233.084891ms)","trace[927109209] 'applied index is now lower than readState.Index' (duration: 50.750804ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:35.959434Z","caller":"traceutil/trace.go:171","msg":"trace[1374714306] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"310.374874ms","start":"2026-02-25T01:39:35.649024Z","end":"2026-02-25T01:39:35.959399Z","steps":["trace[1374714306] 'process raft request' (duration: 259.462736ms)","trace[1374714306] 'compare' (duration: 50.719313ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:35.959597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.096322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-25T01:39:35.959631Z","caller":"traceutil/trace.go:171","msg":"trace[1734345555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:997; }","duration":"284.179994ms","start":"2026-02-25T01:39:35.675441Z","end":"2026-02-25T01:39:35.959621Z","steps":["trace[1734345555] 'agreement among raft nodes before linearized reading' (duration: 283.994549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:35.959691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:35.648992Z","time spent":"310.623301ms","remote":"127.0.0.1:40356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T01:39:35.967383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.661164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-25T01:39:35.967415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.084671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-25T01:39:35.967437Z","caller":"traceutil/trace.go:171","msg":"trace[1641279369] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:998; }","duration":"143.765097ms","start":"2026-02-25T01:39:35.823662Z","end":"2026-02-25T01:39:35.967427Z","steps":["trace[1641279369] 'agreement among raft nodes before linearized reading' (duration: 143.616843ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:35.967449Z","caller":"traceutil/trace.go:171","msg":"trace[1848983940] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:998; }","duration":"221.161143ms","start":"2026-02-25T01:39:35.746279Z","end":"2026-02-25T01:39:35.96744Z","steps":["trace[1848983940] 'agreement among raft nodes before linearized reading' (duration: 221.022279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:35.967546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.666614ms","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-02-25T01:39:35.967614Z","caller":"traceutil/trace.go:171","msg":"trace[2089050583] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"220.907806ms","start":"2026-02-25T01:39:35.746695Z","end":"2026-02-25T01:39:35.967603Z","steps":["trace[2089050583] 'process raft request' (duration: 220.437174ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:35.967681Z","caller":"traceutil/trace.go:171","msg":"trace[1637551112] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:998; }","duration":"291.88541ms","start":"2026-02-25T01:39:35.675754Z","end":"2026-02-25T01:39:35.967639Z","steps":["trace[1637551112] 'agreement among raft nodes before linearized reading' (duration: 291.52018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:35.967568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.293634ms","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-02-25T01:39:35.967804Z","caller":"traceutil/trace.go:171","msg":"trace[1817107849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:998; }","duration":"291.563322ms","start":"2026-02-25T01:39:35.676221Z","end":"2026-02-25T01:39:35.967784Z","steps":["trace[1817107849] 'agreement among raft nodes before linearized reading' (duration: 291.12837ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:36.800909Z","caller":"traceutil/trace.go:171","msg":"trace[1201727222] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"264.934371ms","start":"2026-02-25T01:39:36.535944Z","end":"2026-02-25T01:39:36.800879Z","steps":["trace[1201727222] 'process raft request' (duration: 264.771897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:37.46174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:36.897663Z","time spent":"564.069265ms","remote":"127.0.0.1:56712","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-25T01:39:38.333451Z","caller":"traceutil/trace.go:171","msg":"trace[2113201657] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"299.630236ms","start":"2026-02-25T01:39:38.0338Z","end":"2026-02-25T01:39:38.33343Z","steps":["trace[2113201657] 'process raft request' (duration: 288.856599ms)","trace[2113201657] 'compare' (duration: 10.624273ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:38.333623Z","caller":"traceutil/trace.go:171","msg":"trace[1467006772] linearizableReadLoop","detail":"{readStateIndex:1051; appliedIndex:1049; }","duration":"272.094381ms","start":"2026-02-25T01:39:38.061503Z","end":"2026-02-25T01:39:38.333598Z","steps":["trace[1467006772] 'read index received' (duration: 261.20037ms)","trace[1467006772] 'applied index is now lower than readState.Index' (duration: 10.892731ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:38.333739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.230605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T01:39:38.333769Z","caller":"traceutil/trace.go:171","msg":"trace[743665920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"272.273786ms","start":"2026-02-25T01:39:38.061485Z","end":"2026-02-25T01:39:38.333759Z","steps":["trace[743665920] 'agreement among raft nodes before linearized reading' (duration: 272.194574ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:38.334016Z","caller":"traceutil/trace.go:171","msg":"trace[2072876433] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"299.990546ms","start":"2026-02-25T01:39:38.034015Z","end":"2026-02-25T01:39:38.334006Z","steps":["trace[2072876433] 'process raft request' (duration: 299.357579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:38.334093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:38.034008Z","time spent":"300.032717ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:45.690781Z","caller":"traceutil/trace.go:171","msg":"trace[1810532434] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"432.317857ms","start":"2026-02-25T01:39:45.258433Z","end":"2026-02-25T01:39:45.690751Z","steps":["trace[1810532434] 'process raft request' (duration: 432.107352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:45.690965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:45.258413Z","time spent":"432.459712ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:39:46.5338Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826906228121895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:39:47.034372Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826906228121895,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T01:39:47.407407Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.680160274s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T01:39:47.407777Z","caller":"traceutil/trace.go:171","msg":"trace[595869133] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1102; }","duration":"1.374027363s","start":"2026-02-25T01:39:46.033732Z","end":"2026-02-25T01:39:47.407759Z","steps":["trace[595869133] 'read index received' (duration: 1.373818728s)","trace[595869133] 'applied index is now lower than readState.Index' (duration: 207.785µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:47.407869Z","caller":"traceutil/trace.go:171","msg":"trace[1406662332] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"1.680734869s","start":"2026-02-25T01:39:45.727092Z","end":"2026-02-25T01:39:47.407827Z","steps":["trace[1406662332] 'process raft request' (duration: 1.680520373s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:47.407945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.374196978s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T01:39:47.407984Z","caller":"traceutil/trace.go:171","msg":"trace[1670457025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1059; }","duration":"1.37429344s","start":"2026-02-25T01:39:46.033681Z","end":"2026-02-25T01:39:47.407974Z","steps":["trace[1670457025] 'agreement among raft nodes before linearized reading' (duration: 1.374160757s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:47.408013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:46.033652Z","time spent":"1.374354292s","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T01:39:47.408023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:45.727065Z","time spent":"1.680874612s","remote":"127.0.0.1:40470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13254,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:39:47.408083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.093747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T01:39:47.408133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.752746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-25T01:39:47.408164Z","caller":"traceutil/trace.go:171","msg":"trace[615257500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"346.212411ms","start":"2026-02-25T01:39:47.06193Z","end":"2026-02-25T01:39:47.408143Z","steps":["trace[615257500] 'agreement among raft nodes before linearized reading' (duration: 346.095978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:47.408184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.025637065s","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-02-25T01:39:47.408204Z","caller":"traceutil/trace.go:171","msg":"trace[1198492286] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1059; }","duration":"542.824048ms","start":"2026-02-25T01:39:46.865361Z","end":"2026-02-25T01:39:47.408185Z","steps":["trace[1198492286] 'agreement among raft nodes before linearized reading' (duration: 542.634573ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:47.408234Z","caller":"traceutil/trace.go:171","msg":"trace[553304532] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1059; }","duration":"1.025704007s","start":"2026-02-25T01:39:46.382516Z","end":"2026-02-25T01:39:47.40822Z","steps":["trace[553304532] 'agreement among raft nodes before linearized reading' (duration: 1.025612614s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:47.408254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:46.86527Z","time spent":"542.973412ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-25T01:39:47.408253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.02698637s","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-02-25T01:39:47.408275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:46.382462Z","time spent":"1.025805099s","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T01:39:47.408312Z","caller":"traceutil/trace.go:171","msg":"trace[2099904489] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1059; }","duration":"1.027085804s","start":"2026-02-25T01:39:46.381211Z","end":"2026-02-25T01:39:47.408297Z","steps":["trace[2099904489] 'agreement among raft nodes before linearized reading' (duration: 1.0269427s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:47.40821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:47.061915Z","time spent":"346.287932ms","remote":"127.0.0.1:40274","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T01:39:47.408379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.382323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-02-25T01:39:47.408364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:46.381189Z","time spent":"1.027163325s","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T01:39:47.408435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310921819s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-25T01:39:47.408087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.368947488s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-25T01:39:47.408468Z","caller":"traceutil/trace.go:171","msg":"trace[139044603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1059; }","duration":"1.31097555s","start":"2026-02-25T01:39:46.097484Z","end":"2026-02-25T01:39:47.40846Z","steps":["trace[139044603] 'agreement among raft nodes before linearized reading' (duration: 1.310887298s)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:47.408491Z","caller":"traceutil/trace.go:171","msg":"trace[543388104] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1059; }","duration":"540.415754ms","start":"2026-02-25T01:39:46.867987Z","end":"2026-02-25T01:39:47.408403Z","steps":["trace[543388104] 'agreement among raft nodes before linearized reading' (duration: 540.327351ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:47.408496Z","caller":"traceutil/trace.go:171","msg":"trace[6241154] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1059; }","duration":"1.369376429s","start":"2026-02-25T01:39:46.0391Z","end":"2026-02-25T01:39:47.408477Z","steps":["trace[6241154] 'agreement among raft nodes before linearized reading' (duration: 1.368820684s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:47.408505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:46.097473Z","time spent":"1.311025821s","remote":"127.0.0.1:40562","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-02-25T01:39:47.408548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:46.03909Z","time spent":"1.36944418s","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-25T01:39:47.408547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:46.867951Z","time spent":"540.579018ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-25T01:39:47.755372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.600069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T01:39:47.755602Z","caller":"traceutil/trace.go:171","msg":"trace[1328215798] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"340.645641ms","start":"2026-02-25T01:39:47.414929Z","end":"2026-02-25T01:39:47.755574Z","steps":["trace[1328215798] 'process raft request' (duration: 225.762145ms)","trace[1328215798] 'compare' (duration: 114.474205ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:39:47.755905Z","caller":"traceutil/trace.go:171","msg":"trace[714170596] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"339.946233ms","start":"2026-02-25T01:39:47.41594Z","end":"2026-02-25T01:39:47.755886Z","steps":["trace[714170596] 'process raft request' (duration: 339.750608ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:47.755964Z","caller":"traceutil/trace.go:171","msg":"trace[447992367] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"340.059296ms","start":"2026-02-25T01:39:47.41588Z","end":"2026-02-25T01:39:47.755939Z","steps":["trace[447992367] 'process raft request' (duration: 339.760148ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:47.755969Z","caller":"traceutil/trace.go:171","msg":"trace[23470689] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"336.499421ms","start":"2026-02-25T01:39:47.419452Z","end":"2026-02-25T01:39:47.755951Z","steps":["trace[23470689] 'process raft request' (duration: 336.412279ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:47.755982Z","caller":"traceutil/trace.go:171","msg":"trace[1989531076] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"340.793416ms","start":"2026-02-25T01:39:47.415128Z","end":"2026-02-25T01:39:47.755921Z","steps":["trace[1989531076] 'process raft request' (duration: 340.342274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:47.756059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:47.419441Z","time spent":"336.577233ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:39:47.756075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:47.415869Z","time spent":"340.146628ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:47.756202Z","caller":"traceutil/trace.go:171","msg":"trace[1174118915] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"337.176619ms","start":"2026-02-25T01:39:47.419018Z","end":"2026-02-25T01:39:47.756195Z","steps":["trace[1174118915] 'process raft request' (duration: 336.806249ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:47.756203Z","caller":"traceutil/trace.go:171","msg":"trace[138637205] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"339.887431ms","start":"2026-02-25T01:39:47.416305Z","end":"2026-02-25T01:39:47.756193Z","steps":["trace[138637205] 'process raft request' (duration: 339.457759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:47.755998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:47.415925Z","time spent":"340.026205ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:39:47.756248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:47.419005Z","time spent":"337.22401ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:39:47.75626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:47.416297Z","time spent":"339.938113ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:39:47.756277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:47.415104Z","time spent":"340.993821ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:47.755927Z","caller":"traceutil/trace.go:171","msg":"trace[1618670442] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"340.674153ms","start":"2026-02-25T01:39:47.415216Z","end":"2026-02-25T01:39:47.75589Z","steps":["trace[1618670442] 'process raft request' (duration: 340.353094ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:39:47.756033Z","caller":"traceutil/trace.go:171","msg":"trace[354225529] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"335.364021ms","start":"2026-02-25T01:39:47.420651Z","end":"2026-02-25T01:39:47.756015Z","steps":["trace[354225529] 'process raft request' (duration: 335.252258ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:47.756499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.638401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-98rr7\" ","response":"range_response_count:1 size:13269"} {"level":"info","ts":"2026-02-25T01:39:47.75652Z","caller":"traceutil/trace.go:171","msg":"trace[558808959] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-98rr7; range_end:; response_count:1; response_revision:1070; }","duration":"340.682293ms","start":"2026-02-25T01:39:47.415832Z","end":"2026-02-25T01:39:47.756514Z","steps":["trace[558808959] 'agreement among raft nodes before linearized reading' (duration: 340.59384ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:47.756536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:47.415821Z","time spent":"340.712083ms","remote":"127.0.0.1:40470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":13292,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-provisioner-795cfbf7fb-98rr7\" "} {"level":"warn","ts":"2026-02-25T01:39:47.756596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:47.42064Z","time spent":"335.872324ms","remote":"127.0.0.1:40578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1524,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:47.756637Z","caller":"traceutil/trace.go:171","msg":"trace[445253127] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"332.601818ms","start":"2026-02-25T01:39:47.424029Z","end":"2026-02-25T01:39:47.756631Z","steps":["trace[445253127] 'process raft request' (duration: 332.32018ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:47.756669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:47.424016Z","time spent":"332.635608ms","remote":"127.0.0.1:40832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10772,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:47.756631Z","caller":"traceutil/trace.go:171","msg":"trace[1749529605] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"335.662988ms","start":"2026-02-25T01:39:47.420884Z","end":"2026-02-25T01:39:47.756547Z","steps":["trace[1749529605] 'process raft request' (duration: 335.061832ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:39:47.756747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:47.4152Z","time spent":"341.279949ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:39:47.756414Z","caller":"traceutil/trace.go:171","msg":"trace[899734776] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1103; }","duration":"340.546009ms","start":"2026-02-25T01:39:47.415862Z","end":"2026-02-25T01:39:47.756408Z","steps":["trace[899734776] 'read index received' (duration: 224.903372ms)","trace[899734776] 'applied index is now lower than readState.Index' (duration: 115.641947ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:39:47.756972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:47.420864Z","time spent":"335.904645ms","remote":"127.0.0.1:40446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T01:39:47.756991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T01:39:47.414858Z","time spent":"340.828946ms","remote":"127.0.0.1:40562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T01:40:54.285968Z","caller":"traceutil/trace.go:171","msg":"trace[1773608779] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1395; }","duration":"105.007254ms","start":"2026-02-25T01:40:54.180932Z","end":"2026-02-25T01:40:54.285939Z","steps":["trace[1773608779] 'read index received' (duration: 104.790298ms)","trace[1773608779] 'applied index is now lower than readState.Index' (duration: 215.766µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:40:54.28602Z","caller":"traceutil/trace.go:171","msg":"trace[978620604] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"106.825042ms","start":"2026-02-25T01:40:54.179157Z","end":"2026-02-25T01:40:54.285982Z","steps":["trace[978620604] 'process raft request' (duration: 106.626707ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:54.28625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.293882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T01:40:54.28631Z","caller":"traceutil/trace.go:171","msg":"trace[663173257] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1338; }","duration":"105.377773ms","start":"2026-02-25T01:40:54.180913Z","end":"2026-02-25T01:40:54.286291Z","steps":["trace[663173257] 'agreement among raft nodes before linearized reading' (duration: 105.180668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:54.286327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.006868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-25T01:40:54.286823Z","caller":"traceutil/trace.go:171","msg":"trace[1319692422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1338; }","duration":"104.10946ms","start":"2026-02-25T01:40:54.182265Z","end":"2026-02-25T01:40:54.286375Z","steps":["trace[1319692422] 'agreement among raft nodes before linearized reading' (duration: 103.906504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:40:54.286833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.723427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-25T01:40:54.286986Z","caller":"traceutil/trace.go:171","msg":"trace[2105164555] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1338; }","duration":"102.340652ms","start":"2026-02-25T01:40:54.184629Z","end":"2026-02-25T01:40:54.28697Z","steps":["trace[2105164555] 'agreement among raft nodes before linearized reading' (duration: 101.571332ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:55.096033Z","caller":"traceutil/trace.go:171","msg":"trace[97752348] linearizableReadLoop","detail":"{readStateIndex:1938; appliedIndex:1936; }","duration":"141.987283ms","start":"2026-02-25T01:42:54.954014Z","end":"2026-02-25T01:42:55.096002Z","steps":["trace[97752348] 'read index received' (duration: 22.472188ms)","trace[97752348] 'applied index is now lower than readState.Index' (duration: 119.513975ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T01:42:55.096201Z","caller":"traceutil/trace.go:171","msg":"trace[2002387761] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"167.407638ms","start":"2026-02-25T01:42:54.928774Z","end":"2026-02-25T01:42:55.096182Z","steps":["trace[2002387761] 'process raft request' (duration: 126.805637ms)","trace[2002387761] 'compare' (duration: 40.307773ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T01:42:55.096387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.346533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-02-25T01:42:55.0964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.825919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-25T01:42:55.09648Z","caller":"traceutil/trace.go:171","msg":"trace[1324904102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1855; }","duration":"142.498186ms","start":"2026-02-25T01:42:54.953961Z","end":"2026-02-25T01:42:55.096459Z","steps":["trace[1324904102] 'agreement among raft nodes before linearized reading' (duration: 142.284031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T01:42:55.096435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.666997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-02-25T01:42:55.096386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.006274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-25T01:42:55.09654Z","caller":"traceutil/trace.go:171","msg":"trace[579908562] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1855; }","duration":"138.79738ms","start":"2026-02-25T01:42:54.957719Z","end":"2026-02-25T01:42:55.096517Z","steps":["trace[579908562] 'agreement among raft nodes before linearized reading' (duration: 138.546503ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:55.096553Z","caller":"traceutil/trace.go:171","msg":"trace[578327361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1855; }","duration":"142.215269ms","start":"2026-02-25T01:42:54.954328Z","end":"2026-02-25T01:42:55.096543Z","steps":["trace[578327361] 'agreement among raft nodes before linearized reading' (duration: 141.921801ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T01:42:55.096493Z","caller":"traceutil/trace.go:171","msg":"trace[1933463144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1855; }","duration":"141.945242ms","start":"2026-02-25T01:42:54.954532Z","end":"2026-02-25T01:42:55.096477Z","steps":["trace[1933463144] 'agreement among raft nodes before linearized reading' (duration: 141.756217ms)"],"step_count":1}