{"level":"warn","ts":"2026-02-26T10:12:43.202031Z","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-26T10:12:43.202124Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.244: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.244:2380","--initial-cluster=instance=https://199.204.45.244:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.244:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.244: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-26T10:12:43.202203Z","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-26T10:12:43.20222Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-02-26T10:12:43.202242Z","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-26T10:12:43.203172Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"]} {"level":"info","ts":"2026-02-26T10:12:43.203259Z","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.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.244: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-26T10:12:43.207514Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.037803ms"} {"level":"info","ts":"2026-02-26T10:12:43.258519Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1686e9b1ea717edf","cluster-id":"71c60fe001ca5b3c"} {"level":"info","ts":"2026-02-26T10:12:43.258673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T10:12:43.258865Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 0"} {"level":"info","ts":"2026-02-26T10:12:43.258901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1686e9b1ea717edf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T10:12:43.258938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became follower at term 1"} {"level":"info","ts":"2026-02-26T10:12:43.259187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"warn","ts":"2026-02-26T10:12:43.267752Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T10:12:43.270606Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T10:12:43.272474Z","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-26T10:12:43.312949Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1686e9b1ea717edf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T10:12:43.313385Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1686e9b1ea717edf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T10:12:43.317327Z","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-26T10:12:43.317596Z","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-26T10:12:43.317624Z","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-26T10:12:43.318985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf switched to configuration voters=(1623241666065432287)"} {"level":"info","ts":"2026-02-26T10:12:43.319694Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","added-peer-id":"1686e9b1ea717edf","added-peer-peer-urls":["https://199.204.45.244:2380"]} {"level":"info","ts":"2026-02-26T10:12:43.321777Z","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-26T10:12:43.322063Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1686e9b1ea717edf","initial-advertise-peer-urls":["https://199.204.45.244:2380"],"listen-peer-urls":["https://199.204.45.244:2380"],"advertise-client-urls":["https://199.204.45.244:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.244:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T10:12:43.322124Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T10:12:43.323012Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-02-26T10:12:43.323061Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.244:2380"} {"level":"info","ts":"2026-02-26T10:12:43.360582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T10:12:43.360626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T10:12:43.36065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgPreVoteResp from 1686e9b1ea717edf at term 1"} {"level":"info","ts":"2026-02-26T10:12:43.360664Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became candidate at term 2"} {"level":"info","ts":"2026-02-26T10:12:43.360672Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf received MsgVoteResp from 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-02-26T10:12:43.360683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1686e9b1ea717edf became leader at term 2"} {"level":"info","ts":"2026-02-26T10:12:43.36069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1686e9b1ea717edf elected leader 1686e9b1ea717edf at term 2"} {"level":"info","ts":"2026-02-26T10:12:43.363326Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T10:12:43.364461Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T10:12:43.364471Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T10:12:43.364461Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1686e9b1ea717edf","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.244:2379]}","request-path":"/0/members/1686e9b1ea717edf/attributes","cluster-id":"71c60fe001ca5b3c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T10:12:43.364723Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T10:12:43.364778Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T10:12:43.365924Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"71c60fe001ca5b3c","local-member-id":"1686e9b1ea717edf","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T10:12:43.366044Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T10:12:43.366087Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T10:12:43.36763Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T10:12:43.367908Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.244:2379"} {"level":"info","ts":"2026-02-26T10:12:44.934106Z","caller":"traceutil/trace.go:171","msg":"trace[1289124505] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"111.606982ms","start":"2026-02-26T10:12:44.82247Z","end":"2026-02-26T10:12:44.934077Z","steps":["trace[1289124505] 'process raft request' (duration: 111.40615ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:12:44.934376Z","caller":"traceutil/trace.go:171","msg":"trace[2083820980] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"109.581945ms","start":"2026-02-26T10:12:44.824752Z","end":"2026-02-26T10:12:44.934333Z","steps":["trace[2083820980] 'process raft request' (duration: 109.249542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:12:45.946254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.975903ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-26T10:12:45.946375Z","caller":"traceutil/trace.go:171","msg":"trace[635253405] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"220.079387ms","start":"2026-02-26T10:12:45.726267Z","end":"2026-02-26T10:12:45.946346Z","steps":["trace[635253405] 'read index received' (duration: 20.493739ms)","trace[635253405] 'applied index is now lower than readState.Index' (duration: 199.584578ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:12:45.946414Z","caller":"traceutil/trace.go:171","msg":"trace[169318200] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"220.919694ms","start":"2026-02-26T10:12:45.725454Z","end":"2026-02-26T10:12:45.946373Z","steps":["trace[169318200] 'process raft request' (duration: 21.387137ms)","trace[169318200] 'compare' (duration: 198.857222ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:12:45.946537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.268539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T10:12:45.946574Z","caller":"traceutil/trace.go:171","msg":"trace[1272424961] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:71; }","duration":"220.33235ms","start":"2026-02-26T10:12:45.726232Z","end":"2026-02-26T10:12:45.946564Z","steps":["trace[1272424961] 'agreement among raft nodes before linearized reading' (duration: 220.208229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:12:45.946652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.568045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-26T10:12:45.94678Z","caller":"traceutil/trace.go:171","msg":"trace[2053094491] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"132.780356ms","start":"2026-02-26T10:12:45.813962Z","end":"2026-02-26T10:12:45.946742Z","steps":["trace[2053094491] 'agreement among raft nodes before linearized reading' (duration: 132.515874ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:12:48.756993Z","caller":"traceutil/trace.go:171","msg":"trace[1298490] transaction","detail":"{read_only:false; response_revision:229; number_of_response:1; }","duration":"106.856275ms","start":"2026-02-26T10:12:48.650117Z","end":"2026-02-26T10:12:48.756973Z","steps":["trace[1298490] 'process raft request' (duration: 71.723324ms)","trace[1298490] 'compare' (duration: 34.932079ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:12:58.441302Z","caller":"traceutil/trace.go:171","msg":"trace[1894024593] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"147.9967ms","start":"2026-02-26T10:12:58.293281Z","end":"2026-02-26T10:12:58.441277Z","steps":["trace[1894024593] 'read index received' (duration: 147.674448ms)","trace[1894024593] 'applied index is now lower than readState.Index' (duration: 320.982µs)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:12:58.44145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.157201ms","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-26T10:12:58.441444Z","caller":"traceutil/trace.go:171","msg":"trace[1625903227] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"332.245476ms","start":"2026-02-26T10:12:58.109161Z","end":"2026-02-26T10:12:58.441406Z","steps":["trace[1625903227] 'process raft request' (duration: 331.933354ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:12:58.441498Z","caller":"traceutil/trace.go:171","msg":"trace[1825628684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:405; }","duration":"148.291461ms","start":"2026-02-26T10:12:58.293182Z","end":"2026-02-26T10:12:58.441473Z","steps":["trace[1825628684] 'agreement among raft nodes before linearized reading' (duration: 148.201331ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:12:58.441872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:12:58.109139Z","time spent":"332.383407ms","remote":"127.0.0.1:34334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:13:02.774303Z","caller":"traceutil/trace.go:171","msg":"trace[1438604431] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"109.209413ms","start":"2026-02-26T10:13:02.665056Z","end":"2026-02-26T10:13:02.774266Z","steps":["trace[1438604431] 'process raft request' (duration: 71.90651ms)","trace[1438604431] 'compare' (duration: 37.069942ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:13:08.689708Z","caller":"traceutil/trace.go:171","msg":"trace[1697171356] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"135.540252ms","start":"2026-02-26T10:13:08.554143Z","end":"2026-02-26T10:13:08.689684Z","steps":["trace[1697171356] 'process raft request' (duration: 135.405831ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:13:14.820955Z","caller":"traceutil/trace.go:171","msg":"trace[1136876584] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"104.992368ms","start":"2026-02-26T10:13:14.715944Z","end":"2026-02-26T10:13:14.820936Z","steps":["trace[1136876584] 'process raft request' (duration: 104.877937ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:15.081582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.999043ms","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-26T10:13:15.081682Z","caller":"traceutil/trace.go:171","msg":"trace[887972426] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:509; }","duration":"167.148724ms","start":"2026-02-26T10:13:14.914512Z","end":"2026-02-26T10:13:15.08166Z","steps":["trace[887972426] 'range keys from in-memory index tree' (duration: 166.900993ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:16.248161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197951404080982,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T10:13:16.522088Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.086773392s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T10:13:16.522564Z","caller":"traceutil/trace.go:171","msg":"trace[1611738378] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"1.087302925s","start":"2026-02-26T10:13:15.435236Z","end":"2026-02-26T10:13:16.522539Z","steps":["trace[1611738378] 'process raft request' (duration: 1.087159614s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:16.522725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:13:15.435221Z","time spent":"1.087411316s","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:13:17.320033Z","caller":"traceutil/trace.go:171","msg":"trace[954431358] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:529; }","duration":"1.572842216s","start":"2026-02-26T10:13:15.747165Z","end":"2026-02-26T10:13:17.320007Z","steps":["trace[954431358] 'read index received' (duration: 775.252881ms)","trace[954431358] 'applied index is now lower than readState.Index' (duration: 797.588025ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:13:17.320059Z","caller":"traceutil/trace.go:171","msg":"trace[736502153] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"1.399284184s","start":"2026-02-26T10:13:15.920737Z","end":"2026-02-26T10:13:17.320021Z","steps":["trace[736502153] 'process raft request' (duration: 1.399047482s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:17.320228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.065786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:13:17.320263Z","caller":"traceutil/trace.go:171","msg":"trace[292311652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"873.158157ms","start":"2026-02-26T10:13:16.447095Z","end":"2026-02-26T10:13:17.320253Z","steps":["trace[292311652] 'agreement among raft nodes before linearized reading' (duration: 873.086147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:17.3203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:13:16.447075Z","time spent":"873.217537ms","remote":"127.0.0.1:34178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T10:13:17.320339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:13:15.920718Z","time spent":"1.399507356s","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:13:17.320498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.211569ms","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-26T10:13:17.320588Z","caller":"traceutil/trace.go:171","msg":"trace[525432761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:512; }","duration":"170.33462ms","start":"2026-02-26T10:13:17.150224Z","end":"2026-02-26T10:13:17.320559Z","steps":["trace[525432761] 'agreement among raft nodes before linearized reading' (duration: 170.091468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:17.320834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.419763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4375"} {"level":"info","ts":"2026-02-26T10:13:17.320924Z","caller":"traceutil/trace.go:171","msg":"trace[301218268] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:512; }","duration":"786.534454ms","start":"2026-02-26T10:13:16.534366Z","end":"2026-02-26T10:13:17.3209Z","steps":["trace[301218268] 'agreement among raft nodes before linearized reading' (duration: 786.379263ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:17.320971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:13:16.534353Z","time spent":"786.610614ms","remote":"127.0.0.1:34328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4397,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-26T10:13:17.321034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.822458ms","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-26T10:13:17.321082Z","caller":"traceutil/trace.go:171","msg":"trace[1187165322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:512; }","duration":"495.919068ms","start":"2026-02-26T10:13:16.825153Z","end":"2026-02-26T10:13:17.321072Z","steps":["trace[1187165322] 'agreement among raft nodes before linearized reading' (duration: 495.293124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:17.321119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:13:16.825131Z","time spent":"495.980978ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-26T10:13:17.321337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.574147744s","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-26T10:13:17.321873Z","caller":"traceutil/trace.go:171","msg":"trace[1410183355] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"1.574715838s","start":"2026-02-26T10:13:15.747133Z","end":"2026-02-26T10:13:17.321849Z","steps":["trace[1410183355] 'agreement among raft nodes before linearized reading' (duration: 1.572954227s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:17.322069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:13:15.747118Z","time spent":"1.574933309s","remote":"127.0.0.1:34414","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-26T10:13:18.978289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.034659ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T10:13:18.978415Z","caller":"traceutil/trace.go:171","msg":"trace[865409925] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"159.647586ms","start":"2026-02-26T10:13:18.818743Z","end":"2026-02-26T10:13:18.978391Z","steps":["trace[865409925] 'process raft request' (duration: 27.419496ms)","trace[865409925] 'compare' (duration: 131.900197ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:13:21.527398Z","caller":"traceutil/trace.go:171","msg":"trace[926663066] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"144.328105ms","start":"2026-02-26T10:13:21.383046Z","end":"2026-02-26T10:13:21.527374Z","steps":["trace[926663066] 'process raft request' (duration: 125.382725ms)","trace[926663066] 'compare' (duration: 18.735608ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:13:21.527536Z","caller":"traceutil/trace.go:171","msg":"trace[924457631] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"144.285205ms","start":"2026-02-26T10:13:21.383229Z","end":"2026-02-26T10:13:21.527514Z","steps":["trace[924457631] 'process raft request' (duration: 144.019832ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:13:23.641927Z","caller":"traceutil/trace.go:171","msg":"trace[1105245872] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"236.976987ms","start":"2026-02-26T10:13:23.40493Z","end":"2026-02-26T10:13:23.641907Z","steps":["trace[1105245872] 'read index received' (duration: 236.742616ms)","trace[1105245872] 'applied index is now lower than readState.Index' (duration: 233.471µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:13:23.642039Z","caller":"traceutil/trace.go:171","msg":"trace[1268264697] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"431.943563ms","start":"2026-02-26T10:13:23.210084Z","end":"2026-02-26T10:13:23.642028Z","steps":["trace[1268264697] 'process raft request' (duration: 431.659841ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:23.64212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.390372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:13:23.642183Z","caller":"traceutil/trace.go:171","msg":"trace[589236118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"196.487804ms","start":"2026-02-26T10:13:23.445674Z","end":"2026-02-26T10:13:23.642162Z","steps":["trace[589236118] 'agreement among raft nodes before linearized reading' (duration: 196.376343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:23.642297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.887945ms","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-26T10:13:23.642322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.967752ms","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-26T10:13:23.64223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:13:23.210067Z","time spent":"432.001933ms","remote":"127.0.0.1:34334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:13:23.642394Z","caller":"traceutil/trace.go:171","msg":"trace[965650390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"111.031927ms","start":"2026-02-26T10:13:23.531341Z","end":"2026-02-26T10:13:23.642373Z","steps":["trace[965650390] 'agreement among raft nodes before linearized reading' (duration: 110.748864ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:13:23.642404Z","caller":"traceutil/trace.go:171","msg":"trace[1193419568] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"112.097464ms","start":"2026-02-26T10:13:23.530289Z","end":"2026-02-26T10:13:23.642386Z","steps":["trace[1193419568] 'agreement among raft nodes before linearized reading' (duration: 111.831822ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:23.642449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.511981ms","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-26T10:13:23.642306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.547378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:13:23.642518Z","caller":"traceutil/trace.go:171","msg":"trace[489935392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"173.824571ms","start":"2026-02-26T10:13:23.468685Z","end":"2026-02-26T10:13:23.642509Z","steps":["trace[489935392] 'agreement among raft nodes before linearized reading' (duration: 173.487678ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:13:23.642524Z","caller":"traceutil/trace.go:171","msg":"trace[1403525159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"237.622562ms","start":"2026-02-26T10:13:23.404883Z","end":"2026-02-26T10:13:23.642506Z","steps":["trace[1403525159] 'agreement among raft nodes before linearized reading' (duration: 237.365499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:24.947959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197951404081069,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-26T10:13:25.065628Z","caller":"traceutil/trace.go:171","msg":"trace[436966762] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:576; }","duration":"618.259935ms","start":"2026-02-26T10:13:24.447341Z","end":"2026-02-26T10:13:25.065601Z","steps":["trace[436966762] 'read index received' (duration: 555.321333ms)","trace[436966762] 'applied index is now lower than readState.Index' (duration: 62.937212ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:13:25.065898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.527977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:13:25.065994Z","caller":"traceutil/trace.go:171","msg":"trace[922237178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"618.682407ms","start":"2026-02-26T10:13:24.447284Z","end":"2026-02-26T10:13:25.065966Z","steps":["trace[922237178] 'agreement among raft nodes before linearized reading' (duration: 618.526456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:25.066043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:13:24.447258Z","time spent":"618.771908ms","remote":"127.0.0.1:34194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T10:13:25.066164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.649625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-26T10:13:25.066275Z","caller":"traceutil/trace.go:171","msg":"trace[677648569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"517.839187ms","start":"2026-02-26T10:13:24.548412Z","end":"2026-02-26T10:13:25.066252Z","steps":["trace[677648569] 'agreement among raft nodes before linearized reading' (duration: 517.505155ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:25.066321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:13:24.548384Z","time spent":"517.928737ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T10:13:25.392904Z","caller":"traceutil/trace.go:171","msg":"trace[784824039] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"321.106639ms","start":"2026-02-26T10:13:25.071747Z","end":"2026-02-26T10:13:25.392854Z","steps":["trace[784824039] 'process raft request' (duration: 320.956208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:25.39307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:13:25.071716Z","time spent":"321.25753ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:13:26.22352Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197951404081079,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T10:13:26.724242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197951404081079,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T10:13:27.224389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197951404081079,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T10:13:27.724952Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9142197951404081079,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T10:13:27.751986Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.066734829s","expected-duration":"1s"} {"level":"info","ts":"2026-02-26T10:13:27.752287Z","caller":"traceutil/trace.go:171","msg":"trace[1144177349] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"2.06708859s","start":"2026-02-26T10:13:25.685181Z","end":"2026-02-26T10:13:27.75227Z","steps":["trace[1144177349] 'process raft request' (duration: 2.06699079s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:27.752379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:13:25.685172Z","time spent":"2.067147771s","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T10:13:28.025815Z","caller":"traceutil/trace.go:171","msg":"trace[468218203] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:579; }","duration":"2.302336731s","start":"2026-02-26T10:13:25.723454Z","end":"2026-02-26T10:13:28.02579Z","steps":["trace[468218203] 'read index received' (duration: 2.028958683s)","trace[468218203] 'applied index is now lower than readState.Index' (duration: 273.376928ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:13:28.025945Z","caller":"traceutil/trace.go:171","msg":"trace[1421473007] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"535.88357ms","start":"2026-02-26T10:13:27.490032Z","end":"2026-02-26T10:13:28.025916Z","steps":["trace[1421473007] 'process raft request' (duration: 462.176505ms)","trace[1421473007] 'compare' (duration: 73.436113ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T10:13:28.026044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.468128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-26T10:13:28.026062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:13:27.489995Z","time spent":"536.007491ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T10:13:28.026031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.302561673s","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-26T10:13:28.026081Z","caller":"traceutil/trace.go:171","msg":"trace[369453578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:559; }","duration":"629.556049ms","start":"2026-02-26T10:13:27.396517Z","end":"2026-02-26T10:13:28.026073Z","steps":["trace[369453578] 'agreement among raft nodes before linearized reading' (duration: 629.476218ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:28.026104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:13:27.396494Z","time spent":"629.604479ms","remote":"127.0.0.1:34414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-26T10:13:28.026106Z","caller":"traceutil/trace.go:171","msg":"trace[1671720255] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:559; }","duration":"2.302692384s","start":"2026-02-26T10:13:25.723402Z","end":"2026-02-26T10:13:28.026094Z","steps":["trace[1671720255] 'agreement among raft nodes before linearized reading' (duration: 2.302536383s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:28.026146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:13:25.723384Z","time spent":"2.302752704s","remote":"127.0.0.1:34414","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-26T10:13:28.02606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.301892178s","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-26T10:13:28.026376Z","caller":"traceutil/trace.go:171","msg":"trace[1549773474] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:559; }","duration":"2.302066349s","start":"2026-02-26T10:13:25.724136Z","end":"2026-02-26T10:13:28.026202Z","steps":["trace[1549773474] 'agreement among raft nodes before linearized reading' (duration: 2.301854227s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:28.026411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:13:25.724131Z","time spent":"2.302272141s","remote":"127.0.0.1:34414","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-26T10:13:28.02704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580325496s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:13:28.027143Z","caller":"traceutil/trace.go:171","msg":"trace[1479724739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:559; }","duration":"1.580446907s","start":"2026-02-26T10:13:26.446676Z","end":"2026-02-26T10:13:28.027123Z","steps":["trace[1479724739] 'agreement among raft nodes before linearized reading' (duration: 1.579412s)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:13:28.027189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T10:13:26.446617Z","time spent":"1.580563157s","remote":"127.0.0.1:34178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-26T10:13:29.717265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.62374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T10:13:29.717568Z","caller":"traceutil/trace.go:171","msg":"trace[105555817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"269.956824ms","start":"2026-02-26T10:13:29.447582Z","end":"2026-02-26T10:13:29.717539Z","steps":["trace[105555817] 'range keys from in-memory index tree' (duration: 269.472948ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:13:52.431856Z","caller":"traceutil/trace.go:171","msg":"trace[1897116361] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"190.567092ms","start":"2026-02-26T10:13:52.241241Z","end":"2026-02-26T10:13:52.431809Z","steps":["trace[1897116361] 'process raft request' (duration: 123.150432ms)","trace[1897116361] 'compare' (duration: 67.18254ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:13:52.431913Z","caller":"traceutil/trace.go:171","msg":"trace[1775090080] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"189.981407ms","start":"2026-02-26T10:13:52.241888Z","end":"2026-02-26T10:13:52.431869Z","steps":["trace[1775090080] 'process raft request' (duration: 189.802009ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:14:48.859987Z","caller":"traceutil/trace.go:171","msg":"trace[1536955051] linearizableReadLoop","detail":"{readStateIndex:1005; appliedIndex:1004; }","duration":"147.561756ms","start":"2026-02-26T10:14:48.712385Z","end":"2026-02-26T10:14:48.859947Z","steps":["trace[1536955051] 'read index received' (duration: 146.992162ms)","trace[1536955051] 'applied index is now lower than readState.Index' (duration: 562.784µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T10:14:48.860031Z","caller":"traceutil/trace.go:171","msg":"trace[1523108528] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"154.059999ms","start":"2026-02-26T10:14:48.705903Z","end":"2026-02-26T10:14:48.859963Z","steps":["trace[1523108528] 'process raft request' (duration: 153.808642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T10:14:48.860258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.821793ms","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-26T10:14:48.860317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.812893ms","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-26T10:14:48.860353Z","caller":"traceutil/trace.go:171","msg":"trace[843389778] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:964; }","duration":"147.982527ms","start":"2026-02-26T10:14:48.712354Z","end":"2026-02-26T10:14:48.860336Z","steps":["trace[843389778] 'agreement among raft nodes before linearized reading' (duration: 147.657289ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T10:14:48.860364Z","caller":"traceutil/trace.go:171","msg":"trace[1036786297] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:964; }","duration":"113.899534ms","start":"2026-02-26T10:14:48.746453Z","end":"2026-02-26T10:14:48.860352Z","steps":["trace[1036786297] 'agreement among raft nodes before linearized reading' (duration: 113.703709ms)"],"step_count":1}