{"level":"warn","ts":"2026-02-17T16:03:24.454138Z","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-17T16:03:24.454244Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209: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.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209: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-17T16:03:24.45439Z","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-17T16:03:24.454401Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-17T16:03:24.454464Z","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-17T16:03:24.455115Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]} {"level":"info","ts":"2026-02-17T16:03:24.455201Z","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.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209: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-17T16:03:24.466558Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.087617ms"} {"level":"info","ts":"2026-02-17T16:03:24.486165Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-02-17T16:03:24.486262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-02-17T16:03:24.486291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-02-17T16:03:24.486299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-17T16:03:24.486305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-02-17T16:03:24.48634Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-02-17T16:03:24.493508Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-17T16:03:24.506826Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-17T16:03:24.523825Z","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-17T16:03:24.537799Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-17T16:03:24.539529Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-17T16:03:24.539696Z","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-17T16:03:24.539728Z","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-17T16:03:24.539737Z","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-17T16:03:24.555414Z","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-17T16:03:24.55649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-02-17T16:03:24.55662Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-17T16:03:24.556672Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-17T16:03:24.556689Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-17T16:03:24.561103Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-17T16:03:24.56114Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-17T16:03:24.687183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-02-17T16:03:24.687266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-17T16:03:24.687323Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-02-17T16:03:24.687354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-02-17T16:03:24.687374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-17T16:03:24.687398Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-02-17T16:03:24.687433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-17T16:03:24.688979Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:03:24.691175Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-17T16:03:24.691265Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T16:03:24.691705Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:03:24.691802Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:03:24.691839Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-17T16:03:24.691883Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-17T16:03:24.69421Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-02-17T16:03:24.694667Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-17T16:03:24.694698Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-17T16:03:24.695753Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-17T16:03:37.165752Z","caller":"traceutil/trace.go:171","msg":"trace[923985491] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"102.166812ms","start":"2026-02-17T16:03:37.063561Z","end":"2026-02-17T16:03:37.165728Z","steps":["trace[923985491] 'process raft request' (duration: 102.04622ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:03:37.448421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.777893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-02-17T16:03:37.448498Z","caller":"traceutil/trace.go:171","msg":"trace[1700092322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:359; }","duration":"245.914215ms","start":"2026-02-17T16:03:37.202567Z","end":"2026-02-17T16:03:37.448481Z","steps":["trace[1700092322] 'range keys from in-memory index tree' (duration: 245.715692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:03:37.449419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.330177ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:03:37.449507Z","caller":"traceutil/trace.go:171","msg":"trace[1881803036] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"222.659758ms","start":"2026-02-17T16:03:37.226833Z","end":"2026-02-17T16:03:37.449493Z","steps":["trace[1881803036] 'process raft request' (duration: 47.56492ms)","trace[1881803036] 'compare' (duration: 173.872091ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:03:37.625549Z","caller":"traceutil/trace.go:171","msg":"trace[24879629] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"168.49955ms","start":"2026-02-17T16:03:37.457019Z","end":"2026-02-17T16:03:37.625519Z","steps":["trace[24879629] 'process raft request' (duration: 168.328078ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:03:55.922197Z","caller":"traceutil/trace.go:171","msg":"trace[107096515] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"185.144271ms","start":"2026-02-17T16:03:55.736871Z","end":"2026-02-17T16:03:55.922015Z","steps":["trace[107096515] 'process raft request' (duration: 185.038708ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:03:57.341402Z","caller":"traceutil/trace.go:171","msg":"trace[283885246] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"160.561803ms","start":"2026-02-17T16:03:57.180813Z","end":"2026-02-17T16:03:57.341375Z","steps":["trace[283885246] 'process raft request' (duration: 160.243934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:04:04.58468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.885476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:04:04.584937Z","caller":"traceutil/trace.go:171","msg":"trace[789662279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"144.140694ms","start":"2026-02-17T16:04:04.440701Z","end":"2026-02-17T16:04:04.584842Z","steps":["trace[789662279] 'range keys from in-memory index tree' (duration: 143.830105ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:04:19.841927Z","caller":"traceutil/trace.go:171","msg":"trace[859670324] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:692; }","duration":"334.034594ms","start":"2026-02-17T16:04:19.507875Z","end":"2026-02-17T16:04:19.84191Z","steps":["trace[859670324] 'read index received' (duration: 333.905511ms)","trace[859670324] 'applied index is now lower than readState.Index' (duration: 128.363µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:04:19.842017Z","caller":"traceutil/trace.go:171","msg":"trace[1367675842] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"341.050664ms","start":"2026-02-17T16:04:19.50096Z","end":"2026-02-17T16:04:19.842011Z","steps":["trace[1367675842] 'process raft request' (duration: 340.86317ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:04:19.842087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:04:19.500946Z","time spent":"341.090304ms","remote":"127.0.0.1:37198","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-17T16:04:19.842338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.458401ms","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-17T16:04:19.842359Z","caller":"traceutil/trace.go:171","msg":"trace[650580593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:670; }","duration":"334.507043ms","start":"2026-02-17T16:04:19.507846Z","end":"2026-02-17T16:04:19.842353Z","steps":["trace[650580593] 'agreement among raft nodes before linearized reading' (duration: 334.406321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:04:19.842378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:04:19.507832Z","time spent":"334.540573ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-17T16:04:19.842498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.591115ms","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-17T16:04:19.842514Z","caller":"traceutil/trace.go:171","msg":"trace[1385629208] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:670; }","duration":"334.613255ms","start":"2026-02-17T16:04:19.507896Z","end":"2026-02-17T16:04:19.842509Z","steps":["trace[1385629208] 'agreement among raft nodes before linearized reading' (duration: 334.567534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:04:19.84253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:04:19.507893Z","time spent":"334.633105ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-17T16:04:20.094605Z","caller":"traceutil/trace.go:171","msg":"trace[1427193333] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"245.984204ms","start":"2026-02-17T16:04:19.848595Z","end":"2026-02-17T16:04:20.09458Z","steps":["trace[1427193333] 'process raft request' (duration: 239.80866ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:04:20.094907Z","caller":"traceutil/trace.go:171","msg":"trace[1053620249] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"246.106737ms","start":"2026-02-17T16:04:19.848782Z","end":"2026-02-17T16:04:20.094888Z","steps":["trace[1053620249] 'process raft request' (duration: 245.757801ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:04:20.095118Z","caller":"traceutil/trace.go:171","msg":"trace[1954711040] transaction","detail":"{read_only:false; response_revision:673; number_of_response:1; }","duration":"246.29533ms","start":"2026-02-17T16:04:19.84881Z","end":"2026-02-17T16:04:20.095105Z","steps":["trace[1954711040] 'process raft request' (duration: 246.011275ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:04:24.282262Z","caller":"traceutil/trace.go:171","msg":"trace[69415063] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"147.213426ms","start":"2026-02-17T16:04:24.135026Z","end":"2026-02-17T16:04:24.282239Z","steps":["trace[69415063] 'process raft request' (duration: 119.451231ms)","trace[69415063] 'compare' (duration: 27.673803ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:04:44.437089Z","caller":"traceutil/trace.go:171","msg":"trace[1699081404] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"143.436446ms","start":"2026-02-17T16:04:44.29363Z","end":"2026-02-17T16:04:44.437066Z","steps":["trace[1699081404] 'read index received' (duration: 143.258723ms)","trace[1699081404] 'applied index is now lower than readState.Index' (duration: 176.723µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:04:44.437349Z","caller":"traceutil/trace.go:171","msg":"trace[1568818098] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"223.62131ms","start":"2026-02-17T16:04:44.213716Z","end":"2026-02-17T16:04:44.437337Z","steps":["trace[1568818098] 'process raft request' (duration: 223.226743ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:04:44.437621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.967796ms","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-17T16:04:44.437655Z","caller":"traceutil/trace.go:171","msg":"trace[1132867772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:744; }","duration":"144.029636ms","start":"2026-02-17T16:04:44.293614Z","end":"2026-02-17T16:04:44.437644Z","steps":["trace[1132867772] 'agreement among raft nodes before linearized reading' (duration: 143.903544ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:05:02.534723Z","caller":"traceutil/trace.go:171","msg":"trace[34590752] transaction","detail":"{read_only:false; response_revision:855; number_of_response:1; }","duration":"410.132706ms","start":"2026-02-17T16:05:02.124573Z","end":"2026-02-17T16:05:02.534706Z","steps":["trace[34590752] 'process raft request' (duration: 409.665617ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:05:02.534877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:05:02.124559Z","time spent":"410.255998ms","remote":"127.0.0.1:37296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5143,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:05:02.642613Z","caller":"traceutil/trace.go:171","msg":"trace[564757764] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"104.014718ms","start":"2026-02-17T16:05:02.538584Z","end":"2026-02-17T16:05:02.642599Z","steps":["trace[564757764] 'process raft request' (duration: 100.621446ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:05:10.196288Z","caller":"traceutil/trace.go:171","msg":"trace[974461888] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"100.771599ms","start":"2026-02-17T16:05:10.095501Z","end":"2026-02-17T16:05:10.196272Z","steps":["trace[974461888] 'process raft request' (duration: 100.670197ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:05:10.903329Z","caller":"traceutil/trace.go:171","msg":"trace[1470893573] linearizableReadLoop","detail":"{readStateIndex:914; appliedIndex:913; }","duration":"234.952218ms","start":"2026-02-17T16:05:10.668362Z","end":"2026-02-17T16:05:10.903315Z","steps":["trace[1470893573] 'read index received' (duration: 234.799065ms)","trace[1470893573] 'applied index is now lower than readState.Index' (duration: 152.653µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:05:10.903414Z","caller":"traceutil/trace.go:171","msg":"trace[803504268] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"237.034627ms","start":"2026-02-17T16:05:10.666371Z","end":"2026-02-17T16:05:10.903406Z","steps":["trace[803504268] 'process raft request' (duration: 236.837393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:05:10.903578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.209962ms","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-17T16:05:10.903606Z","caller":"traceutil/trace.go:171","msg":"trace[2146853263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:878; }","duration":"235.262414ms","start":"2026-02-17T16:05:10.668327Z","end":"2026-02-17T16:05:10.903589Z","steps":["trace[2146853263] 'agreement among raft nodes before linearized reading' (duration: 235.177042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:05:10.903729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.391889ms","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-17T16:05:10.903792Z","caller":"traceutil/trace.go:171","msg":"trace[1052808977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:878; }","duration":"216.502711ms","start":"2026-02-17T16:05:10.687277Z","end":"2026-02-17T16:05:10.90378Z","steps":["trace[1052808977] 'agreement among raft nodes before linearized reading' (duration: 216.354949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:05:11.527796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.132334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:05:11.527954Z","caller":"traceutil/trace.go:171","msg":"trace[1848641770] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:914; }","duration":"491.805807ms","start":"2026-02-17T16:05:11.036132Z","end":"2026-02-17T16:05:11.527938Z","steps":["trace[1848641770] 'read index received' (duration: 85.523961ms)","trace[1848641770] 'applied index is now lower than readState.Index' (duration: 406.280976ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:05:11.528155Z","caller":"traceutil/trace.go:171","msg":"trace[1411833830] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"620.331152ms","start":"2026-02-17T16:05:10.907809Z","end":"2026-02-17T16:05:11.52814Z","steps":["trace[1411833830] 'process raft request' (duration: 105.781701ms)","trace[1411833830] 'compare' (duration: 513.871599ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:05:11.528217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:05:10.907798Z","time spent":"620.387023ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:05:11.528437Z","caller":"traceutil/trace.go:171","msg":"trace[413131832] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"620.150959ms","start":"2026-02-17T16:05:10.908276Z","end":"2026-02-17T16:05:11.528427Z","steps":["trace[413131832] 'process raft request' (duration: 619.609469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:05:11.52849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:05:10.908262Z","time spent":"620.199289ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:05:11.528684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.5521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-17T16:05:11.528711Z","caller":"traceutil/trace.go:171","msg":"trace[1677409161] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:880; }","duration":"492.602781ms","start":"2026-02-17T16:05:11.036099Z","end":"2026-02-17T16:05:11.528702Z","steps":["trace[1677409161] 'agreement among raft nodes before linearized reading' (duration: 492.495339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:05:11.528738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:05:11.036079Z","time spent":"492.653172ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-17T16:05:11.5289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.422417ms","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-17T16:05:11.528925Z","caller":"traceutil/trace.go:171","msg":"trace[1030085629] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:880; }","duration":"235.472198ms","start":"2026-02-17T16:05:11.293445Z","end":"2026-02-17T16:05:11.528917Z","steps":["trace[1030085629] 'agreement among raft nodes before linearized reading' (duration: 235.396916ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:05:12.238844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.703487ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:05:12.238964Z","caller":"traceutil/trace.go:171","msg":"trace[1837875747] linearizableReadLoop","detail":"{readStateIndex:919; appliedIndex:916; }","duration":"335.424182ms","start":"2026-02-17T16:05:11.90353Z","end":"2026-02-17T16:05:12.238954Z","steps":["trace[1837875747] 'read index received' (duration: 182.571992ms)","trace[1837875747] 'applied index is now lower than readState.Index' (duration: 152.85164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:05:12.239011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.487153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:05:12.239023Z","caller":"traceutil/trace.go:171","msg":"trace[787530953] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"335.536944ms","start":"2026-02-17T16:05:11.903482Z","end":"2026-02-17T16:05:12.239019Z","steps":["trace[787530953] 'agreement among raft nodes before linearized reading' (duration: 335.499204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:05:12.239038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:05:11.903463Z","time spent":"335.571675ms","remote":"127.0.0.1:37124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T16:05:12.239197Z","caller":"traceutil/trace.go:171","msg":"trace[1155566187] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"649.792409ms","start":"2026-02-17T16:05:11.589398Z","end":"2026-02-17T16:05:12.239191Z","steps":["trace[1155566187] 'process raft request' (duration: 496.694705ms)","trace[1155566187] 'compare' (duration: 152.568875ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:05:12.239224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:05:11.589383Z","time spent":"649.825331ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:05:12.239307Z","caller":"traceutil/trace.go:171","msg":"trace[1738543089] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"649.715638ms","start":"2026-02-17T16:05:11.589565Z","end":"2026-02-17T16:05:12.239281Z","steps":["trace[1738543089] 'process raft request' (duration: 649.340171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:05:12.239339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:05:11.58956Z","time spent":"649.764039ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:05:12.239406Z","caller":"traceutil/trace.go:171","msg":"trace[1119910692] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"394.221165ms","start":"2026-02-17T16:05:11.84518Z","end":"2026-02-17T16:05:12.239402Z","steps":["trace[1119910692] 'process raft request' (duration: 393.755457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:05:12.239429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:05:11.845165Z","time spent":"394.251106ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:05:32.155526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.575325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:05:32.156807Z","caller":"traceutil/trace.go:171","msg":"trace[633979047] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"339.461376ms","start":"2026-02-17T16:05:31.817325Z","end":"2026-02-17T16:05:32.156786Z","steps":["trace[633979047] 'process raft request' (duration: 144.955135ms)","trace[633979047] 'compare' (duration: 192.350471ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:05:32.156879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:05:31.817294Z","time spent":"339.550298ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-17T16:05:32.629943Z","caller":"traceutil/trace.go:171","msg":"trace[1038727574] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"218.863545ms","start":"2026-02-17T16:05:32.411058Z","end":"2026-02-17T16:05:32.629922Z","steps":["trace[1038727574] 'process raft request' (duration: 207.306744ms)","trace[1038727574] 'compare' (duration: 11.348817ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:05:33.109267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.697761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:05:33.114892Z","caller":"traceutil/trace.go:171","msg":"trace[643085011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1005; }","duration":"207.832624ms","start":"2026-02-17T16:05:32.901507Z","end":"2026-02-17T16:05:33.109339Z","steps":["trace[643085011] 'range keys from in-memory index tree' (duration: 207.6359ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:05:33.235177Z","caller":"traceutil/trace.go:171","msg":"trace[1471525980] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"191.698279ms","start":"2026-02-17T16:05:33.043455Z","end":"2026-02-17T16:05:33.235153Z","steps":["trace[1471525980] 'process raft request' (duration: 191.569146ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:05:33.582116Z","caller":"traceutil/trace.go:171","msg":"trace[173237682] linearizableReadLoop","detail":"{readStateIndex:1047; appliedIndex:1046; }","duration":"466.25107ms","start":"2026-02-17T16:05:33.115847Z","end":"2026-02-17T16:05:33.582098Z","steps":["trace[173237682] 'read index received' (duration: 119.611663ms)","trace[173237682] 'applied index is now lower than readState.Index' (duration: 346.638757ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:05:33.582454Z","caller":"traceutil/trace.go:171","msg":"trace[375931040] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"468.614403ms","start":"2026-02-17T16:05:33.113813Z","end":"2026-02-17T16:05:33.582427Z","steps":["trace[375931040] 'process raft request' (duration: 450.188127ms)","trace[375931040] 'compare' (duration: 17.824535ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:05:33.582575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:05:33.113798Z","time spent":"468.717455ms","remote":"127.0.0.1:37370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-17T16:05:33.58504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.341372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:05:33.585144Z","caller":"traceutil/trace.go:171","msg":"trace[1047668272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1007; }","duration":"469.268925ms","start":"2026-02-17T16:05:33.115821Z","end":"2026-02-17T16:05:33.58509Z","steps":["trace[1047668272] 'agreement among raft nodes before linearized reading' (duration: 466.343412ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:05:33.585182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-17T16:05:33.115813Z","time spent":"469.358556ms","remote":"127.0.0.1:37136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-17T16:08:48.321309Z","caller":"traceutil/trace.go:171","msg":"trace[1222367153] linearizableReadLoop","detail":"{readStateIndex:2006; appliedIndex:2005; }","duration":"106.377918ms","start":"2026-02-17T16:08:48.214908Z","end":"2026-02-17T16:08:48.321286Z","steps":["trace[1222367153] 'read index received' (duration: 106.150564ms)","trace[1222367153] 'applied index is now lower than readState.Index' (duration: 226.184µs)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:08:48.321555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.625053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-02-17T16:08:48.321591Z","caller":"traceutil/trace.go:171","msg":"trace[336750640] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1926; }","duration":"106.681874ms","start":"2026-02-17T16:08:48.214896Z","end":"2026-02-17T16:08:48.321578Z","steps":["trace[336750640] 'agreement among raft nodes before linearized reading' (duration: 106.48655ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:48.32194Z","caller":"traceutil/trace.go:171","msg":"trace[1881064665] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"108.149121ms","start":"2026-02-17T16:08:48.213778Z","end":"2026-02-17T16:08:48.321928Z","steps":["trace[1881064665] 'process raft request' (duration: 107.337746ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:48.481185Z","caller":"traceutil/trace.go:171","msg":"trace[950501856] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2007; }","duration":"119.570702ms","start":"2026-02-17T16:08:48.361593Z","end":"2026-02-17T16:08:48.481164Z","steps":["trace[950501856] 'read index received' (duration: 45.09741ms)","trace[950501856] 'applied index is now lower than readState.Index' (duration: 74.472262ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:08:48.481318Z","caller":"traceutil/trace.go:171","msg":"trace[1396519976] transaction","detail":"{read_only:false; response_revision:1928; number_of_response:1; }","duration":"138.688584ms","start":"2026-02-17T16:08:48.342615Z","end":"2026-02-17T16:08:48.481304Z","steps":["trace[1396519976] 'process raft request' (duration: 64.126541ms)","trace[1396519976] 'compare' (duration: 74.185707ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:08:48.481616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.790939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-17T16:08:48.481644Z","caller":"traceutil/trace.go:171","msg":"trace[1072186902] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:1928; }","duration":"130.83337ms","start":"2026-02-17T16:08:48.350801Z","end":"2026-02-17T16:08:48.481634Z","steps":["trace[1072186902] 'agreement among raft nodes before linearized reading' (duration: 130.667086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:08:48.4818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.356174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-02-17T16:08:48.481821Z","caller":"traceutil/trace.go:171","msg":"trace[2007290366] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1928; }","duration":"115.404306ms","start":"2026-02-17T16:08:48.36641Z","end":"2026-02-17T16:08:48.481814Z","steps":["trace[2007290366] 'agreement among raft nodes before linearized reading' (duration: 115.331684ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:48.762722Z","caller":"traceutil/trace.go:171","msg":"trace[1434922334] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"272.395997ms","start":"2026-02-17T16:08:48.490303Z","end":"2026-02-17T16:08:48.762699Z","steps":["trace[1434922334] 'process raft request' (duration: 272.349676ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:08:48.763089Z","caller":"traceutil/trace.go:171","msg":"trace[1204532663] transaction","detail":"{read_only:false; response_revision:1929; number_of_response:1; }","duration":"274.794171ms","start":"2026-02-17T16:08:48.488285Z","end":"2026-02-17T16:08:48.763079Z","steps":["trace[1204532663] 'process raft request' (duration: 274.171189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:09:15.475011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.513956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-17T16:09:15.475714Z","caller":"traceutil/trace.go:171","msg":"trace[1924120770] linearizableReadLoop","detail":"{readStateIndex:2147; appliedIndex:2146; }","duration":"135.498846ms","start":"2026-02-17T16:09:15.340195Z","end":"2026-02-17T16:09:15.475694Z","steps":["trace[1924120770] 'read index received' (duration: 24.204936ms)","trace[1924120770] 'applied index is now lower than readState.Index' (duration: 111.29211ms)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:09:15.47593Z","caller":"traceutil/trace.go:171","msg":"trace[622441436] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"198.158622ms","start":"2026-02-17T16:09:15.277756Z","end":"2026-02-17T16:09:15.475915Z","steps":["trace[622441436] 'process raft request' (duration: 86.657077ms)","trace[622441436] 'compare' (duration: 110.258502ms)"],"step_count":2} {"level":"warn","ts":"2026-02-17T16:09:15.476302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.900798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-17T16:09:15.476517Z","caller":"traceutil/trace.go:171","msg":"trace[1186441360] range","detail":"{range_begin:/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config; range_end:; response_count:1; response_revision:2061; }","duration":"127.089922ms","start":"2026-02-17T16:09:15.349369Z","end":"2026-02-17T16:09:15.476459Z","steps":["trace[1186441360] 'agreement among raft nodes before linearized reading' (duration: 126.871698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:09:15.476973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.760381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-17T16:09:15.477023Z","caller":"traceutil/trace.go:171","msg":"trace[1405779928] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2061; }","duration":"136.843502ms","start":"2026-02-17T16:09:15.340167Z","end":"2026-02-17T16:09:15.47701Z","steps":["trace[1405779928] 'agreement among raft nodes before linearized reading' (duration: 136.73127ms)"],"step_count":1} {"level":"info","ts":"2026-02-17T16:09:15.736996Z","caller":"traceutil/trace.go:171","msg":"trace[736161727] linearizableReadLoop","detail":"{readStateIndex:2151; appliedIndex:2150; }","duration":"168.309231ms","start":"2026-02-17T16:09:15.568665Z","end":"2026-02-17T16:09:15.736975Z","steps":["trace[736161727] 'read index received' (duration: 168.107847ms)","trace[736161727] 'applied index is now lower than readState.Index' (duration: 200.504µs)"],"step_count":2} {"level":"info","ts":"2026-02-17T16:09:15.737246Z","caller":"traceutil/trace.go:171","msg":"trace[274607522] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"170.805377ms","start":"2026-02-17T16:09:15.5664Z","end":"2026-02-17T16:09:15.737205Z","steps":["trace[274607522] 'process raft request' (duration: 170.42688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:09:15.737485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.79823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-17T16:09:15.738045Z","caller":"traceutil/trace.go:171","msg":"trace[46026257] range","detail":"{range_begin:/registry/rolebindings/kube-system/kubeadm:nodes-kubeadm-config; range_end:; response_count:1; response_revision:2064; }","duration":"169.380471ms","start":"2026-02-17T16:09:15.568647Z","end":"2026-02-17T16:09:15.738027Z","steps":["trace[46026257] 'agreement among raft nodes before linearized reading' (duration: 168.77494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:09:15.738356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.057338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader\" ","response":"range_response_count:1 size:856"} {"level":"info","ts":"2026-02-17T16:09:15.738469Z","caller":"traceutil/trace.go:171","msg":"trace[1736208622] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::extension-apiserver-authentication-reader; range_end:; response_count:1; response_revision:2064; }","duration":"148.19878ms","start":"2026-02-17T16:09:15.590258Z","end":"2026-02-17T16:09:15.738456Z","steps":["trace[1736208622] 'agreement among raft nodes before linearized reading' (duration: 148.052787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:09:16.018835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.696602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-17T16:09:16.01899Z","caller":"traceutil/trace.go:171","msg":"trace[852360620] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2065; }","duration":"115.896106ms","start":"2026-02-17T16:09:15.903072Z","end":"2026-02-17T16:09:16.018968Z","steps":["trace[852360620] 'range keys from in-memory index tree' (duration: 115.6291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:09:16.019173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.008549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-controller-manager\" ","response":"range_response_count:1 size:853"} {"level":"info","ts":"2026-02-17T16:09:16.01925Z","caller":"traceutil/trace.go:171","msg":"trace[256070837] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::leader-locking-kube-controller-manager; range_end:; response_count:1; response_revision:2065; }","duration":"191.110501ms","start":"2026-02-17T16:09:15.82812Z","end":"2026-02-17T16:09:16.01923Z","steps":["trace[256070837] 'range keys from in-memory index tree' (duration: 190.924268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-17T16:09:16.019471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.293143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler\" ","response":"range_response_count:1 size:808"} {"level":"info","ts":"2026-02-17T16:09:16.020659Z","caller":"traceutil/trace.go:171","msg":"trace[260119450] range","detail":"{range_begin:/registry/rolebindings/kube-system/system::leader-locking-kube-scheduler; range_end:; response_count:1; response_revision:2065; }","duration":"187.487975ms","start":"2026-02-17T16:09:15.833155Z","end":"2026-02-17T16:09:16.020643Z","steps":["trace[260119450] 'range keys from in-memory index tree' (duration: 186.252872ms)"],"step_count":1}