{"level":"warn","ts":"2026-02-12T01:56:53.944628Z","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-12T01:56:53.944869Z","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-12T01:56:53.944987Z","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-12T01:56:53.945053Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-12T01:56:53.945122Z","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-12T01:56:53.945753Z","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-12T01:56:53.94591Z","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-12T01:56:53.950233Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.958533ms"} {"level":"info","ts":"2026-02-12T01:56:53.974041Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-02-12T01:56:53.974114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T01:56:53.974139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-02-12T01:56:53.974152Z","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-12T01:56:53.974159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-02-12T01:56:53.974194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-02-12T01:56:53.994188Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T01:56:54.000866Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T01:56:54.006941Z","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-12T01:56:54.0146Z","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-12T01:56:54.020855Z","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-12T01:56:54.021526Z","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-12T01:56:54.021602Z","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-12T01:56:54.021639Z","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-12T01:56:54.03113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-02-12T01:56:54.031284Z","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-12T01:56:54.031999Z","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-12T01:56:54.032227Z","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-12T01:56:54.032273Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T01:56:54.032238Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-12T01:56:54.033357Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-12T01:56:54.775229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T01:56:54.775266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T01:56:54.775287Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-02-12T01:56:54.775297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-02-12T01:56:54.775302Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-12T01:56:54.77531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-02-12T01:56:54.775317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-12T01:56:54.776258Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T01:56:54.776508Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:56:54.776249Z","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-12T01:56:54.780354Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T01:56:54.780495Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T01:56:54.780504Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T01:56:54.781752Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-02-12T01:56:54.781809Z","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-12T01:56:54.781847Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:56:54.781858Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T01:56:54.789915Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T01:57:02.869917Z","caller":"traceutil/trace.go:171","msg":"trace[596423114] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"580.601996ms","start":"2026-02-12T01:57:02.289293Z","end":"2026-02-12T01:57:02.869895Z","steps":["trace[596423114] 'process raft request' (duration: 580.450233ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:02.870465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:57:02.289282Z","time spent":"580.716385ms","remote":"127.0.0.1:35716","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-12T01:57:04.065302Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.195497002s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T01:57:04.066804Z","caller":"traceutil/trace.go:171","msg":"trace[1875233657] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:349; }","duration":"1.593133388s","start":"2026-02-12T01:57:02.473648Z","end":"2026-02-12T01:57:04.066782Z","steps":["trace[1875233657] 'read index received' (duration: 396.289298ms)","trace[1875233657] 'applied index is now lower than readState.Index' (duration: 1.19684301s)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:57:04.066967Z","caller":"traceutil/trace.go:171","msg":"trace[361189766] transaction","detail":"{read_only:false; response_revision:338; number_of_response:1; }","duration":"1.777522083s","start":"2026-02-12T01:57:02.28943Z","end":"2026-02-12T01:57:04.066953Z","steps":["trace[361189766] 'process raft request' (duration: 1.776086857s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:04.068869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:57:02.289423Z","time spent":"1.779332952s","remote":"127.0.0.1:35716","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-12T01:57:04.067295Z","caller":"traceutil/trace.go:171","msg":"trace[1415945853] transaction","detail":"{read_only:false; response_revision:339; number_of_response:1; }","duration":"1.741475217s","start":"2026-02-12T01:57:02.325809Z","end":"2026-02-12T01:57:04.067284Z","steps":["trace[1415945853] 'process raft request' (duration: 1.740926608s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:04.069157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:57:02.325793Z","time spent":"1.743341261s","remote":"127.0.0.1:35642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":180,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T01:57:04.067382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.59371866s","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-12T01:57:04.069306Z","caller":"traceutil/trace.go:171","msg":"trace[1367456004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:339; }","duration":"1.595690934s","start":"2026-02-12T01:57:02.473597Z","end":"2026-02-12T01:57:04.069288Z","steps":["trace[1367456004] 'agreement among raft nodes before linearized reading' (duration: 1.593732371s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:04.069351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:57:02.473505Z","time spent":"1.595836226s","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T01:57:04.067585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.477177368s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T01:57:04.069492Z","caller":"traceutil/trace.go:171","msg":"trace[156887947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:339; }","duration":"1.479107288s","start":"2026-02-12T01:57:02.590375Z","end":"2026-02-12T01:57:04.069483Z","steps":["trace[156887947] 'agreement among raft nodes before linearized reading' (duration: 1.477175888s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:04.069528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:57:02.590362Z","time spent":"1.479158342s","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T01:57:04.068525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.415851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4260"} {"level":"info","ts":"2026-02-12T01:57:04.070614Z","caller":"traceutil/trace.go:171","msg":"trace[200123340] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:339; }","duration":"428.536518ms","start":"2026-02-12T01:57:03.642066Z","end":"2026-02-12T01:57:04.070602Z","steps":["trace[200123340] 'agreement among raft nodes before linearized reading' (duration: 426.411731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:04.070658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:57:03.642048Z","time spent":"428.597973ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4282,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"info","ts":"2026-02-12T01:57:07.676334Z","caller":"traceutil/trace.go:171","msg":"trace[966248431] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"201.987035ms","start":"2026-02-12T01:57:07.474322Z","end":"2026-02-12T01:57:07.676309Z","steps":["trace[966248431] 'process raft request' (duration: 201.850833ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:57:32.346665Z","caller":"traceutil/trace.go:171","msg":"trace[1496143391] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:573; }","duration":"121.059912ms","start":"2026-02-12T01:57:32.22559Z","end":"2026-02-12T01:57:32.34665Z","steps":["trace[1496143391] 'read index received' (duration: 120.934343ms)","trace[1496143391] 'applied index is now lower than readState.Index' (duration: 123.089µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:57:32.346882Z","caller":"traceutil/trace.go:171","msg":"trace[232742894] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"123.082041ms","start":"2026-02-12T01:57:32.223794Z","end":"2026-02-12T01:57:32.346876Z","steps":["trace[232742894] 'process raft request' (duration: 122.737526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:32.347062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.460101ms","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-12T01:57:32.347094Z","caller":"traceutil/trace.go:171","msg":"trace[522444274] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"121.516216ms","start":"2026-02-12T01:57:32.225571Z","end":"2026-02-12T01:57:32.347087Z","steps":["trace[522444274] 'agreement among raft nodes before linearized reading' (duration: 121.417419ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:32.3472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.172258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-12T01:57:32.347213Z","caller":"traceutil/trace.go:171","msg":"trace[1521066950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:555; }","duration":"107.186599ms","start":"2026-02-12T01:57:32.240023Z","end":"2026-02-12T01:57:32.347209Z","steps":["trace[1521066950] 'agreement among raft nodes before linearized reading' (duration: 107.146866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:40.314319Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.091915203s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T01:57:40.315089Z","caller":"traceutil/trace.go:171","msg":"trace[551678723] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"1.092435718s","start":"2026-02-12T01:57:39.222318Z","end":"2026-02-12T01:57:40.314754Z","steps":["trace[551678723] 'process raft request' (duration: 1.092221712s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:40.315269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:57:39.222306Z","time spent":"1.092866949s","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:57:40.684695Z","caller":"traceutil/trace.go:171","msg":"trace[224967616] linearizableReadLoop","detail":"{readStateIndex:594; appliedIndex:593; }","duration":"312.165005ms","start":"2026-02-12T01:57:40.372488Z","end":"2026-02-12T01:57:40.684653Z","steps":["trace[224967616] 'read index received' (duration: 287.015802ms)","trace[224967616] 'applied index is now lower than readState.Index' (duration: 25.147243ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:57:40.685035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.525261ms","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-12T01:57:40.685119Z","caller":"traceutil/trace.go:171","msg":"trace[1462460212] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"312.646883ms","start":"2026-02-12T01:57:40.372455Z","end":"2026-02-12T01:57:40.685102Z","steps":["trace[1462460212] 'agreement among raft nodes before linearized reading' (duration: 312.467745ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:40.685159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:57:40.372437Z","time spent":"312.716909ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T01:57:40.68521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.892937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T01:57:40.685251Z","caller":"traceutil/trace.go:171","msg":"trace[185253679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"213.009309ms","start":"2026-02-12T01:57:40.472228Z","end":"2026-02-12T01:57:40.685238Z","steps":["trace[185253679] 'agreement among raft nodes before linearized reading' (duration: 212.808032ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:57:40.685373Z","caller":"traceutil/trace.go:171","msg":"trace[1676757872] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"667.429674ms","start":"2026-02-12T01:57:40.017929Z","end":"2026-02-12T01:57:40.685358Z","steps":["trace[1676757872] 'process raft request' (duration: 641.623285ms)","trace[1676757872] 'compare' (duration: 24.696693ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:57:40.685445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:57:40.017901Z","time spent":"667.49457ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:57:40.685497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.343603ms","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-12T01:57:40.68552Z","caller":"traceutil/trace.go:171","msg":"trace[1327170991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:573; }","duration":"212.377411ms","start":"2026-02-12T01:57:40.473136Z","end":"2026-02-12T01:57:40.685513Z","steps":["trace[1327170991] 'agreement among raft nodes before linearized reading' (duration: 212.312415ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:57:40.835399Z","caller":"traceutil/trace.go:171","msg":"trace[1207241547] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"144.700807ms","start":"2026-02-12T01:57:40.690682Z","end":"2026-02-12T01:57:40.835383Z","steps":["trace[1207241547] 'process raft request' (duration: 137.26681ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:57:40.898089Z","caller":"traceutil/trace.go:171","msg":"trace[1711470041] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"194.370205ms","start":"2026-02-12T01:57:40.703684Z","end":"2026-02-12T01:57:40.898055Z","steps":["trace[1711470041] 'process raft request' (duration: 194.319148ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:57:40.898691Z","caller":"traceutil/trace.go:171","msg":"trace[1227510412] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"207.939795ms","start":"2026-02-12T01:57:40.690743Z","end":"2026-02-12T01:57:40.898683Z","steps":["trace[1227510412] 'process raft request' (duration: 207.101851ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:57:43.868519Z","caller":"traceutil/trace.go:171","msg":"trace[238280623] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"290.326828ms","start":"2026-02-12T01:57:43.578168Z","end":"2026-02-12T01:57:43.868494Z","steps":["trace[238280623] 'process raft request' (duration: 290.166918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:45.520986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.531816865s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:57:45.521046Z","caller":"traceutil/trace.go:171","msg":"trace[209314051] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"1.531931499s","start":"2026-02-12T01:57:43.989101Z","end":"2026-02-12T01:57:45.521033Z","steps":["trace[209314051] 'range keys from in-memory index tree' (duration: 1.531705392s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:45.521071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:57:43.989083Z","time spent":"1.531983175s","remote":"127.0.0.1:35444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T01:57:45.521274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.196138178s","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-12T01:57:45.521288Z","caller":"traceutil/trace.go:171","msg":"trace[241197433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"1.196182955s","start":"2026-02-12T01:57:44.3251Z","end":"2026-02-12T01:57:45.521283Z","steps":["trace[241197433] 'range keys from in-memory index tree' (duration: 1.196029973s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:45.521302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:57:44.325084Z","time spent":"1.196213233s","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T01:57:45.521408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.117076699s","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-12T01:57:45.521443Z","caller":"traceutil/trace.go:171","msg":"trace[352621554] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:584; }","duration":"1.117115147s","start":"2026-02-12T01:57:44.404324Z","end":"2026-02-12T01:57:45.521439Z","steps":["trace[352621554] 'range keys from in-memory index tree' (duration: 1.117072649s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:45.521664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.021192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T01:57:45.521678Z","caller":"traceutil/trace.go:171","msg":"trace[412553457] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:584; }","duration":"677.06383ms","start":"2026-02-12T01:57:44.844609Z","end":"2026-02-12T01:57:45.521673Z","steps":["trace[412553457] 'range keys from in-memory index tree' (duration: 676.953586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:45.52169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:57:44.844596Z","time spent":"677.091238ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T01:57:45.521782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.404954ms","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-12T01:57:45.521794Z","caller":"traceutil/trace.go:171","msg":"trace[877803015] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:584; }","duration":"613.474311ms","start":"2026-02-12T01:57:44.908316Z","end":"2026-02-12T01:57:45.521791Z","steps":["trace[877803015] 'range keys from in-memory index tree' (duration: 613.269872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:45.521806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:57:44.908284Z","time spent":"613.518968ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T01:57:45.521891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.118158ms","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-12T01:57:45.521903Z","caller":"traceutil/trace.go:171","msg":"trace[1831445304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"608.157987ms","start":"2026-02-12T01:57:44.913741Z","end":"2026-02-12T01:57:45.521899Z","steps":["trace[1831445304] 'range keys from in-memory index tree' (duration: 608.045512ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:57:45.521923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:57:44.913728Z","time spent":"608.192085ms","remote":"127.0.0.1:35716","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":"info","ts":"2026-02-12T01:58:04.093272Z","caller":"traceutil/trace.go:171","msg":"trace[1864421360] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"119.177985ms","start":"2026-02-12T01:58:03.974074Z","end":"2026-02-12T01:58:04.093252Z","steps":["trace[1864421360] 'process raft request' (duration: 53.982354ms)","trace[1864421360] 'compare' (duration: 64.999326ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:58:04.093421Z","caller":"traceutil/trace.go:171","msg":"trace[178552415] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"109.181464ms","start":"2026-02-12T01:58:03.984219Z","end":"2026-02-12T01:58:04.0934Z","steps":["trace[178552415] 'process raft request' (duration: 108.967719ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:04.593041Z","caller":"traceutil/trace.go:171","msg":"trace[1496741639] linearizableReadLoop","detail":"{readStateIndex:805; appliedIndex:801; }","duration":"212.238758ms","start":"2026-02-12T01:58:04.380788Z","end":"2026-02-12T01:58:04.593027Z","steps":["trace[1496741639] 'read index received' (duration: 62.107537ms)","trace[1496741639] 'applied index is now lower than readState.Index' (duration: 150.130731ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:58:04.593115Z","caller":"traceutil/trace.go:171","msg":"trace[1709526566] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"212.681268ms","start":"2026-02-12T01:58:04.380427Z","end":"2026-02-12T01:58:04.593109Z","steps":["trace[1709526566] 'process raft request' (duration: 212.382005ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:04.593334Z","caller":"traceutil/trace.go:171","msg":"trace[1636948014] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"212.839586ms","start":"2026-02-12T01:58:04.380487Z","end":"2026-02-12T01:58:04.593327Z","steps":["trace[1636948014] 'process raft request' (duration: 212.421415ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:04.593478Z","caller":"traceutil/trace.go:171","msg":"trace[486589478] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"212.757007ms","start":"2026-02-12T01:58:04.380715Z","end":"2026-02-12T01:58:04.593472Z","steps":["trace[486589478] 'process raft request' (duration: 212.232148ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:04.593689Z","caller":"traceutil/trace.go:171","msg":"trace[1498435970] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"209.871699ms","start":"2026-02-12T01:58:04.38379Z","end":"2026-02-12T01:58:04.593661Z","steps":["trace[1498435970] 'process raft request' (duration: 209.203283ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:04.59381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.029891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:58:04.593834Z","caller":"traceutil/trace.go:171","msg":"trace[1915788091] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:777; }","duration":"213.0662ms","start":"2026-02-12T01:58:04.38076Z","end":"2026-02-12T01:58:04.593826Z","steps":["trace[1915788091] 'agreement among raft nodes before linearized reading' (duration: 213.037871ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:04.593924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.041621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:58:04.593942Z","caller":"traceutil/trace.go:171","msg":"trace[2004131016] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:777; }","duration":"213.10476ms","start":"2026-02-12T01:58:04.380833Z","end":"2026-02-12T01:58:04.593938Z","steps":["trace[2004131016] 'agreement among raft nodes before linearized reading' (duration: 213.07849ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:04.593783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.939783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:58:04.594308Z","caller":"traceutil/trace.go:171","msg":"trace[2089782066] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:777; }","duration":"213.483772ms","start":"2026-02-12T01:58:04.380817Z","end":"2026-02-12T01:58:04.594301Z","steps":["trace[2089782066] 'agreement among raft nodes before linearized reading' (duration: 212.927614ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:05.255531Z","caller":"traceutil/trace.go:171","msg":"trace[2097113585] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"161.512835ms","start":"2026-02-12T01:58:05.094001Z","end":"2026-02-12T01:58:05.255514Z","steps":["trace[2097113585] 'process raft request' (duration: 161.178572ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:05.967695Z","caller":"traceutil/trace.go:171","msg":"trace[1916825717] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"123.123375ms","start":"2026-02-12T01:58:05.844549Z","end":"2026-02-12T01:58:05.967672Z","steps":["trace[1916825717] 'process raft request' (duration: 122.964758ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:06.353816Z","caller":"traceutil/trace.go:171","msg":"trace[1974466901] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"508.458613ms","start":"2026-02-12T01:58:05.845333Z","end":"2026-02-12T01:58:06.353792Z","steps":["trace[1974466901] 'process raft request' (duration: 440.510105ms)","trace[1974466901] 'compare' (duration: 67.597455ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:58:06.353934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:05.845311Z","time spent":"508.565901ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:58:06.353985Z","caller":"traceutil/trace.go:171","msg":"trace[921291103] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:810; }","duration":"507.941453ms","start":"2026-02-12T01:58:05.846017Z","end":"2026-02-12T01:58:06.353958Z","steps":["trace[921291103] 'read index received' (duration: 122.141435ms)","trace[921291103] 'applied index is now lower than readState.Index' (duration: 385.799088ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:58:06.354096Z","caller":"traceutil/trace.go:171","msg":"trace[86024919] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"502.478922ms","start":"2026-02-12T01:58:05.851607Z","end":"2026-02-12T01:58:06.354086Z","steps":["trace[86024919] 'process raft request' (duration: 502.118999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:06.354181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:05.851599Z","time spent":"502.521481ms","remote":"127.0.0.1:35516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":723,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T01:58:06.354235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.57752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-ts8p9\" ","response":"range_response_count:1 size:4824"} {"level":"info","ts":"2026-02-12T01:58:06.354257Z","caller":"traceutil/trace.go:171","msg":"trace[240423179] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-ts8p9; range_end:; response_count:1; response_revision:783; }","duration":"502.60932ms","start":"2026-02-12T01:58:05.85164Z","end":"2026-02-12T01:58:06.35425Z","steps":["trace[240423179] 'agreement among raft nodes before linearized reading' (duration: 502.503442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:06.354273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:05.851637Z","time spent":"502.633419ms","remote":"127.0.0.1:35630","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4846,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-ts8p9\" "} {"level":"warn","ts":"2026-02-12T01:58:06.354389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.373046ms","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-12T01:58:06.354403Z","caller":"traceutil/trace.go:171","msg":"trace[410039331] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:783; }","duration":"508.404615ms","start":"2026-02-12T01:58:05.845994Z","end":"2026-02-12T01:58:06.354399Z","steps":["trace[410039331] 'agreement among raft nodes before linearized reading' (duration: 508.360786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:06.354419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:05.845986Z","time spent":"508.428195ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T01:58:06.354496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.999061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:58:06.354509Z","caller":"traceutil/trace.go:171","msg":"trace[1209368000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:783; }","duration":"364.5272ms","start":"2026-02-12T01:58:05.989978Z","end":"2026-02-12T01:58:06.354505Z","steps":["trace[1209368000] 'agreement among raft nodes before linearized reading' (duration: 364.500741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:06.35452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:05.989963Z","time spent":"364.55513ms","remote":"127.0.0.1:35444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T01:58:06.354643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.426612ms","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-12T01:58:06.354679Z","caller":"traceutil/trace.go:171","msg":"trace[863605090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:783; }","duration":"449.48803ms","start":"2026-02-12T01:58:05.90518Z","end":"2026-02-12T01:58:06.354668Z","steps":["trace[863605090] 'agreement among raft nodes before linearized reading' (duration: 449.366733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:06.354711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:05.90516Z","time spent":"449.54445ms","remote":"127.0.0.1:35716","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-12T01:58:06.573269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.538165ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T01:58:06.573567Z","caller":"traceutil/trace.go:171","msg":"trace[405192397] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"182.473457ms","start":"2026-02-12T01:58:06.391072Z","end":"2026-02-12T01:58:06.573545Z","steps":["trace[405192397] 'process raft request' (duration: 80.439661ms)","trace[405192397] 'compare' (duration: 101.422067ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:58:06.573691Z","caller":"traceutil/trace.go:171","msg":"trace[1598823048] transaction","detail":"{read_only:false; response_revision:785; number_of_response:1; }","duration":"176.755051ms","start":"2026-02-12T01:58:06.396918Z","end":"2026-02-12T01:58:06.573673Z","steps":["trace[1598823048] 'process raft request' (duration: 176.450307ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:06.940378Z","caller":"traceutil/trace.go:171","msg":"trace[2017002714] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"149.660616ms","start":"2026-02-12T01:58:06.790699Z","end":"2026-02-12T01:58:06.940359Z","steps":["trace[2017002714] 'process raft request' (duration: 149.515098ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:07.942012Z","caller":"traceutil/trace.go:171","msg":"trace[1693013055] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"230.133296ms","start":"2026-02-12T01:58:07.711862Z","end":"2026-02-12T01:58:07.941995Z","steps":["trace[1693013055] 'read index received' (duration: 229.991839ms)","trace[1693013055] 'applied index is now lower than readState.Index' (duration: 140.747µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:58:07.942147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.266414ms","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-12T01:58:07.942173Z","caller":"traceutil/trace.go:171","msg":"trace[324062578] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:788; }","duration":"230.333443ms","start":"2026-02-12T01:58:07.711835Z","end":"2026-02-12T01:58:07.942168Z","steps":["trace[324062578] 'agreement among raft nodes before linearized reading' (duration: 230.223215ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:07.942216Z","caller":"traceutil/trace.go:171","msg":"trace[1354090855] transaction","detail":"{read_only:false; response_revision:788; number_of_response:1; }","duration":"422.993899ms","start":"2026-02-12T01:58:07.519199Z","end":"2026-02-12T01:58:07.942192Z","steps":["trace[1354090855] 'process raft request' (duration: 422.673604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:07.942329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:07.519179Z","time spent":"423.083937ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:58:08.226479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.801873ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-02-12T01:58:08.226572Z","caller":"traceutil/trace.go:171","msg":"trace[849829711] linearizableReadLoop","detail":"{readStateIndex:818; appliedIndex:817; }","duration":"249.464219ms","start":"2026-02-12T01:58:07.977098Z","end":"2026-02-12T01:58:08.226563Z","steps":["trace[849829711] 'read index received' (duration: 37.526747ms)","trace[849829711] 'applied index is now lower than readState.Index' (duration: 211.936292ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:58:08.226702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.596617ms","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-12T01:58:08.226716Z","caller":"traceutil/trace.go:171","msg":"trace[981673500] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:788; }","duration":"249.645656ms","start":"2026-02-12T01:58:07.977066Z","end":"2026-02-12T01:58:08.226711Z","steps":["trace[981673500] 'agreement among raft nodes before linearized reading' (duration: 249.553987ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:08.226917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.887903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:58:08.226949Z","caller":"traceutil/trace.go:171","msg":"trace[222322964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"237.935293ms","start":"2026-02-12T01:58:07.989009Z","end":"2026-02-12T01:58:08.226945Z","steps":["trace[222322964] 'agreement among raft nodes before linearized reading' (duration: 237.888884ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:08.44429Z","caller":"traceutil/trace.go:171","msg":"trace[1939087740] linearizableReadLoop","detail":"{readStateIndex:820; appliedIndex:819; }","duration":"131.756921ms","start":"2026-02-12T01:58:08.312517Z","end":"2026-02-12T01:58:08.444274Z","steps":["trace[1939087740] 'read index received' (duration: 111.194286ms)","trace[1939087740] 'applied index is now lower than readState.Index' (duration: 20.561885ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:58:08.444357Z","caller":"traceutil/trace.go:171","msg":"trace[169082228] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"211.208433ms","start":"2026-02-12T01:58:08.233143Z","end":"2026-02-12T01:58:08.444351Z","steps":["trace[169082228] 'process raft request' (duration: 190.614158ms)","trace[169082228] 'compare' (duration: 20.436357ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:58:08.444433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.899039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-02-12T01:58:08.444486Z","caller":"traceutil/trace.go:171","msg":"trace[406450063] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:790; }","duration":"131.959338ms","start":"2026-02-12T01:58:08.312512Z","end":"2026-02-12T01:58:08.444472Z","steps":["trace[406450063] 'agreement among raft nodes before linearized reading' (duration: 131.859209ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:08.826201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.534746ms","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-12T01:58:08.826256Z","caller":"traceutil/trace.go:171","msg":"trace[1988646186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:792; }","duration":"247.625064ms","start":"2026-02-12T01:58:08.578618Z","end":"2026-02-12T01:58:08.826243Z","steps":["trace[1988646186] 'range keys from in-memory index tree' (duration: 247.415848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:08.826403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.732847ms","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-12T01:58:08.826447Z","caller":"traceutil/trace.go:171","msg":"trace[1951181658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:792; }","duration":"248.815056ms","start":"2026-02-12T01:58:08.577621Z","end":"2026-02-12T01:58:08.826436Z","steps":["trace[1951181658] 'range keys from in-memory index tree' (duration: 248.605419ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:08.994004Z","caller":"traceutil/trace.go:171","msg":"trace[584548273] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:823; }","duration":"134.311001ms","start":"2026-02-12T01:58:08.85967Z","end":"2026-02-12T01:58:08.993981Z","steps":["trace[584548273] 'read index received' (duration: 20.989208ms)","trace[584548273] 'applied index is now lower than readState.Index' (duration: 113.320953ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:58:08.994201Z","caller":"traceutil/trace.go:171","msg":"trace[1125713008] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"163.037361ms","start":"2026-02-12T01:58:08.831154Z","end":"2026-02-12T01:58:08.994191Z","steps":["trace[1125713008] 'process raft request' (duration: 162.622278ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:08.994421Z","caller":"traceutil/trace.go:171","msg":"trace[1564181331] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"162.48316ms","start":"2026-02-12T01:58:08.831929Z","end":"2026-02-12T01:58:08.994412Z","steps":["trace[1564181331] 'process raft request' (duration: 162.000897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:08.994659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.967771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-6f56467975-sgdjl\" ","response":"range_response_count:1 size:3796"} {"level":"info","ts":"2026-02-12T01:58:08.994693Z","caller":"traceutil/trace.go:171","msg":"trace[799297563] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-6f56467975-sgdjl; range_end:; response_count:1; response_revision:795; }","duration":"135.03162ms","start":"2026-02-12T01:58:08.85965Z","end":"2026-02-12T01:58:08.994681Z","steps":["trace[799297563] 'agreement among raft nodes before linearized reading' (duration: 134.898712ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:09.238685Z","caller":"traceutil/trace.go:171","msg":"trace[914597750] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"235.953293ms","start":"2026-02-12T01:58:09.002664Z","end":"2026-02-12T01:58:09.238617Z","steps":["trace[914597750] 'process raft request' (duration: 183.893736ms)","trace[914597750] 'compare' (duration: 51.928389ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:58:09.410142Z","caller":"traceutil/trace.go:171","msg":"trace[176494445] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:826; }","duration":"101.812435ms","start":"2026-02-12T01:58:09.308311Z","end":"2026-02-12T01:58:09.410124Z","steps":["trace[176494445] 'read index received' (duration: 79.515405ms)","trace[176494445] 'applied index is now lower than readState.Index' (duration: 22.29609ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:58:09.41032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.990033ms","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-12T01:58:09.41034Z","caller":"traceutil/trace.go:171","msg":"trace[1982248983] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:797; }","duration":"102.053032ms","start":"2026-02-12T01:58:09.308279Z","end":"2026-02-12T01:58:09.410332Z","steps":["trace[1982248983] 'agreement among raft nodes before linearized reading' (duration: 101.905804ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:09.410464Z","caller":"traceutil/trace.go:171","msg":"trace[1042483447] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"163.806595ms","start":"2026-02-12T01:58:09.246626Z","end":"2026-02-12T01:58:09.410433Z","steps":["trace[1042483447] 'process raft request' (duration: 141.265028ms)","trace[1042483447] 'compare' (duration: 22.153322ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:58:11.993204Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088396062547122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T01:58:12.494122Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088396062547122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T01:58:12.994463Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088396062547122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T01:58:13.49532Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088396062547122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T01:58:13.691245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.692551835s","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-12T01:58:13.691351Z","caller":"traceutil/trace.go:171","msg":"trace[1891719942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:802; }","duration":"2.692694224s","start":"2026-02-12T01:58:10.998632Z","end":"2026-02-12T01:58:13.691327Z","steps":["trace[1891719942] 'range keys from in-memory index tree' (duration: 2.692324777s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:13.691415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:10.998625Z","time spent":"2.692779563s","remote":"127.0.0.1:35716","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-12T01:58:13.992168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000055152s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-12T01:58:13.992268Z","caller":"traceutil/trace.go:171","msg":"trace[155750895] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00022241s","start":"2026-02-12T01:58:11.992027Z","end":"2026-02-12T01:58:13.992249Z","steps":["trace[155750895] 'agreement among raft nodes before linearized reading' (duration: 2.000104051s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:13.992318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:11.991998Z","time spent":"2.00031006s","remote":"127.0.0.1:35444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T01:58:13.996067Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088396062547122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T01:58:14.496661Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088396062547122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T01:58:14.638716Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.599048213s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T01:58:14.655665Z","caller":"traceutil/trace.go:171","msg":"trace[1588015454] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"3.61507742s","start":"2026-02-12T01:58:11.039563Z","end":"2026-02-12T01:58:14.654641Z","steps":["trace[1588015454] 'process raft request' (duration: 3.614906162s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:14.656567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:11.039542Z","time spent":"3.616936966s","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T01:58:14.669468Z","caller":"traceutil/trace.go:171","msg":"trace[223031664] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"2.77869061s","start":"2026-02-12T01:58:11.890759Z","end":"2026-02-12T01:58:14.669449Z","steps":["trace[223031664] 'process raft request' (duration: 2.778434772s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:14.66957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:11.890741Z","time spent":"2.778771709s","remote":"127.0.0.1:35516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":817,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T01:58:14.69328Z","caller":"traceutil/trace.go:171","msg":"trace[873250898] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:832; }","duration":"3.200494012s","start":"2026-02-12T01:58:11.492769Z","end":"2026-02-12T01:58:14.693263Z","steps":["trace[873250898] 'read index received' (duration: 3.161679705s)","trace[873250898] 'applied index is now lower than readState.Index' (duration: 38.813507ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:58:14.694029Z","caller":"traceutil/trace.go:171","msg":"trace[2029387401] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"976.159591ms","start":"2026-02-12T01:58:13.71786Z","end":"2026-02-12T01:58:14.69402Z","steps":["trace[2029387401] 'process raft request' (duration: 974.341714ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:14.694573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:13.717836Z","time spent":"976.213891ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:58:14.694733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.201964381s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-12T01:58:14.694754Z","caller":"traceutil/trace.go:171","msg":"trace[2094721950] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"3.201984931s","start":"2026-02-12T01:58:11.492763Z","end":"2026-02-12T01:58:14.694748Z","steps":["trace[2094721950] 'agreement among raft nodes before linearized reading' (duration: 3.201905772s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:14.694768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:11.492718Z","time spent":"3.20204724s","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T01:58:14.694865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.182520521s","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-12T01:58:14.694882Z","caller":"traceutil/trace.go:171","msg":"trace[1751203243] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:805; }","duration":"2.182569201s","start":"2026-02-12T01:58:12.512309Z","end":"2026-02-12T01:58:14.694878Z","steps":["trace[1751203243] 'agreement among raft nodes before linearized reading' (duration: 2.182527582s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:14.694895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:12.51229Z","time spent":"2.182602151s","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T01:58:14.697654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"704.357862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:58:14.697723Z","caller":"traceutil/trace.go:171","msg":"trace[274905509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"704.441881ms","start":"2026-02-12T01:58:13.993267Z","end":"2026-02-12T01:58:14.697709Z","steps":["trace[274905509] 'agreement among raft nodes before linearized reading' (duration: 704.262893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:14.697756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:13.993192Z","time spent":"704.558351ms","remote":"127.0.0.1:35454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T01:58:14.69805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.964622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T01:58:14.698073Z","caller":"traceutil/trace.go:171","msg":"trace[708748055] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:805; }","duration":"618.016262ms","start":"2026-02-12T01:58:14.080047Z","end":"2026-02-12T01:58:14.698064Z","steps":["trace[708748055] 'agreement among raft nodes before linearized reading' (duration: 617.972912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:14.69809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:14.080035Z","time spent":"618.050512ms","remote":"127.0.0.1:35884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":29,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-02-12T01:58:14.698314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.295717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T01:58:14.698341Z","caller":"traceutil/trace.go:171","msg":"trace[555237933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"836.367327ms","start":"2026-02-12T01:58:13.861967Z","end":"2026-02-12T01:58:14.698334Z","steps":["trace[555237933] 'agreement among raft nodes before linearized reading' (duration: 836.318167ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:14.698358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:13.861944Z","time spent":"836.410176ms","remote":"127.0.0.1:35444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-12T01:58:14.698509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.13738824s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T01:58:14.698531Z","caller":"traceutil/trace.go:171","msg":"trace[1318803914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:805; }","duration":"2.137436259s","start":"2026-02-12T01:58:12.561089Z","end":"2026-02-12T01:58:14.698525Z","steps":["trace[1318803914] 'agreement among raft nodes before linearized reading' (duration: 2.13731049s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:14.698551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:12.561077Z","time spent":"2.137470339s","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-12T01:58:14.698614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.75841195s","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-12T01:58:14.698632Z","caller":"traceutil/trace.go:171","msg":"trace[1198721762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:805; }","duration":"1.75847973s","start":"2026-02-12T01:58:12.940147Z","end":"2026-02-12T01:58:14.698627Z","steps":["trace[1198721762] 'agreement among raft nodes before linearized reading' (duration: 1.75842936s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:58:14.698646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:58:12.940119Z","time spent":"1.75852328s","remote":"127.0.0.1:35716","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-12T01:58:14.814998Z","caller":"traceutil/trace.go:171","msg":"trace[1507203795] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"103.392393ms","start":"2026-02-12T01:58:14.711558Z","end":"2026-02-12T01:58:14.81495Z","steps":["trace[1507203795] 'process raft request' (duration: 102.935546ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:14.815099Z","caller":"traceutil/trace.go:171","msg":"trace[335795232] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"103.502002ms","start":"2026-02-12T01:58:14.711592Z","end":"2026-02-12T01:58:14.815094Z","steps":["trace[335795232] 'process raft request' (duration: 102.984475ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:14.815154Z","caller":"traceutil/trace.go:171","msg":"trace[543393214] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"101.024279ms","start":"2026-02-12T01:58:14.714124Z","end":"2026-02-12T01:58:14.815148Z","steps":["trace[543393214] 'process raft request' (duration: 100.470603ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:58:47.084987Z","caller":"traceutil/trace.go:171","msg":"trace[2058891547] transaction","detail":"{read_only:false; response_revision:901; number_of_response:1; }","duration":"169.131157ms","start":"2026-02-12T01:58:46.915837Z","end":"2026-02-12T01:58:47.084968Z","steps":["trace[2058891547] 'process raft request' (duration: 168.892321ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:59:07.544951Z","caller":"traceutil/trace.go:171","msg":"trace[949507750] linearizableReadLoop","detail":"{readStateIndex:1038; appliedIndex:1037; }","duration":"281.180166ms","start":"2026-02-12T01:59:07.263748Z","end":"2026-02-12T01:59:07.544928Z","steps":["trace[949507750] 'read index received' (duration: 280.962337ms)","trace[949507750] 'applied index is now lower than readState.Index' (duration: 216.969µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:59:07.545227Z","caller":"traceutil/trace.go:171","msg":"trace[1859782466] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"289.218631ms","start":"2026-02-12T01:59:07.255996Z","end":"2026-02-12T01:59:07.545215Z","steps":["trace[1859782466] 'process raft request' (duration: 288.781603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:59:07.545553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.78526ms","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-12T01:59:07.545592Z","caller":"traceutil/trace.go:171","msg":"trace[71141055] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:995; }","duration":"281.858253ms","start":"2026-02-12T01:59:07.263723Z","end":"2026-02-12T01:59:07.545581Z","steps":["trace[71141055] 'agreement among raft nodes before linearized reading' (duration: 281.691487ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:59:07.545793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.285783ms","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-12T01:59:07.545821Z","caller":"traceutil/trace.go:171","msg":"trace[2082367420] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:995; }","duration":"259.356346ms","start":"2026-02-12T01:59:07.286456Z","end":"2026-02-12T01:59:07.545813Z","steps":["trace[2082367420] 'agreement among raft nodes before linearized reading' (duration: 259.273052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:59:07.546238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.770649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-02-12T01:59:07.546269Z","caller":"traceutil/trace.go:171","msg":"trace[826650162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:995; }","duration":"249.836783ms","start":"2026-02-12T01:59:07.296422Z","end":"2026-02-12T01:59:07.546259Z","steps":["trace[826650162] 'agreement among raft nodes before linearized reading' (duration: 249.755009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:59:07.54642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.129105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-12T01:59:07.546447Z","caller":"traceutil/trace.go:171","msg":"trace[1661585804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:995; }","duration":"252.195468ms","start":"2026-02-12T01:59:07.294243Z","end":"2026-02-12T01:59:07.546439Z","steps":["trace[1661585804] 'agreement among raft nodes before linearized reading' (duration: 252.123225ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:59:31.859741Z","caller":"traceutil/trace.go:171","msg":"trace[1956789387] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"126.743758ms","start":"2026-02-12T01:59:31.732975Z","end":"2026-02-12T01:59:31.859719Z","steps":["trace[1956789387] 'process raft request' (duration: 107.032689ms)","trace[1956789387] 'compare' (duration: 19.33269ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:59:31.859948Z","caller":"traceutil/trace.go:171","msg":"trace[2114073743] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"124.963527ms","start":"2026-02-12T01:59:31.734974Z","end":"2026-02-12T01:59:31.859938Z","steps":["trace[2114073743] 'process raft request' (duration: 124.45863ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:59:31.860059Z","caller":"traceutil/trace.go:171","msg":"trace[117595018] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"124.978117ms","start":"2026-02-12T01:59:31.735073Z","end":"2026-02-12T01:59:31.860051Z","steps":["trace[117595018] 'process raft request' (duration: 124.395837ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:59:31.860401Z","caller":"traceutil/trace.go:171","msg":"trace[1520459037] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"125.279253ms","start":"2026-02-12T01:59:31.735111Z","end":"2026-02-12T01:59:31.86039Z","steps":["trace[1520459037] 'process raft request' (duration: 124.386897ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T01:59:38.115786Z","caller":"traceutil/trace.go:171","msg":"trace[333798601] linearizableReadLoop","detail":"{readStateIndex:1135; appliedIndex:1134; }","duration":"302.000144ms","start":"2026-02-12T01:59:37.813761Z","end":"2026-02-12T01:59:38.115761Z","steps":["trace[333798601] 'read index received' (duration: 244.553812ms)","trace[333798601] 'applied index is now lower than readState.Index' (duration: 57.444882ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T01:59:38.116136Z","caller":"traceutil/trace.go:171","msg":"trace[1847982888] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"302.49193ms","start":"2026-02-12T01:59:37.813627Z","end":"2026-02-12T01:59:38.116119Z","steps":["trace[1847982888] 'process raft request' (duration: 244.735592ms)","trace[1847982888] 'compare' (duration: 56.829658ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T01:59:38.116289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:59:37.813605Z","time spent":"302.593356ms","remote":"127.0.0.1:35716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T01:59:38.116631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.86144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-12T01:59:38.116698Z","caller":"traceutil/trace.go:171","msg":"trace[906880900] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1085; }","duration":"302.911704ms","start":"2026-02-12T01:59:37.813752Z","end":"2026-02-12T01:59:38.116663Z","steps":["trace[906880900] 'agreement among raft nodes before linearized reading' (duration: 302.737574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:59:38.11674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T01:59:37.813746Z","time spent":"302.983797ms","remote":"127.0.0.1:35474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-02-12T01:59:38.117459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.231821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:4 size:329932"} {"level":"info","ts":"2026-02-12T01:59:38.11753Z","caller":"traceutil/trace.go:171","msg":"trace[1266597309] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:1085; }","duration":"298.332176ms","start":"2026-02-12T01:59:37.819164Z","end":"2026-02-12T01:59:38.117496Z","steps":["trace[1266597309] 'agreement among raft nodes before linearized reading' (duration: 297.959926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:59:38.120967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.655223ms","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-12T01:59:38.124306Z","caller":"traceutil/trace.go:171","msg":"trace[1679276839] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1085; }","duration":"106.91339ms","start":"2026-02-12T01:59:38.017253Z","end":"2026-02-12T01:59:38.124167Z","steps":["trace[1679276839] 'agreement among raft nodes before linearized reading' (duration: 103.522765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:59:38.121632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.17526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-12T01:59:38.125157Z","caller":"traceutil/trace.go:171","msg":"trace[854694579] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1085; }","duration":"211.752715ms","start":"2026-02-12T01:59:37.913394Z","end":"2026-02-12T01:59:38.125147Z","steps":["trace[854694579] 'agreement among raft nodes before linearized reading' (duration: 208.138498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T01:59:38.121873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.017567ms","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-12T01:59:38.1253Z","caller":"traceutil/trace.go:171","msg":"trace[1770774087] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:1085; }","duration":"240.466926ms","start":"2026-02-12T01:59:37.884821Z","end":"2026-02-12T01:59:38.125288Z","steps":["trace[1770774087] 'agreement among raft nodes before linearized reading' (duration: 236.898042ms)"],"step_count":1}