{"level":"warn","ts":"2026-02-18T14:40:34.973289Z","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-18T14:40:34.973455Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.212:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.212:2380","--initial-cluster=instance=https://162.253.55.212:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.212:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.212: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-18T14:40:34.97357Z","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-18T14:40:34.973597Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-18T14:40:34.973627Z","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-18T14:40:34.974297Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"]} {"level":"info","ts":"2026-02-18T14:40:34.974427Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.212: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-18T14:40:34.992655Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"17.918313ms"} {"level":"info","ts":"2026-02-18T14:40:35.034215Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d078b1e39c21939c","cluster-id":"cdc9ba8de57e3589"} {"level":"info","ts":"2026-02-18T14:40:35.034522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=()"} {"level":"info","ts":"2026-02-18T14:40:35.034604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 0"} {"level":"info","ts":"2026-02-18T14:40:35.034683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d078b1e39c21939c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-18T14:40:35.034729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became follower at term 1"} {"level":"info","ts":"2026-02-18T14:40:35.035032Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"warn","ts":"2026-02-18T14:40:35.045805Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-18T14:40:35.073203Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-18T14:40:35.079302Z","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-18T14:40:35.085158Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d078b1e39c21939c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-18T14:40:35.086829Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d078b1e39c21939c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-18T14:40:35.087299Z","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-18T14:40:35.087493Z","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-18T14:40:35.087526Z","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-18T14:40:35.087983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c switched to configuration voters=(15021952148229428124)"} {"level":"info","ts":"2026-02-18T14:40:35.088163Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","added-peer-id":"d078b1e39c21939c","added-peer-peer-urls":["https://162.253.55.212:2380"]} {"level":"info","ts":"2026-02-18T14:40:35.099369Z","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-18T14:40:35.099716Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d078b1e39c21939c","initial-advertise-peer-urls":["https://162.253.55.212:2380"],"listen-peer-urls":["https://162.253.55.212:2380"],"advertise-client-urls":["https://162.253.55.212:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.212:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-18T14:40:35.099806Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-18T14:40:35.099972Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-18T14:40:35.100031Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.212:2380"} {"level":"info","ts":"2026-02-18T14:40:35.235426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c is starting a new election at term 1"} {"level":"info","ts":"2026-02-18T14:40:35.235609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became pre-candidate at term 1"} {"level":"info","ts":"2026-02-18T14:40:35.235674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgPreVoteResp from d078b1e39c21939c at term 1"} {"level":"info","ts":"2026-02-18T14:40:35.235737Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became candidate at term 2"} {"level":"info","ts":"2026-02-18T14:40:35.235774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c received MsgVoteResp from d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-18T14:40:35.236022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d078b1e39c21939c became leader at term 2"} {"level":"info","ts":"2026-02-18T14:40:35.2361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d078b1e39c21939c elected leader d078b1e39c21939c at term 2"} {"level":"info","ts":"2026-02-18T14:40:35.244107Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d078b1e39c21939c","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.212:2379]}","request-path":"/0/members/d078b1e39c21939c/attributes","cluster-id":"cdc9ba8de57e3589","publish-timeout":"7s"} {"level":"info","ts":"2026-02-18T14:40:35.244285Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T14:40:35.24465Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:40:35.247129Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-18T14:40:35.247434Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.212:2379"} {"level":"info","ts":"2026-02-18T14:40:35.248508Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdc9ba8de57e3589","local-member-id":"d078b1e39c21939c","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:40:35.249309Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:40:35.249377Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-18T14:40:35.250706Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-18T14:40:35.250742Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-18T14:40:35.257438Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-18T14:40:39.246037Z","caller":"traceutil/trace.go:171","msg":"trace[1329858764] linearizableReadLoop","detail":"{readStateIndex:248; appliedIndex:246; }","duration":"126.621018ms","start":"2026-02-18T14:40:39.119396Z","end":"2026-02-18T14:40:39.246017Z","steps":["trace[1329858764] 'read index received' (duration: 55.466492ms)","trace[1329858764] 'applied index is now lower than readState.Index' (duration: 71.153666ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:39.246066Z","caller":"traceutil/trace.go:171","msg":"trace[30843704] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"168.19103ms","start":"2026-02-18T14:40:39.077834Z","end":"2026-02-18T14:40:39.246025Z","steps":["trace[30843704] 'process raft request' (duration: 96.967533ms)","trace[30843704] 'compare' (duration: 71.036964ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:39.246368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.947354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:39.246458Z","caller":"traceutil/trace.go:171","msg":"trace[1352238796] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:0; response_revision:243; }","duration":"127.082867ms","start":"2026-02-18T14:40:39.119362Z","end":"2026-02-18T14:40:39.246445Z","steps":["trace[1352238796] 'agreement among raft nodes before linearized reading' (duration: 126.747281ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:39.246949Z","caller":"traceutil/trace.go:171","msg":"trace[661505236] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"131.979952ms","start":"2026-02-18T14:40:39.114955Z","end":"2026-02-18T14:40:39.246935Z","steps":["trace[661505236] 'process raft request' (duration: 131.016966ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:39.428828Z","caller":"traceutil/trace.go:171","msg":"trace[1429258181] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"104.942592ms","start":"2026-02-18T14:40:39.323869Z","end":"2026-02-18T14:40:39.428812Z","steps":["trace[1429258181] 'process raft request' (duration: 104.912782ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:39.428854Z","caller":"traceutil/trace.go:171","msg":"trace[1231472468] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"105.061974ms","start":"2026-02-18T14:40:39.323765Z","end":"2026-02-18T14:40:39.428827Z","steps":["trace[1231472468] 'process raft request' (duration: 38.384945ms)","trace[1231472468] 'compare' (duration: 66.533706ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:45.016175Z","caller":"traceutil/trace.go:171","msg":"trace[363926495] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"121.042108ms","start":"2026-02-18T14:40:44.895112Z","end":"2026-02-18T14:40:45.016154Z","steps":["trace[363926495] 'process raft request' (duration: 82.89533ms)","trace[363926495] 'compare' (duration: 38.052807ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:45.523053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.19479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-18T14:40:45.523253Z","caller":"traceutil/trace.go:171","msg":"trace[221581398] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:340; }","duration":"127.418905ms","start":"2026-02-18T14:40:45.395802Z","end":"2026-02-18T14:40:45.523221Z","steps":["trace[221581398] 'range keys from in-memory index tree' (duration: 127.079728ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:45.523556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.443584ms","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-18T14:40:45.523609Z","caller":"traceutil/trace.go:171","msg":"trace[1915086849] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:340; }","duration":"228.539595ms","start":"2026-02-18T14:40:45.295058Z","end":"2026-02-18T14:40:45.523598Z","steps":["trace[1915086849] 'range keys from in-memory index tree' (duration: 228.370783ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:45.523753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.371951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-18T14:40:45.523779Z","caller":"traceutil/trace.go:171","msg":"trace[1631076004] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:340; }","duration":"178.452913ms","start":"2026-02-18T14:40:45.345319Z","end":"2026-02-18T14:40:45.523772Z","steps":["trace[1631076004] 'range keys from in-memory index tree' (duration: 178.26895ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:45.716923Z","caller":"traceutil/trace.go:171","msg":"trace[1495924305] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"172.240087ms","start":"2026-02-18T14:40:45.544663Z","end":"2026-02-18T14:40:45.716903Z","steps":["trace[1495924305] 'read index received' (duration: 46.41062ms)","trace[1495924305] 'applied index is now lower than readState.Index' (duration: 125.828457ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:40:45.717284Z","caller":"traceutil/trace.go:171","msg":"trace[239306054] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"185.398432ms","start":"2026-02-18T14:40:45.531871Z","end":"2026-02-18T14:40:45.71727Z","steps":["trace[239306054] 'process raft request' (duration: 184.615599ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:45.717764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.093811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-18T14:40:45.71798Z","caller":"traceutil/trace.go:171","msg":"trace[1983929352] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:342; }","duration":"173.350175ms","start":"2026-02-18T14:40:45.54462Z","end":"2026-02-18T14:40:45.71797Z","steps":["trace[1983929352] 'agreement among raft nodes before linearized reading' (duration: 173.02688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:45.718156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.454045ms","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-18T14:40:45.718216Z","caller":"traceutil/trace.go:171","msg":"trace[2038687779] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:342; }","duration":"152.539446ms","start":"2026-02-18T14:40:45.565669Z","end":"2026-02-18T14:40:45.718209Z","steps":["trace[2038687779] 'agreement among raft nodes before linearized reading' (duration: 152.458965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:45.718511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.837838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-18T14:40:45.719149Z","caller":"traceutil/trace.go:171","msg":"trace[1181433385] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:342; }","duration":"122.496929ms","start":"2026-02-18T14:40:45.596643Z","end":"2026-02-18T14:40:45.71914Z","steps":["trace[1181433385] 'agreement among raft nodes before linearized reading' (duration: 121.848628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:45.7194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.652736ms","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-18T14:40:45.719695Z","caller":"traceutil/trace.go:171","msg":"trace[300576240] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:342; }","duration":"153.943312ms","start":"2026-02-18T14:40:45.565717Z","end":"2026-02-18T14:40:45.71966Z","steps":["trace[300576240] 'agreement among raft nodes before linearized reading' (duration: 153.30847ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:40:46.03749Z","caller":"traceutil/trace.go:171","msg":"trace[1470933012] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"126.361285ms","start":"2026-02-18T14:40:45.911111Z","end":"2026-02-18T14:40:46.037472Z","steps":["trace[1470933012] 'process raft request' (duration: 125.493401ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:46.595651Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548429928128044,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T14:40:47.085954Z","caller":"traceutil/trace.go:171","msg":"trace[2121100071] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"1.031655532s","start":"2026-02-18T14:40:46.054282Z","end":"2026-02-18T14:40:47.085938Z","steps":["trace[2121100071] 'process raft request' (duration: 1.031615581s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:47.086336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.054276Z","time spent":"1.031727513s","remote":"127.0.0.1:52974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3669,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:47.086491Z","caller":"traceutil/trace.go:171","msg":"trace[1348487223] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"1.035321725s","start":"2026-02-18T14:40:46.051161Z","end":"2026-02-18T14:40:47.086483Z","steps":["trace[1348487223] 'process raft request' (duration: 1.013591341s)","trace[1348487223] 'compare' (duration: 20.97019ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:47.086527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.051155Z","time spent":"1.035357865s","remote":"127.0.0.1:52580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T14:40:47.086641Z","caller":"traceutil/trace.go:171","msg":"trace[493540154] transaction","detail":"{read_only:false; response_revision:350; number_of_response:1; }","duration":"1.034428539s","start":"2026-02-18T14:40:46.052207Z","end":"2026-02-18T14:40:47.086635Z","steps":["trace[493540154] 'process raft request' (duration: 1.033611375s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:47.092145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.052198Z","time spent":"1.039843583s","remote":"127.0.0.1:52720","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":156,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T14:40:47.090108Z","caller":"traceutil/trace.go:171","msg":"trace[1290393411] linearizableReadLoop","detail":"{readStateIndex:363; appliedIndex:359; }","duration":"995.069012ms","start":"2026-02-18T14:40:46.095028Z","end":"2026-02-18T14:40:47.090097Z","steps":["trace[1290393411] 'read index received' (duration: 969.733426ms)","trace[1290393411] 'applied index is now lower than readState.Index' (duration: 25.335076ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:47.090196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"995.158433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-18T14:40:47.09035Z","caller":"traceutil/trace.go:171","msg":"trace[379493411] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"997.564205ms","start":"2026-02-18T14:40:46.092779Z","end":"2026-02-18T14:40:47.090344Z","steps":["trace[379493411] 'process raft request' (duration: 997.27629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:47.098899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.092767Z","time spent":"1.006094442s","remote":"127.0.0.1:53002","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2125,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T14:40:47.095849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"949.60866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-18T14:40:47.099098Z","caller":"traceutil/trace.go:171","msg":"trace[519412925] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:352; }","duration":"952.891567ms","start":"2026-02-18T14:40:46.146193Z","end":"2026-02-18T14:40:47.099085Z","steps":["trace[519412925] 'agreement among raft nodes before linearized reading' (duration: 949.58708ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:47.099165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.14618Z","time spent":"952.956398ms","remote":"127.0.0.1:52720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":233,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"warn","ts":"2026-02-18T14:40:47.095966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.598818ms","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-18T14:40:47.099342Z","caller":"traceutil/trace.go:171","msg":"trace[542551415] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:352; }","duration":"754.020927ms","start":"2026-02-18T14:40:46.345313Z","end":"2026-02-18T14:40:47.099334Z","steps":["trace[542551415] 'agreement among raft nodes before linearized reading' (duration: 750.633149ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:47.099387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.345247Z","time spent":"754.130829ms","remote":"127.0.0.1:52720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-02-18T14:40:47.095995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.810515ms","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-18T14:40:47.099596Z","caller":"traceutil/trace.go:171","msg":"trace[1875747270] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:352; }","duration":"803.448946ms","start":"2026-02-18T14:40:46.29614Z","end":"2026-02-18T14:40:47.099589Z","steps":["trace[1875747270] 'agreement among raft nodes before linearized reading' (duration: 799.837134ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:47.09964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.296119Z","time spent":"803.513148ms","remote":"127.0.0.1:52720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":217,"request content":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" "} {"level":"warn","ts":"2026-02-18T14:40:47.096021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.663171ms","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-18T14:40:47.099874Z","caller":"traceutil/trace.go:171","msg":"trace[2026899973] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller; range_end:; response_count:1; response_revision:352; }","duration":"853.539397ms","start":"2026-02-18T14:40:46.246328Z","end":"2026-02-18T14:40:47.099867Z","steps":["trace[2026899973] 'agreement among raft nodes before linearized reading' (duration: 849.677321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:47.099919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.246311Z","time spent":"853.598719ms","remote":"127.0.0.1:52720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":258,"request content":"key:\"/registry/serviceaccounts/kube-system/clusterrole-aggregation-controller\" "} {"level":"warn","ts":"2026-02-18T14:40:47.096057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"900.238711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-18T14:40:47.10015Z","caller":"traceutil/trace.go:171","msg":"trace[1949512448] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:352; }","duration":"904.35262ms","start":"2026-02-18T14:40:46.19579Z","end":"2026-02-18T14:40:47.100143Z","steps":["trace[1949512448] 'agreement among raft nodes before linearized reading' (duration: 900.249311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:47.100201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.195776Z","time spent":"904.416462ms","remote":"127.0.0.1:52720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-02-18T14:40:47.09879Z","caller":"traceutil/trace.go:171","msg":"trace[1095191647] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:352; }","duration":"1.003762301s","start":"2026-02-18T14:40:46.095012Z","end":"2026-02-18T14:40:47.098774Z","steps":["trace[1095191647] 'agreement among raft nodes before linearized reading' (duration: 995.118823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:47.100587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:46.095007Z","time spent":"1.005568863s","remote":"127.0.0.1:52720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":221,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-02-18T14:40:54.110866Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548429928128146,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:40:54.422322Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.21445272s","expected-duration":"1s"} {"level":"info","ts":"2026-02-18T14:40:54.42304Z","caller":"traceutil/trace.go:171","msg":"trace[1109662891] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"1.215250493s","start":"2026-02-18T14:40:53.207773Z","end":"2026-02-18T14:40:54.423024Z","steps":["trace[1109662891] 'process raft request' (duration: 1.214733834s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:54.423157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:53.207755Z","time spent":"1.215334025s","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:54.423429Z","caller":"traceutil/trace.go:171","msg":"trace[884999054] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"812.719474ms","start":"2026-02-18T14:40:53.609905Z","end":"2026-02-18T14:40:54.422625Z","steps":["trace[884999054] 'read index received' (duration: 812.549551ms)","trace[884999054] 'applied index is now lower than readState.Index' (duration: 167.363µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:54.423882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.505372ms","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-18T14:40:54.423922Z","caller":"traceutil/trace.go:171","msg":"trace[630473158] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:415; }","duration":"788.581812ms","start":"2026-02-18T14:40:53.635327Z","end":"2026-02-18T14:40:54.423909Z","steps":["trace[630473158] 'agreement among raft nodes before linearized reading' (duration: 788.510071ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:54.423955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:53.635306Z","time spent":"788.640254ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T14:40:54.424413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.104623ms","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-18T14:40:54.424445Z","caller":"traceutil/trace.go:171","msg":"trace[1725406832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"655.159785ms","start":"2026-02-18T14:40:53.769275Z","end":"2026-02-18T14:40:54.424435Z","steps":["trace[1725406832] 'agreement among raft nodes before linearized reading' (duration: 655.107514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:54.424472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:53.769265Z","time spent":"655.200705ms","remote":"127.0.0.1:52774","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-18T14:40:54.424596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.450096ms","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-18T14:40:54.424622Z","caller":"traceutil/trace.go:171","msg":"trace[1891445815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:415; }","duration":"660.508776ms","start":"2026-02-18T14:40:53.764106Z","end":"2026-02-18T14:40:54.424615Z","steps":["trace[1891445815] 'agreement among raft nodes before linearized reading' (duration: 660.465895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:54.424654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:53.764086Z","time spent":"660.562137ms","remote":"127.0.0.1:52774","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-18T14:40:54.423527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.62443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:54.425243Z","caller":"traceutil/trace.go:171","msg":"trace[1949863154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:415; }","duration":"815.348659ms","start":"2026-02-18T14:40:53.609851Z","end":"2026-02-18T14:40:54.4252Z","steps":["trace[1949863154] 'agreement among raft nodes before linearized reading' (duration: 813.64583ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:54.425375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:53.609831Z","time spent":"815.501781ms","remote":"127.0.0.1:52502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:40:54.941555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548429928128150,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T14:40:55.202775Z","caller":"traceutil/trace.go:171","msg":"trace[565176907] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"768.619302ms","start":"2026-02-18T14:40:54.434132Z","end":"2026-02-18T14:40:55.202752Z","steps":["trace[565176907] 'process raft request' (duration: 764.394929ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:55.202979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:54.434122Z","time spent":"768.778554ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:55.212256Z","caller":"traceutil/trace.go:171","msg":"trace[994509726] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"771.575131ms","start":"2026-02-18T14:40:54.440671Z","end":"2026-02-18T14:40:55.212246Z","steps":["trace[994509726] 'process raft request' (duration: 771.299496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:55.212341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:54.440665Z","time spent":"771.619812ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:55.212595Z","caller":"traceutil/trace.go:171","msg":"trace[598135307] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"770.90607ms","start":"2026-02-18T14:40:54.441679Z","end":"2026-02-18T14:40:55.212585Z","steps":["trace[598135307] 'process raft request' (duration: 770.360761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:55.212664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:54.441668Z","time spent":"770.95198ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:55.212601Z","caller":"traceutil/trace.go:171","msg":"trace[668653074] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"773.195939ms","start":"2026-02-18T14:40:54.439376Z","end":"2026-02-18T14:40:55.212572Z","steps":["trace[668653074] 'process raft request' (duration: 772.543389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:55.213028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:54.439367Z","time spent":"773.575106ms","remote":"127.0.0.1:52974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:55.212849Z","caller":"traceutil/trace.go:171","msg":"trace[201796087] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"765.800583ms","start":"2026-02-18T14:40:54.44704Z","end":"2026-02-18T14:40:55.212841Z","steps":["trace[201796087] 'process raft request' (duration: 765.02381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:55.213418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:54.447024Z","time spent":"766.339833ms","remote":"127.0.0.1:52698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7222,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:40:55.219228Z","caller":"traceutil/trace.go:171","msg":"trace[218383110] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:428; }","duration":"778.580691ms","start":"2026-02-18T14:40:54.44063Z","end":"2026-02-18T14:40:55.219211Z","steps":["trace[218383110] 'read index received' (duration: 757.907349ms)","trace[218383110] 'applied index is now lower than readState.Index' (duration: 20.672252ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:40:55.219626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.949227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:40:55.219673Z","caller":"traceutil/trace.go:171","msg":"trace[1749178332] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"779.049329ms","start":"2026-02-18T14:40:54.440612Z","end":"2026-02-18T14:40:55.219661Z","steps":["trace[1749178332] 'agreement among raft nodes before linearized reading' (duration: 778.662542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:40:55.220226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:40:54.440604Z","time spent":"779.260802ms","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:41:09.45631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.587559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T14:41:09.456364Z","caller":"traceutil/trace.go:171","msg":"trace[586537899] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:512; }","duration":"491.671301ms","start":"2026-02-18T14:41:08.964681Z","end":"2026-02-18T14:41:09.456353Z","steps":["trace[586537899] 'range keys from in-memory index tree' (duration: 491.528527ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:09.456382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:08.964665Z","time spent":"491.713001ms","remote":"127.0.0.1:52540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.212\" "} {"level":"warn","ts":"2026-02-18T14:41:09.456621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.675198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-18T14:41:09.456665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.222265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:41:09.456684Z","caller":"traceutil/trace.go:171","msg":"trace[1932779463] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:512; }","duration":"331.265165ms","start":"2026-02-18T14:41:09.125413Z","end":"2026-02-18T14:41:09.456679Z","steps":["trace[1932779463] 'range keys from in-memory index tree' (duration: 331.177234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:09.456696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:09.125399Z","time spent":"331.294136ms","remote":"127.0.0.1:52502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T14:41:09.456694Z","caller":"traceutil/trace.go:171","msg":"trace[17868902] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:512; }","duration":"206.79462ms","start":"2026-02-18T14:41:09.249882Z","end":"2026-02-18T14:41:09.456677Z","steps":["trace[17868902] 'range keys from in-memory index tree' (duration: 206.533445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:17.625441Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548429928128431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:41:18.126426Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548429928128431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:41:18.627126Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548429928128431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:41:19.125839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000430309s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-18T14:41:19.126081Z","caller":"traceutil/trace.go:171","msg":"trace[428350979] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000715925s","start":"2026-02-18T14:41:17.125352Z","end":"2026-02-18T14:41:19.126068Z","steps":["trace[428350979] 'agreement among raft nodes before linearized reading' (duration: 2.00045498s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:19.126125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:17.125338Z","time spent":"2.000777926s","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/18 14:41:19 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-18T14:41:19.128887Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548429928128431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:41:19.515666Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.460407498s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-18T14:41:19.575539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.35355998s","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-18T14:41:19.57561Z","caller":"traceutil/trace.go:171","msg":"trace[979026090] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:555; }","duration":"1.353638201s","start":"2026-02-18T14:41:18.221962Z","end":"2026-02-18T14:41:19.5756Z","steps":["trace[979026090] 'range keys from in-memory index tree' (duration: 1.353548969s)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:41:19.575724Z","caller":"traceutil/trace.go:171","msg":"trace[311732660] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"2.520550576s","start":"2026-02-18T14:41:17.055146Z","end":"2026-02-18T14:41:19.575697Z","steps":["trace[311732660] 'process raft request' (duration: 2.460785166s)","trace[311732660] 'compare' (duration: 58.915644ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:41:19.575848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:17.055119Z","time spent":"2.520660088s","remote":"127.0.0.1:52698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:41:19.664174Z","caller":"traceutil/trace.go:171","msg":"trace[720511449] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"1.873815271s","start":"2026-02-18T14:41:17.790339Z","end":"2026-02-18T14:41:19.664154Z","steps":["trace[720511449] 'process raft request' (duration: 1.873684569s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:19.664306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:17.79032Z","time spent":"1.873928043s","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:41:19.664372Z","caller":"traceutil/trace.go:171","msg":"trace[581478043] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:574; }","duration":"2.538969703s","start":"2026-02-18T14:41:17.125383Z","end":"2026-02-18T14:41:19.664353Z","steps":["trace[581478043] 'read index received' (duration: 2.390565223s)","trace[581478043] 'applied index is now lower than readState.Index' (duration: 148.40365ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:41:19.664447Z","caller":"traceutil/trace.go:171","msg":"trace[952320441] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"535.606546ms","start":"2026-02-18T14:41:19.128834Z","end":"2026-02-18T14:41:19.664441Z","steps":["trace[952320441] 'process raft request' (duration: 535.28714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:19.664509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:19.128813Z","time spent":"535.650266ms","remote":"127.0.0.1:52580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-18T14:41:19.664829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.119106196s","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-18T14:41:19.664853Z","caller":"traceutil/trace.go:171","msg":"trace[1047215947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:558; }","duration":"2.119153327s","start":"2026-02-18T14:41:17.545693Z","end":"2026-02-18T14:41:19.664846Z","steps":["trace[1047215947] 'agreement among raft nodes before linearized reading' (duration: 2.118875861s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:19.664873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:17.54568Z","time spent":"2.119188547s","remote":"127.0.0.1:52774","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-18T14:41:19.665002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.103995977s","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-18T14:41:19.665004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.182033ms","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-18T14:41:19.665021Z","caller":"traceutil/trace.go:171","msg":"trace[1076744312] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:558; }","duration":"2.104037767s","start":"2026-02-18T14:41:17.560978Z","end":"2026-02-18T14:41:19.665016Z","steps":["trace[1076744312] 'agreement among raft nodes before linearized reading' (duration: 2.103965596s)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:41:19.665034Z","caller":"traceutil/trace.go:171","msg":"trace[1005221924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:558; }","duration":"905.254505ms","start":"2026-02-18T14:41:18.759773Z","end":"2026-02-18T14:41:19.665027Z","steps":["trace[1005221924] 'agreement among raft nodes before linearized reading' (duration: 905.138673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:19.665038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:17.560967Z","time spent":"2.104067247s","remote":"127.0.0.1:52774","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-18T14:41:19.665056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:18.759755Z","time spent":"905.295876ms","remote":"127.0.0.1:52774","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-18T14:41:19.665115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.938533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:41:19.66513Z","caller":"traceutil/trace.go:171","msg":"trace[1601443770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:558; }","duration":"534.969193ms","start":"2026-02-18T14:41:19.130155Z","end":"2026-02-18T14:41:19.665125Z","steps":["trace[1601443770] 'agreement among raft nodes before linearized reading' (duration: 534.947243ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:19.665144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:19.130146Z","time spent":"534.995463ms","remote":"127.0.0.1:52502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:41:19.66516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.109546677s","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-18T14:41:19.66518Z","caller":"traceutil/trace.go:171","msg":"trace[840233053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:558; }","duration":"2.109564307s","start":"2026-02-18T14:41:17.555609Z","end":"2026-02-18T14:41:19.665173Z","steps":["trace[840233053] 'agreement among raft nodes before linearized reading' (duration: 2.109515096s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:19.665196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:17.55558Z","time spent":"2.109612077s","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-18T14:41:19.66527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.517043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T14:41:19.66529Z","caller":"traceutil/trace.go:171","msg":"trace[1932297595] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:558; }","duration":"700.556704ms","start":"2026-02-18T14:41:18.964726Z","end":"2026-02-18T14:41:19.665283Z","steps":["trace[1932297595] 'agreement among raft nodes before linearized reading' (duration: 700.499323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:19.665306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:18.964713Z","time spent":"700.589594ms","remote":"127.0.0.1:52540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.212\" "} {"level":"warn","ts":"2026-02-18T14:41:20.175046Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548429928128436,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-18T14:41:20.365789Z","caller":"traceutil/trace.go:171","msg":"trace[100338571] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"692.550439ms","start":"2026-02-18T14:41:19.673222Z","end":"2026-02-18T14:41:20.365773Z","steps":["trace[100338571] 'process raft request' (duration: 672.336231ms)","trace[100338571] 'compare' (duration: 19.839051ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:41:20.365873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:19.673207Z","time spent":"692.62267ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:41:20.429601Z","caller":"traceutil/trace.go:171","msg":"trace[2008105325] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:577; }","duration":"755.55126ms","start":"2026-02-18T14:41:19.674034Z","end":"2026-02-18T14:41:20.429585Z","steps":["trace[2008105325] 'read index received' (duration: 671.531865ms)","trace[2008105325] 'applied index is now lower than readState.Index' (duration: 84.018865ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:41:20.429686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.634632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:41:20.429701Z","caller":"traceutil/trace.go:171","msg":"trace[1966663773] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:562; }","duration":"755.680393ms","start":"2026-02-18T14:41:19.674016Z","end":"2026-02-18T14:41:20.429697Z","steps":["trace[1966663773] 'agreement among raft nodes before linearized reading' (duration: 755.625542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:20.42972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:19.674008Z","time spent":"755.707584ms","remote":"127.0.0.1:52514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T14:41:20.429759Z","caller":"traceutil/trace.go:171","msg":"trace[138114664] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"755.595701ms","start":"2026-02-18T14:41:19.674127Z","end":"2026-02-18T14:41:20.429723Z","steps":["trace[138114664] 'process raft request' (duration: 755.359137ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:41:20.429879Z","caller":"traceutil/trace.go:171","msg":"trace[1568002022] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"755.710664ms","start":"2026-02-18T14:41:19.674161Z","end":"2026-02-18T14:41:20.429872Z","steps":["trace[1568002022] 'process raft request' (duration: 755.385998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:20.429933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:19.674157Z","time spent":"755.735644ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:41:20.429924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:19.674122Z","time spent":"755.712053ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:41:20.430004Z","caller":"traceutil/trace.go:171","msg":"trace[1044555500] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"753.197924ms","start":"2026-02-18T14:41:19.676801Z","end":"2026-02-18T14:41:20.429999Z","steps":["trace[1044555500] 'process raft request' (duration: 752.764236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:20.430027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:19.676786Z","time spent":"753.228295ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:41:20.430162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:19.674067Z","time spent":"756.093781ms","remote":"127.0.0.1:52540","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-18T14:41:20.938479Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10636548429928128443,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-18T14:41:22.409722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.283683861s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:41:22.409879Z","caller":"traceutil/trace.go:171","msg":"trace[1424183816] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"694.786303ms","start":"2026-02-18T14:41:21.715077Z","end":"2026-02-18T14:41:22.409863Z","steps":["trace[1424183816] 'process raft request' (duration: 694.727652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:22.409939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:21.715058Z","time spent":"694.850464ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:41:22.41011Z","caller":"traceutil/trace.go:171","msg":"trace[1499093121] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.976955446s","start":"2026-02-18T14:41:20.433141Z","end":"2026-02-18T14:41:22.410096Z","steps":["trace[1499093121] 'process raft request' (duration: 692.662223ms)","trace[1499093121] 'compare' (duration: 1.283198001s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:41:22.41016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:20.433129Z","time spent":"1.977003646s","remote":"127.0.0.1:52540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:41:22.410287Z","caller":"traceutil/trace.go:171","msg":"trace[109334377] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"1.972402585s","start":"2026-02-18T14:41:20.437875Z","end":"2026-02-18T14:41:22.410277Z","steps":["trace[109334377] 'read index received' (duration: 687.872728ms)","trace[109334377] 'applied index is now lower than readState.Index' (duration: 1.284528807s)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:41:22.410521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.97264593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:41:22.410551Z","caller":"traceutil/trace.go:171","msg":"trace[1381864143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"1.972696031s","start":"2026-02-18T14:41:20.437846Z","end":"2026-02-18T14:41:22.410542Z","steps":["trace[1381864143] 'agreement among raft nodes before linearized reading' (duration: 1.97264915s)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:22.410576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:20.437833Z","time spent":"1.972737782s","remote":"127.0.0.1:52502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-18T14:41:24.709166Z","caller":"traceutil/trace.go:171","msg":"trace[1293137220] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"431.708684ms","start":"2026-02-18T14:41:24.27744Z","end":"2026-02-18T14:41:24.709149Z","steps":["trace[1293137220] 'process raft request' (duration: 431.629002ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:24.70943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:24.277431Z","time spent":"431.945958ms","remote":"127.0.0.1:52552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T14:41:24.715711Z","caller":"traceutil/trace.go:171","msg":"trace[360613821] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"436.493288ms","start":"2026-02-18T14:41:24.279202Z","end":"2026-02-18T14:41:24.715695Z","steps":["trace[360613821] 'process raft request' (duration: 436.262893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:24.715839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:24.279185Z","time spent":"436.607379ms","remote":"127.0.0.1:52552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T14:41:24.716105Z","caller":"traceutil/trace.go:171","msg":"trace[898929253] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"433.256303ms","start":"2026-02-18T14:41:24.282843Z","end":"2026-02-18T14:41:24.716099Z","steps":["trace[898929253] 'process raft request' (duration: 432.690812ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:24.716154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:41:24.282833Z","time spent":"433.308394ms","remote":"127.0.0.1:52552","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T14:41:24.721503Z","caller":"traceutil/trace.go:171","msg":"trace[708702282] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:607; }","duration":"285.750983ms","start":"2026-02-18T14:41:24.43573Z","end":"2026-02-18T14:41:24.721481Z","steps":["trace[708702282] 'read index received' (duration: 273.30319ms)","trace[708702282] 'applied index is now lower than readState.Index' (duration: 12.447263ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:41:24.721738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.013047ms","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-18T14:41:24.721782Z","caller":"traceutil/trace.go:171","msg":"trace[1672466395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"286.102289ms","start":"2026-02-18T14:41:24.435674Z","end":"2026-02-18T14:41:24.721776Z","steps":["trace[1672466395] 'agreement among raft nodes before linearized reading' (duration: 286.028118ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:24.725918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.83209ms","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-18T14:41:24.725982Z","caller":"traceutil/trace.go:171","msg":"trace[1087307647] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:592; }","duration":"275.921741ms","start":"2026-02-18T14:41:24.450051Z","end":"2026-02-18T14:41:24.725973Z","steps":["trace[1087307647] 'agreement among raft nodes before linearized reading' (duration: 275.773318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:24.727162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.656174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:41:24.727253Z","caller":"traceutil/trace.go:171","msg":"trace[2060410693] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:0; response_revision:592; }","duration":"257.746135ms","start":"2026-02-18T14:41:24.469499Z","end":"2026-02-18T14:41:24.727245Z","steps":["trace[2060410693] 'agreement among raft nodes before linearized reading' (duration: 257.554732ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:24.727537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.973577ms","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-18T14:41:24.727606Z","caller":"traceutil/trace.go:171","msg":"trace[29633211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"264.053039ms","start":"2026-02-18T14:41:24.463545Z","end":"2026-02-18T14:41:24.727599Z","steps":["trace[29633211] 'agreement among raft nodes before linearized reading' (duration: 263.969147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:24.727728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.389235ms","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-18T14:41:24.727797Z","caller":"traceutil/trace.go:171","msg":"trace[632018850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"264.482257ms","start":"2026-02-18T14:41:24.463302Z","end":"2026-02-18T14:41:24.727784Z","steps":["trace[632018850] 'agreement among raft nodes before linearized reading' (duration: 264.405005ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:41:27.445076Z","caller":"traceutil/trace.go:171","msg":"trace[1751210574] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"151.287279ms","start":"2026-02-18T14:41:27.293769Z","end":"2026-02-18T14:41:27.445056Z","steps":["trace[1751210574] 'process raft request' (duration: 142.57898ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:41:40.420237Z","caller":"traceutil/trace.go:171","msg":"trace[1187544527] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"107.943638ms","start":"2026-02-18T14:41:40.312264Z","end":"2026-02-18T14:41:40.420208Z","steps":["trace[1187544527] 'process raft request' (duration: 72.059957ms)","trace[1187544527] 'compare' (duration: 35.774069ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:41:40.989902Z","caller":"traceutil/trace.go:171","msg":"trace[80532251] linearizableReadLoop","detail":"{readStateIndex:793; appliedIndex:792; }","duration":"130.77904ms","start":"2026-02-18T14:41:40.859102Z","end":"2026-02-18T14:41:40.989881Z","steps":["trace[80532251] 'read index received' (duration: 130.596307ms)","trace[80532251] 'applied index is now lower than readState.Index' (duration: 181.893µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:41:40.990161Z","caller":"traceutil/trace.go:171","msg":"trace[1736742042] transaction","detail":"{read_only:false; response_revision:766; number_of_response:1; }","duration":"152.069006ms","start":"2026-02-18T14:41:40.838082Z","end":"2026-02-18T14:41:40.990151Z","steps":["trace[1736742042] 'process raft request' (duration: 151.670498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:40.990416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.298881ms","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-18T14:41:40.990449Z","caller":"traceutil/trace.go:171","msg":"trace[1843714353] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:766; }","duration":"131.360933ms","start":"2026-02-18T14:41:40.859077Z","end":"2026-02-18T14:41:40.990438Z","steps":["trace[1843714353] 'agreement among raft nodes before linearized reading' (duration: 131.250211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:40.99062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.620428ms","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-18T14:41:40.990646Z","caller":"traceutil/trace.go:171","msg":"trace[1263698215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:766; }","duration":"120.67089ms","start":"2026-02-18T14:41:40.869967Z","end":"2026-02-18T14:41:40.990638Z","steps":["trace[1263698215] 'agreement among raft nodes before linearized reading' (duration: 120.593658ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:44.40773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.868623ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-18T14:41:45.173873Z","caller":"traceutil/trace.go:171","msg":"trace[502770521] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"121.388925ms","start":"2026-02-18T14:41:45.052467Z","end":"2026-02-18T14:41:45.173856Z","steps":["trace[502770521] 'read index received' (duration: 76.920078ms)","trace[502770521] 'applied index is now lower than readState.Index' (duration: 44.468177ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:41:45.173953Z","caller":"traceutil/trace.go:171","msg":"trace[1129136065] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"121.607739ms","start":"2026-02-18T14:41:45.052339Z","end":"2026-02-18T14:41:45.173947Z","steps":["trace[1129136065] 'process raft request' (duration: 77.02476ms)","trace[1129136065] 'compare' (duration: 44.355015ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:41:45.174098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.615589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-18T14:41:45.174113Z","caller":"traceutil/trace.go:171","msg":"trace[43908461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"121.65354ms","start":"2026-02-18T14:41:45.052454Z","end":"2026-02-18T14:41:45.174108Z","steps":["trace[43908461] 'agreement among raft nodes before linearized reading' (duration: 121.576039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:41:45.174181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.330644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T14:41:45.174192Z","caller":"traceutil/trace.go:171","msg":"trace[78861181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:784; }","duration":"121.349304ms","start":"2026-02-18T14:41:45.052839Z","end":"2026-02-18T14:41:45.174188Z","steps":["trace[78861181] 'agreement among raft nodes before linearized reading' (duration: 121.312553ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:41:46.56623Z","caller":"traceutil/trace.go:171","msg":"trace[786747237] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"104.580695ms","start":"2026-02-18T14:41:46.461634Z","end":"2026-02-18T14:41:46.566215Z","steps":["trace[786747237] 'process raft request' (duration: 104.489034ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:41:51.373946Z","caller":"traceutil/trace.go:171","msg":"trace[1494674240] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"158.320854ms","start":"2026-02-18T14:41:51.215609Z","end":"2026-02-18T14:41:51.373929Z","steps":["trace[1494674240] 'process raft request' (duration: 69.915535ms)","trace[1494674240] 'compare' (duration: 88.280737ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:41:51.597027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.263279ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:41:51.597124Z","caller":"traceutil/trace.go:171","msg":"trace[434416244] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"217.57256ms","start":"2026-02-18T14:41:51.379541Z","end":"2026-02-18T14:41:51.597113Z","steps":["trace[434416244] 'process raft request' (duration: 217.54191ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:41:51.597138Z","caller":"traceutil/trace.go:171","msg":"trace[149214469] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"217.788095ms","start":"2026-02-18T14:41:51.379341Z","end":"2026-02-18T14:41:51.597129Z","steps":["trace[149214469] 'process raft request' (duration: 100.374264ms)","trace[149214469] 'compare' (duration: 117.167616ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:41:57.545971Z","caller":"traceutil/trace.go:171","msg":"trace[531567418] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"120.110432ms","start":"2026-02-18T14:41:57.425833Z","end":"2026-02-18T14:41:57.545944Z","steps":["trace[531567418] 'process raft request' (duration: 119.916889ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:42:14.077117Z","caller":"traceutil/trace.go:171","msg":"trace[1666021403] linearizableReadLoop","detail":"{readStateIndex:898; appliedIndex:897; }","duration":"229.823826ms","start":"2026-02-18T14:42:13.847278Z","end":"2026-02-18T14:42:14.077102Z","steps":["trace[1666021403] 'read index received' (duration: 229.703504ms)","trace[1666021403] 'applied index is now lower than readState.Index' (duration: 119.742µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:42:14.077238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.947328ms","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-18T14:42:14.077253Z","caller":"traceutil/trace.go:171","msg":"trace[325323768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:864; }","duration":"230.006229ms","start":"2026-02-18T14:42:13.847243Z","end":"2026-02-18T14:42:14.077249Z","steps":["trace[325323768] 'agreement among raft nodes before linearized reading' (duration: 229.918818ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:42:14.077319Z","caller":"traceutil/trace.go:171","msg":"trace[1472517087] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"238.682328ms","start":"2026-02-18T14:42:13.838608Z","end":"2026-02-18T14:42:14.07729Z","steps":["trace[1472517087] 'process raft request' (duration: 238.413403ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:42:17.866797Z","caller":"traceutil/trace.go:171","msg":"trace[627094373] transaction","detail":"{read_only:false; response_revision:873; number_of_response:1; }","duration":"169.847844ms","start":"2026-02-18T14:42:17.696935Z","end":"2026-02-18T14:42:17.866783Z","steps":["trace[627094373] 'process raft request' (duration: 169.761843ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:42:19.138442Z","caller":"traceutil/trace.go:171","msg":"trace[336041744] linearizableReadLoop","detail":"{readStateIndex:911; appliedIndex:910; }","duration":"170.749356ms","start":"2026-02-18T14:42:18.967675Z","end":"2026-02-18T14:42:19.138425Z","steps":["trace[336041744] 'read index received' (duration: 170.578603ms)","trace[336041744] 'applied index is now lower than readState.Index' (duration: 170.023µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:42:19.138869Z","caller":"traceutil/trace.go:171","msg":"trace[157559308] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"183.751091ms","start":"2026-02-18T14:42:18.955105Z","end":"2026-02-18T14:42:19.138856Z","steps":["trace[157559308] 'process raft request' (duration: 183.196291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:42:19.139765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.072251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.212\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-18T14:42:19.13981Z","caller":"traceutil/trace.go:171","msg":"trace[1075966443] range","detail":"{range_begin:/registry/masterleases/162.253.55.212; range_end:; response_count:1; response_revision:876; }","duration":"172.141562ms","start":"2026-02-18T14:42:18.967658Z","end":"2026-02-18T14:42:19.139799Z","steps":["trace[1075966443] 'agreement among raft nodes before linearized reading' (duration: 172.015749ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:42:31.346827Z","caller":"traceutil/trace.go:171","msg":"trace[1415714014] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"302.646274ms","start":"2026-02-18T14:42:31.044159Z","end":"2026-02-18T14:42:31.346805Z","steps":["trace[1415714014] 'process raft request' (duration: 292.312229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:42:31.346951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:42:31.044144Z","time spent":"302.751125ms","remote":"127.0.0.1:42952","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2231,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-18T14:42:32.615934Z","caller":"traceutil/trace.go:171","msg":"trace[370575108] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"189.1348ms","start":"2026-02-18T14:42:32.426773Z","end":"2026-02-18T14:42:32.615907Z","steps":["trace[370575108] 'read index received' (duration: 136.139692ms)","trace[370575108] 'applied index is now lower than readState.Index' (duration: 52.993538ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:42:32.616224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.434735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8e0845d7-e6cf-4111-bc36-4b65b7ac0997\" ","response":"range_response_count:1 size:3535"} {"level":"info","ts":"2026-02-18T14:42:32.616273Z","caller":"traceutil/trace.go:171","msg":"trace[2033495893] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8e0845d7-e6cf-4111-bc36-4b65b7ac0997; range_end:; response_count:1; response_revision:930; }","duration":"189.533977ms","start":"2026-02-18T14:42:32.426725Z","end":"2026-02-18T14:42:32.616259Z","steps":["trace[2033495893] 'agreement among raft nodes before linearized reading' (duration: 189.300753ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:42:32.616485Z","caller":"traceutil/trace.go:171","msg":"trace[2116672253] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"281.681395ms","start":"2026-02-18T14:42:32.334792Z","end":"2026-02-18T14:42:32.616473Z","steps":["trace[2116672253] 'process raft request' (duration: 228.105786ms)","trace[2116672253] 'compare' (duration: 52.5986ms)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:42:38.426749Z","caller":"traceutil/trace.go:171","msg":"trace[1170355076] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"464.391436ms","start":"2026-02-18T14:42:37.962349Z","end":"2026-02-18T14:42:38.426741Z","steps":["trace[1170355076] 'process raft request' (duration: 464.159852ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:42:38.426833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:42:37.962336Z","time spent":"464.445767ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:42:38.42661Z","caller":"traceutil/trace.go:171","msg":"trace[643172605] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"299.884187ms","start":"2026-02-18T14:42:38.126711Z","end":"2026-02-18T14:42:38.426595Z","steps":["trace[643172605] 'read index received' (duration: 299.746575ms)","trace[643172605] 'applied index is now lower than readState.Index' (duration: 136.962µs)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:42:38.42712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.018198ms","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-18T14:42:38.427142Z","caller":"traceutil/trace.go:171","msg":"trace[416862884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:961; }","duration":"146.072539ms","start":"2026-02-18T14:42:38.281062Z","end":"2026-02-18T14:42:38.427135Z","steps":["trace[416862884] 'agreement among raft nodes before linearized reading' (duration: 146.001358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:42:38.427294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.338685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:42:38.427315Z","caller":"traceutil/trace.go:171","msg":"trace[1177191214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"300.664861ms","start":"2026-02-18T14:42:38.126643Z","end":"2026-02-18T14:42:38.427308Z","steps":["trace[1177191214] 'agreement among raft nodes before linearized reading' (duration: 300.390006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:42:38.42733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:42:38.126623Z","time spent":"300.703471ms","remote":"127.0.0.1:52502","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-18T14:42:39.038111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.672595ms","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-18T14:42:39.03908Z","caller":"traceutil/trace.go:171","msg":"trace[537299703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:962; }","duration":"398.689013ms","start":"2026-02-18T14:42:38.640373Z","end":"2026-02-18T14:42:39.039062Z","steps":["trace[537299703] 'range keys from in-memory index tree' (duration: 397.513322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:42:39.039133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:42:38.640353Z","time spent":"398.770214ms","remote":"127.0.0.1:52774","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-18T14:42:39.039003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.919162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-18T14:42:39.040224Z","caller":"traceutil/trace.go:171","msg":"trace[1470087294] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:962; }","duration":"248.192355ms","start":"2026-02-18T14:42:38.792018Z","end":"2026-02-18T14:42:39.04021Z","steps":["trace[1470087294] 'count revisions from in-memory index tree' (duration: 246.82458ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:42:39.75616Z","caller":"traceutil/trace.go:171","msg":"trace[495711621] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"364.468431ms","start":"2026-02-18T14:42:39.39167Z","end":"2026-02-18T14:42:39.756139Z","steps":["trace[495711621] 'process raft request' (duration: 364.308168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:42:39.75657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:42:39.391655Z","time spent":"364.595703ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-18T14:42:40.746852Z","caller":"traceutil/trace.go:171","msg":"trace[643425738] linearizableReadLoop","detail":"{readStateIndex:1008; appliedIndex:1007; }","duration":"285.015366ms","start":"2026-02-18T14:42:40.461815Z","end":"2026-02-18T14:42:40.74683Z","steps":["trace[643425738] 'read index received' (duration: 284.733801ms)","trace[643425738] 'applied index is now lower than readState.Index' (duration: 280.564µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:42:40.746988Z","caller":"traceutil/trace.go:171","msg":"trace[565997747] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"312.383052ms","start":"2026-02-18T14:42:40.434563Z","end":"2026-02-18T14:42:40.746946Z","steps":["trace[565997747] 'process raft request' (duration: 312.043716ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:42:40.747296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:42:40.434547Z","time spent":"312.471634ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:42:40.747585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.759058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-18T14:42:40.747627Z","caller":"traceutil/trace.go:171","msg":"trace[542799197] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:967; }","duration":"285.805409ms","start":"2026-02-18T14:42:40.461811Z","end":"2026-02-18T14:42:40.747617Z","steps":["trace[542799197] 'agreement among raft nodes before linearized reading' (duration: 285.729458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:42:40.748708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.966287ms","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-18T14:42:40.748931Z","caller":"traceutil/trace.go:171","msg":"trace[610379219] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:967; }","duration":"284.210182ms","start":"2026-02-18T14:42:40.464707Z","end":"2026-02-18T14:42:40.748917Z","steps":["trace[610379219] 'agreement among raft nodes before linearized reading' (duration: 283.866775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:42:44.180359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.020726ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-18T14:42:44.181027Z","caller":"traceutil/trace.go:171","msg":"trace[1304252193] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"124.239762ms","start":"2026-02-18T14:42:44.056769Z","end":"2026-02-18T14:42:44.181009Z","steps":["trace[1304252193] 'compare' (duration: 119.816493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:42:44.339183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.079671ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-18T14:42:47.335618Z","caller":"traceutil/trace.go:171","msg":"trace[228889600] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1033; }","duration":"485.868088ms","start":"2026-02-18T14:42:46.849734Z","end":"2026-02-18T14:42:47.335602Z","steps":["trace[228889600] 'read index received' (duration: 485.721675ms)","trace[228889600] 'applied index is now lower than readState.Index' (duration: 145.803µs)"],"step_count":2} {"level":"info","ts":"2026-02-18T14:42:47.336011Z","caller":"traceutil/trace.go:171","msg":"trace[314511717] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"488.218689ms","start":"2026-02-18T14:42:46.84778Z","end":"2026-02-18T14:42:47.335999Z","steps":["trace[314511717] 'process raft request' (duration: 487.71818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:42:47.336123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:42:46.847771Z","time spent":"488.29193ms","remote":"127.0.0.1:52774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-18T14:42:47.3363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.56144ms","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-18T14:42:47.336324Z","caller":"traceutil/trace.go:171","msg":"trace[1303896923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:991; }","duration":"486.59359ms","start":"2026-02-18T14:42:46.849723Z","end":"2026-02-18T14:42:47.336316Z","steps":["trace[1303896923] 'agreement among raft nodes before linearized reading' (duration: 486.510199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:42:47.336342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-18T14:42:46.849718Z","time spent":"486.620711ms","remote":"127.0.0.1:52774","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-18T14:42:47.33645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.629053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-18T14:42:47.336467Z","caller":"traceutil/trace.go:171","msg":"trace[428970778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:991; }","duration":"203.673554ms","start":"2026-02-18T14:42:47.132788Z","end":"2026-02-18T14:42:47.336462Z","steps":["trace[428970778] 'agreement among raft nodes before linearized reading' (duration: 203.628713ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:42:48.577258Z","caller":"traceutil/trace.go:171","msg":"trace[1457301895] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"172.078261ms","start":"2026-02-18T14:42:48.405152Z","end":"2026-02-18T14:42:48.57723Z","steps":["trace[1457301895] 'process raft request' (duration: 171.893208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:08.357962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.747302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubelet-config\" ","response":"range_response_count:1 size:1357"} {"level":"info","ts":"2026-02-18T14:43:08.358694Z","caller":"traceutil/trace.go:171","msg":"trace[1972403818] range","detail":"{range_begin:/registry/configmaps/kube-system/kubelet-config; range_end:; response_count:1; response_revision:1055; }","duration":"173.481076ms","start":"2026-02-18T14:43:08.185164Z","end":"2026-02-18T14:43:08.358645Z","steps":["trace[1972403818] 'range keys from in-memory index tree' (duration: 172.669431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:08.358729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.086466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/local-path-storage/\" range_end:\"/registry/configmaps/local-path-storage0\" limit:500 ","response":"range_response_count:2 size:3195"} {"level":"info","ts":"2026-02-18T14:43:08.358883Z","caller":"traceutil/trace.go:171","msg":"trace[874835685] range","detail":"{range_begin:/registry/configmaps/local-path-storage/; range_end:/registry/configmaps/local-path-storage0; response_count:2; response_revision:1055; }","duration":"122.244929ms","start":"2026-02-18T14:43:08.236618Z","end":"2026-02-18T14:43:08.358863Z","steps":["trace[874835685] 'range keys from in-memory index tree' (duration: 121.967514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:08.358112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.434186ms","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-18T14:43:08.359259Z","caller":"traceutil/trace.go:171","msg":"trace[620392347] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1055; }","duration":"137.543616ms","start":"2026-02-18T14:43:08.221664Z","end":"2026-02-18T14:43:08.359208Z","steps":["trace[620392347] 'range keys from in-memory index tree' (duration: 136.425396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:08.358257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.827359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/kubeadm-config\" ","response":"range_response_count:1 size:1000"} {"level":"info","ts":"2026-02-18T14:43:08.359575Z","caller":"traceutil/trace.go:171","msg":"trace[273227350] range","detail":"{range_begin:/registry/configmaps/kube-system/kubeadm-config; range_end:; response_count:1; response_revision:1055; }","duration":"160.155682ms","start":"2026-02-18T14:43:08.199407Z","end":"2026-02-18T14:43:08.359562Z","steps":["trace[273227350] 'range keys from in-memory index tree' (duration: 158.786198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-18T14:43:08.358419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.035258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-system/\" range_end:\"/registry/services/endpoints/kube-system0\" limit:500 ","response":"range_response_count:1 size:916"} {"level":"info","ts":"2026-02-18T14:43:08.359956Z","caller":"traceutil/trace.go:171","msg":"trace[1306757672] range","detail":"{range_begin:/registry/services/endpoints/kube-system/; range_end:/registry/services/endpoints/kube-system0; response_count:1; response_revision:1055; }","duration":"142.590715ms","start":"2026-02-18T14:43:08.217352Z","end":"2026-02-18T14:43:08.359943Z","steps":["trace[1306757672] 'range keys from in-memory index tree' (duration: 140.886735ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:13.709507Z","caller":"traceutil/trace.go:171","msg":"trace[380735127] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"114.411888ms","start":"2026-02-18T14:43:13.595073Z","end":"2026-02-18T14:43:13.709485Z","steps":["trace[380735127] 'read index received' (duration: 21.577846ms)","trace[380735127] 'applied index is now lower than readState.Index' (duration: 92.833012ms)"],"step_count":2} {"level":"warn","ts":"2026-02-18T14:43:13.709725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.635402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:618"} {"level":"info","ts":"2026-02-18T14:43:13.7098Z","caller":"traceutil/trace.go:171","msg":"trace[736911701] range","detail":"{range_begin:/registry/roles/kube-system/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1070; }","duration":"114.752604ms","start":"2026-02-18T14:43:13.595026Z","end":"2026-02-18T14:43:13.709779Z","steps":["trace[736911701] 'agreement among raft nodes before linearized reading' (duration: 114.612991ms)"],"step_count":1} {"level":"info","ts":"2026-02-18T14:43:13.709939Z","caller":"traceutil/trace.go:171","msg":"trace[1873989149] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"115.870523ms","start":"2026-02-18T14:43:13.594053Z","end":"2026-02-18T14:43:13.709924Z","steps":["trace[1873989149] 'process raft request' (duration: 22.622884ms)","trace[1873989149] 'compare' (duration: 92.642928ms)"],"step_count":2}