{"level":"warn","ts":"2026-02-11T01:51:58.577641Z","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-11T01:51:58.577782Z","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-11T01:51:58.577865Z","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-11T01:51:58.577878Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-11T01:51:58.577898Z","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-11T01:51:58.57851Z","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-11T01:51:58.5786Z","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-11T01:51:58.585285Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.427314ms"} {"level":"info","ts":"2026-02-11T01:51:58.620969Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-02-11T01:51:58.621045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-02-11T01:51:58.621068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-02-11T01:51:58.621083Z","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-11T01:51:58.62109Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-02-11T01:51:58.621248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-02-11T01:51:58.639016Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-11T01:51:58.643665Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-11T01:51:58.650017Z","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-11T01:51:58.656367Z","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-11T01:51:58.659621Z","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-11T01:51:58.659735Z","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-11T01:51:58.659762Z","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-11T01:51:58.659769Z","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-11T01:51:58.674827Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-02-11T01:51:58.679133Z","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-11T01:51:58.679539Z","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-11T01:51:58.67966Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-11T01:51:58.679673Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-11T01:51:58.679873Z","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-11T01:51:58.679893Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-11T01:51:59.121445Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-02-11T01:51:59.121481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-11T01:51:59.121501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-02-11T01:51:59.121512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-02-11T01:51:59.121517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-11T01:51:59.121525Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-02-11T01:51:59.121531Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-11T01:51:59.129557Z","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-11T01:51:59.132721Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:51:59.132847Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:51:59.133117Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-11T01:51:59.134915Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-11T01:51:59.134938Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-11T01:51:59.134963Z","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-11T01:51:59.135014Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:51:59.135032Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-11T01:51:59.143536Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-02-11T01:51:59.147404Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-02-11T01:52:15.178399Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868535899,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-11T01:52:15.567804Z","caller":"traceutil/trace.go:171","msg":"trace[1465950345] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"889.83398ms","start":"2026-02-11T01:52:14.67795Z","end":"2026-02-11T01:52:15.567784Z","steps":["trace[1465950345] 'read index received' (duration: 889.629132ms)","trace[1465950345] 'applied index is now lower than readState.Index' (duration: 203.778µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:15.568085Z","caller":"traceutil/trace.go:171","msg":"trace[914884855] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"947.075252ms","start":"2026-02-11T01:52:14.620982Z","end":"2026-02-11T01:52:15.568058Z","steps":["trace[914884855] 'process raft request' (duration: 946.670346ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:15.568277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.308268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4019"} {"level":"info","ts":"2026-02-11T01:52:15.568461Z","caller":"traceutil/trace.go:171","msg":"trace[1823979428] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:375; }","duration":"890.534386ms","start":"2026-02-11T01:52:14.677908Z","end":"2026-02-11T01:52:15.568443Z","steps":["trace[1823979428] 'agreement among raft nodes before linearized reading' (duration: 890.162322ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:15.568635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:14.677873Z","time spent":"890.746666ms","remote":"127.0.0.1:53866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4041,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-11T01:52:15.568905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:14.620967Z","time spent":"947.182356ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:52:15.569296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"776.605396ms","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-11T01:52:15.5695Z","caller":"traceutil/trace.go:171","msg":"trace[396566364] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:375; }","duration":"776.848684ms","start":"2026-02-11T01:52:14.792638Z","end":"2026-02-11T01:52:15.569487Z","steps":["trace[396566364] 'agreement among raft nodes before linearized reading' (duration: 776.612995ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:15.569704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:14.792617Z","time spent":"777.071923ms","remote":"127.0.0.1:53960","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-11T01:52:15.570117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.354071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-11T01:52:15.570292Z","caller":"traceutil/trace.go:171","msg":"trace[1371528449] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:375; }","duration":"137.551399ms","start":"2026-02-11T01:52:15.432726Z","end":"2026-02-11T01:52:15.570277Z","steps":["trace[1371528449] 'agreement among raft nodes before linearized reading' (duration: 137.345801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:15.570739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"755.689787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-11T01:52:15.571254Z","caller":"traceutil/trace.go:171","msg":"trace[530239100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:375; }","duration":"756.170555ms","start":"2026-02-11T01:52:14.815036Z","end":"2026-02-11T01:52:15.571207Z","steps":["trace[530239100] 'agreement among raft nodes before linearized reading' (duration: 755.662806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:15.57181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"763.5125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-11T01:52:15.571867Z","caller":"traceutil/trace.go:171","msg":"trace[341179539] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:375; }","duration":"763.601223ms","start":"2026-02-11T01:52:14.808251Z","end":"2026-02-11T01:52:15.571852Z","steps":["trace[341179539] 'agreement among raft nodes before linearized reading' (duration: 763.50496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:15.571908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:14.808235Z","time spent":"763.664336ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-11T01:52:15.572386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:14.815002Z","time spent":"756.408375ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-11T01:52:16.339726Z","caller":"traceutil/trace.go:171","msg":"trace[418953937] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"756.278043ms","start":"2026-02-11T01:52:15.582752Z","end":"2026-02-11T01:52:16.33903Z","steps":["trace[418953937] 'process raft request' (duration: 748.761431ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.339994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:15.582736Z","time spent":"757.191639ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:16.343074Z","caller":"traceutil/trace.go:171","msg":"trace[767923956] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:387; }","duration":"399.322155ms","start":"2026-02-11T01:52:15.943735Z","end":"2026-02-11T01:52:16.343057Z","steps":["trace[767923956] 'read index received' (duration: 387.730055ms)","trace[767923956] 'applied index is now lower than readState.Index' (duration: 11.59127ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:16.343505Z","caller":"traceutil/trace.go:171","msg":"trace[1823826855] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"760.592681ms","start":"2026-02-11T01:52:15.5829Z","end":"2026-02-11T01:52:16.343493Z","steps":["trace[1823826855] 'process raft request' (duration: 760.010468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.343985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:15.582894Z","time spent":"760.640112ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:16.34444Z","caller":"traceutil/trace.go:171","msg":"trace[805800447] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"757.22099ms","start":"2026-02-11T01:52:15.58721Z","end":"2026-02-11T01:52:16.344431Z","steps":["trace[805800447] 'process raft request' (duration: 755.822006ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.345898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:15.587206Z","time spent":"757.260622ms","remote":"127.0.0.1:53960","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-11T01:52:16.346117Z","caller":"traceutil/trace.go:171","msg":"trace[1292564329] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"759.021701ms","start":"2026-02-11T01:52:15.587086Z","end":"2026-02-11T01:52:16.346107Z","steps":["trace[1292564329] 'process raft request' (duration: 755.91469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.346259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.518779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:16.346654Z","caller":"traceutil/trace.go:171","msg":"trace[818119713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:379; }","duration":"402.913124ms","start":"2026-02-11T01:52:15.943731Z","end":"2026-02-11T01:52:16.346644Z","steps":["trace[818119713] 'agreement among raft nodes before linearized reading' (duration: 402.490768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:16.34672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:15.943687Z","time spent":"403.023999ms","remote":"127.0.0.1:53672","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-11T01:52:16.346542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:15.587079Z","time spent":"759.437056ms","remote":"127.0.0.1:53874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6883,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:52:29.274172Z","caller":"traceutil/trace.go:171","msg":"trace[218911316] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"160.288876ms","start":"2026-02-11T01:52:29.113852Z","end":"2026-02-11T01:52:29.274141Z","steps":["trace[218911316] 'process raft request' (duration: 160.164442ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:37.24531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.879025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:37.245362Z","caller":"traceutil/trace.go:171","msg":"trace[604671895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"302.966018ms","start":"2026-02-11T01:52:36.942385Z","end":"2026-02-11T01:52:37.245351Z","steps":["trace[604671895] 'range keys from in-memory index tree' (duration: 302.831343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:37.24539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:36.94237Z","time spent":"303.01396ms","remote":"127.0.0.1:53672","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-11T01:52:37.371444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.122563ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-11T01:52:37.371553Z","caller":"traceutil/trace.go:171","msg":"trace[1498778788] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"124.006826ms","start":"2026-02-11T01:52:37.247511Z","end":"2026-02-11T01:52:37.371518Z","steps":["trace[1498778788] 'read index received' (duration: 26.151µs)","trace[1498778788] 'applied index is now lower than readState.Index' (duration: 123.979535ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:52:37.371623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.106571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:37.371648Z","caller":"traceutil/trace.go:171","msg":"trace[234491843] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:516; }","duration":"124.177543ms","start":"2026-02-11T01:52:37.247461Z","end":"2026-02-11T01:52:37.371639Z","steps":["trace[234491843] 'agreement among raft nodes before linearized reading' (duration: 124.122421ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:39.012903Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868536184,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:52:39.489927Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.71150757s","expected-duration":"1s"} {"level":"info","ts":"2026-02-11T01:52:39.490211Z","caller":"traceutil/trace.go:171","msg":"trace[1745886137] linearizableReadLoop","detail":"{readStateIndex:536; appliedIndex:535; }","duration":"977.872404ms","start":"2026-02-11T01:52:38.512322Z","end":"2026-02-11T01:52:39.490195Z","steps":["trace[1745886137] 'read index received' (duration: 977.753139ms)","trace[1745886137] 'applied index is now lower than readState.Index' (duration: 118.505µs)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:52:39.490409Z","caller":"traceutil/trace.go:171","msg":"trace[87123152] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.712081613s","start":"2026-02-11T01:52:37.778322Z","end":"2026-02-11T01:52:39.490403Z","steps":["trace[87123152] 'process raft request' (duration: 1.711758821s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:39.490468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"978.123304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-11T01:52:39.490484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:37.778311Z","time spent":"1.712126185s","remote":"127.0.0.1:53960","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-11T01:52:39.490522Z","caller":"traceutil/trace.go:171","msg":"trace[1515616783] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:517; }","duration":"978.217158ms","start":"2026-02-11T01:52:38.512288Z","end":"2026-02-11T01:52:39.490505Z","steps":["trace[1515616783] 'agreement among raft nodes before linearized reading' (duration: 978.098953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:39.49059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:38.512271Z","time spent":"978.308422ms","remote":"127.0.0.1:53960","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-11T01:52:39.490664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"940.291206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-11T01:52:39.490718Z","caller":"traceutil/trace.go:171","msg":"trace[1510471258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"940.350978ms","start":"2026-02-11T01:52:38.550328Z","end":"2026-02-11T01:52:39.490679Z","steps":["trace[1510471258] 'agreement among raft nodes before linearized reading' (duration: 940.317186ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:39.490734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:38.550307Z","time spent":"940.424011ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-11T01:52:39.490779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.175912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:52:39.490789Z","caller":"traceutil/trace.go:171","msg":"trace[69004903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:517; }","duration":"546.207704ms","start":"2026-02-11T01:52:38.944578Z","end":"2026-02-11T01:52:39.490786Z","steps":["trace[69004903] 'agreement among raft nodes before linearized reading' (duration: 546.191573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:39.4908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:38.944565Z","time spent":"546.231955ms","remote":"127.0.0.1:53672","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-11T01:52:39.490863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.690256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-11T01:52:39.490892Z","caller":"traceutil/trace.go:171","msg":"trace[181860532] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:517; }","duration":"974.740508ms","start":"2026-02-11T01:52:38.516143Z","end":"2026-02-11T01:52:39.490884Z","steps":["trace[181860532] 'agreement among raft nodes before linearized reading' (duration: 974.220067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:52:39.490919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:52:38.516129Z","time spent":"974.7834ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-11T01:54:06.928304Z","caller":"traceutil/trace.go:171","msg":"trace[2007523177] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"193.922382ms","start":"2026-02-11T01:54:06.73436Z","end":"2026-02-11T01:54:06.928283Z","steps":["trace[2007523177] 'process raft request' (duration: 193.393278ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:54:26.392193Z","caller":"traceutil/trace.go:171","msg":"trace[190630279] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"228.003029ms","start":"2026-02-11T01:54:26.164173Z","end":"2026-02-11T01:54:26.392176Z","steps":["trace[190630279] 'process raft request' (duration: 227.910417ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:54:26.732324Z","caller":"traceutil/trace.go:171","msg":"trace[484204050] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1055; }","duration":"553.758804ms","start":"2026-02-11T01:54:26.178528Z","end":"2026-02-11T01:54:26.732286Z","steps":["trace[484204050] 'read index received' (duration: 214.089383ms)","trace[484204050] 'applied index is now lower than readState.Index' (duration: 339.668341ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:54:26.732556Z","caller":"traceutil/trace.go:171","msg":"trace[178984872] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"568.160693ms","start":"2026-02-11T01:54:26.164386Z","end":"2026-02-11T01:54:26.732547Z","steps":["trace[178984872] 'process raft request' (duration: 567.770682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:26.732715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:26.164362Z","time spent":"568.244106ms","remote":"127.0.0.1:53960","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-11T01:54:26.732941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.408493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-node-lease/\" range_end:\"/registry/persistentvolumeclaims/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:54:26.732997Z","caller":"traceutil/trace.go:171","msg":"trace[1716639274] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-node-lease/; range_end:/registry/persistentvolumeclaims/kube-node-lease0; response_count:0; response_revision:1013; }","duration":"554.483026ms","start":"2026-02-11T01:54:26.178504Z","end":"2026-02-11T01:54:26.732987Z","steps":["trace[1716639274] 'agreement among raft nodes before linearized reading' (duration: 554.410133ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:26.73377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:26.178492Z","time spent":"555.265728ms","remote":"127.0.0.1:53838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/kube-node-lease/\" range_end:\"/registry/persistentvolumeclaims/kube-node-lease0\" limit:500 "} {"level":"warn","ts":"2026-02-11T01:54:26.739166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.737356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:54:26.739294Z","caller":"traceutil/trace.go:171","msg":"trace[1299005863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1014; }","duration":"508.895321ms","start":"2026-02-11T01:54:26.230379Z","end":"2026-02-11T01:54:26.739274Z","steps":["trace[1299005863] 'agreement among raft nodes before linearized reading' (duration: 508.720586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:26.739323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:26.230364Z","time spent":"508.952193ms","remote":"127.0.0.1:53672","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-11T01:54:26.739498Z","caller":"traceutil/trace.go:171","msg":"trace[679194552] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"216.496906ms","start":"2026-02-11T01:54:26.522994Z","end":"2026-02-11T01:54:26.739491Z","steps":["trace[679194552] 'process raft request' (duration: 216.016592ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:26.739589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.524168ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-node-lease/\" range_end:\"/registry/rolebindings/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:54:26.739608Z","caller":"traceutil/trace.go:171","msg":"trace[166661782] range","detail":"{range_begin:/registry/rolebindings/kube-node-lease/; range_end:/registry/rolebindings/kube-node-lease0; response_count:0; response_revision:1014; }","duration":"301.57556ms","start":"2026-02-11T01:54:26.438024Z","end":"2026-02-11T01:54:26.7396Z","steps":["trace[166661782] 'agreement among raft nodes before linearized reading' (duration: 301.542089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:26.739624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:26.438008Z","time spent":"301.611441ms","remote":"127.0.0.1:54034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":27,"request content":"key:\"/registry/rolebindings/kube-node-lease/\" range_end:\"/registry/rolebindings/kube-node-lease0\" limit:500 "} {"level":"warn","ts":"2026-02-11T01:54:26.73977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.155715ms","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-11T01:54:26.739786Z","caller":"traceutil/trace.go:171","msg":"trace[1647909379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1014; }","duration":"336.195855ms","start":"2026-02-11T01:54:26.403585Z","end":"2026-02-11T01:54:26.739781Z","steps":["trace[1647909379] 'agreement among raft nodes before linearized reading' (duration: 336.112533ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:26.739802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:26.403574Z","time spent":"336.224997ms","remote":"127.0.0.1:53960","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-11T01:54:32.480445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.395338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-11T01:54:32.48053Z","caller":"traceutil/trace.go:171","msg":"trace[321741861] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1026; }","duration":"367.493601ms","start":"2026-02-11T01:54:32.113006Z","end":"2026-02-11T01:54:32.4805Z","steps":["trace[321741861] 'range keys from in-memory index tree' (duration: 367.279095ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:32.480567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:32.112993Z","time spent":"367.565413ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T01:54:32.480772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.459418ms","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-11T01:54:32.4808Z","caller":"traceutil/trace.go:171","msg":"trace[27535599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1026; }","duration":"364.493498ms","start":"2026-02-11T01:54:32.116296Z","end":"2026-02-11T01:54:32.480789Z","steps":["trace[27535599] 'range keys from in-memory index tree' (duration: 364.388595ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:32.480824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:32.116291Z","time spent":"364.527379ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:54:32.480946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.80737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/local-path-storage/\" range_end:\"/registry/statefulsets/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:54:32.480974Z","caller":"traceutil/trace.go:171","msg":"trace[452621798] range","detail":"{range_begin:/registry/statefulsets/local-path-storage/; range_end:/registry/statefulsets/local-path-storage0; response_count:0; response_revision:1026; }","duration":"315.858402ms","start":"2026-02-11T01:54:32.165105Z","end":"2026-02-11T01:54:32.480964Z","steps":["trace[452621798] 'range keys from in-memory index tree' (duration: 315.766389ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:32.481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:32.165092Z","time spent":"315.900002ms","remote":"127.0.0.1:54144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":27,"request content":"key:\"/registry/statefulsets/local-path-storage/\" range_end:\"/registry/statefulsets/local-path-storage0\" limit:500 "} {"level":"warn","ts":"2026-02-11T01:54:32.481134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.070776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-public/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:741"} {"level":"info","ts":"2026-02-11T01:54:32.48116Z","caller":"traceutil/trace.go:171","msg":"trace[1505691705] range","detail":"{range_begin:/registry/rolebindings/kube-public/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1026; }","duration":"313.104857ms","start":"2026-02-11T01:54:32.168046Z","end":"2026-02-11T01:54:32.481151Z","steps":["trace[1505691705] 'range keys from in-memory index tree' (duration: 313.040195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:32.481188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:32.168042Z","time spent":"313.140278ms","remote":"127.0.0.1:54034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":763,"request content":"key:\"/registry/rolebindings/kube-public/system:controller:bootstrap-signer\" "} {"level":"warn","ts":"2026-02-11T01:54:32.48132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.043435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/kubeadm:bootstrap-signer-clusterinfo\" ","response":"range_response_count:1 size:371"} {"level":"info","ts":"2026-02-11T01:54:32.481345Z","caller":"traceutil/trace.go:171","msg":"trace[828497756] range","detail":"{range_begin:/registry/roles/kube-public/kubeadm:bootstrap-signer-clusterinfo; range_end:; response_count:1; response_revision:1026; }","duration":"279.084807ms","start":"2026-02-11T01:54:32.202252Z","end":"2026-02-11T01:54:32.481337Z","steps":["trace[828497756] 'range keys from in-memory index tree' (duration: 278.996954ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:54:32.76455Z","caller":"traceutil/trace.go:171","msg":"trace[2011408827] linearizableReadLoop","detail":"{readStateIndex:1074; appliedIndex:1072; }","duration":"194.719355ms","start":"2026-02-11T01:54:32.56981Z","end":"2026-02-11T01:54:32.764529Z","steps":["trace[2011408827] 'read index received' (duration: 1.796294ms)","trace[2011408827] 'applied index is now lower than readState.Index' (duration: 192.922101ms)"],"step_count":2} {"level":"info","ts":"2026-02-11T01:54:32.765341Z","caller":"traceutil/trace.go:171","msg":"trace[1198738697] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"268.389098ms","start":"2026-02-11T01:54:32.496937Z","end":"2026-02-11T01:54:32.765326Z","steps":["trace[1198738697] 'process raft request' (duration: 239.392812ms)","trace[1198738697] 'compare' (duration: 28.032257ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:54:32.765943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.17229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/system:controller:bootstrap-signer\" ","response":"range_response_count:1 size:709"} {"level":"info","ts":"2026-02-11T01:54:32.766194Z","caller":"traceutil/trace.go:171","msg":"trace[113054395] range","detail":"{range_begin:/registry/roles/kube-public/system:controller:bootstrap-signer; range_end:; response_count:1; response_revision:1029; }","duration":"162.424548ms","start":"2026-02-11T01:54:32.603746Z","end":"2026-02-11T01:54:32.766171Z","steps":["trace[113054395] 'agreement among raft nodes before linearized reading' (duration: 162.126619ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:32.766682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.85258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/kubeadm:bootstrap-signer-clusterinfo\" ","response":"range_response_count:1 size:371"} {"level":"info","ts":"2026-02-11T01:54:32.766738Z","caller":"traceutil/trace.go:171","msg":"trace[1353296627] range","detail":"{range_begin:/registry/roles/kube-public/kubeadm:bootstrap-signer-clusterinfo; range_end:; response_count:1; response_revision:1029; }","duration":"196.936443ms","start":"2026-02-11T01:54:32.569784Z","end":"2026-02-11T01:54:32.76672Z","steps":["trace[1353296627] 'agreement among raft nodes before linearized reading' (duration: 196.83921ms)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:54:32.767172Z","caller":"traceutil/trace.go:171","msg":"trace[1361516310] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"270.174163ms","start":"2026-02-11T01:54:32.496984Z","end":"2026-02-11T01:54:32.767158Z","steps":["trace[1361516310] 'process raft request' (duration: 267.502221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:33.349802Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:33.850216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:34.350894Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:34.851427Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:34.944056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000185322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:54:34.944212Z","caller":"traceutil/trace.go:171","msg":"trace[1198832465] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000383828s","start":"2026-02-11T01:54:32.943808Z","end":"2026-02-11T01:54:34.944192Z","steps":["trace[1198832465] 'agreement among raft nodes before linearized reading' (duration: 2.000205943s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:34.944309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:32.943776Z","time spent":"2.000519582s","remote":"127.0.0.1:53672","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-11T01:54:35.351587Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:35.852082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:36.352997Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:36.853893Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:36.94527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000102512s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:54:36.945401Z","caller":"traceutil/trace.go:171","msg":"trace[1801390795] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000266968s","start":"2026-02-11T01:54:34.945112Z","end":"2026-02-11T01:54:36.945379Z","steps":["trace[1801390795] 'agreement among raft nodes before linearized reading' (duration: 2.000126903s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:36.945558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:34.945093Z","time spent":"2.000452524s","remote":"127.0.0.1:53676","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-11T01:54:37.354513Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:37.854793Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:38.234141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001014887s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:54:38.234375Z","caller":"traceutil/trace.go:171","msg":"trace[1117442110] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001301376s","start":"2026-02-11T01:54:36.23305Z","end":"2026-02-11T01:54:38.234352Z","steps":["trace[1117442110] 'agreement among raft nodes before linearized reading' (duration: 2.001059068s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:38.234559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:36.233028Z","time spent":"2.00142782s","remote":"127.0.0.1:53672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/11 01:54:38 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T01:54:38.355214Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:38.855509Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:38.953392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000501222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:54:38.95355Z","caller":"traceutil/trace.go:171","msg":"trace[758444613] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000700239s","start":"2026-02-11T01:54:36.952832Z","end":"2026-02-11T01:54:38.953532Z","steps":["trace[758444613] 'agreement among raft nodes before linearized reading' (duration: 2.000520323s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:38.953619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:36.952815Z","time spent":"2.000792682s","remote":"127.0.0.1:53676","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-11T01:54:39.356621Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538069,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:39.574326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999214816s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:54:39.574429Z","caller":"traceutil/trace.go:171","msg":"trace[222094374] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.99936555s","start":"2026-02-11T01:54:34.575038Z","end":"2026-02-11T01:54:39.574403Z","steps":["trace[222094374] 'agreement among raft nodes before linearized reading' (duration: 4.999247917s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:39.574482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:34.575018Z","time spent":"4.999453843s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-11T01:54:39.776585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000638519s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:54:39.776706Z","caller":"traceutil/trace.go:171","msg":"trace[2015464569] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"5.000749813s","start":"2026-02-11T01:54:34.775896Z","end":"2026-02-11T01:54:39.776646Z","steps":["trace[2015464569] 'agreement among raft nodes before linearized reading' (duration: 5.00064802s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:39.776784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:34.775892Z","time spent":"5.000874297s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-11T01:54:39.785477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:32.785226Z","time spent":"7.000244774s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-11T01:54:39.813037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999948217s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:54:39.813225Z","caller":"traceutil/trace.go:171","msg":"trace[626902225] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"5.000171894s","start":"2026-02-11T01:54:34.813015Z","end":"2026-02-11T01:54:39.813187Z","steps":["trace[626902225] 'agreement among raft nodes before linearized reading' (duration: 4.999970928s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:39.813374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:34.812996Z","time spent":"5.000363329s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/02/11 01:54:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T01:54:39.849698Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-02-11T01:54:39.849802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.000277491s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/system:controller:bootstrap-signer\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-11T01:54:39.849848Z","caller":"traceutil/trace.go:171","msg":"trace[846255262] range","detail":"{range_begin:/registry/roles/kube-public/system:controller:bootstrap-signer; range_end:; }","duration":"7.000349694s","start":"2026-02-11T01:54:32.849484Z","end":"2026-02-11T01:54:39.849834Z","steps":["trace[846255262] 'agreement among raft nodes before linearized reading' (duration: 7.000300552s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:39.849888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:32.849469Z","time spent":"7.000410485s","remote":"127.0.0.1:54022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":0,"request content":"key:\"/registry/roles/kube-public/system:controller:bootstrap-signer\" "} {"level":"warn","ts":"2026-02-11T01:54:40.350533Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:40.851311Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:40.960198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000459934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:54:40.96028Z","caller":"traceutil/trace.go:171","msg":"trace[1917895967] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000603208s","start":"2026-02-11T01:54:38.959658Z","end":"2026-02-11T01:54:40.960261Z","steps":["trace[1917895967] 'agreement among raft nodes before linearized reading' (duration: 2.000499415s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:40.9605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:38.959642Z","time spent":"2.000844606s","remote":"127.0.0.1:53672","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-11T01:54:41.149593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:34.148884Z","time spent":"7.000702704s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-11T01:54:41.35213Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:41.852412Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:42.353171Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:42.755526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"965.947882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-11T01:54:42.755597Z","caller":"traceutil/trace.go:171","msg":"trace[553635645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"966.049054ms","start":"2026-02-11T01:54:41.789531Z","end":"2026-02-11T01:54:42.75558Z","steps":["trace[553635645] 'agreement among raft nodes before linearized reading' (duration: 965.963891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:42.755812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:41.789519Z","time spent":"966.280193ms","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/02/11 01:54:42 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T01:54:42.853667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:42.961497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000211221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:54:42.961561Z","caller":"traceutil/trace.go:171","msg":"trace[673509440] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000320105s","start":"2026-02-11T01:54:40.961227Z","end":"2026-02-11T01:54:42.961547Z","steps":["trace[673509440] 'agreement among raft nodes before linearized reading' (duration: 2.000234632s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:42.961595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:40.961212Z","time spent":"2.000375856s","remote":"127.0.0.1:53676","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-11T01:54:43.354142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:43.741937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000683239s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:54:43.742721Z","caller":"traceutil/trace.go:171","msg":"trace[88246237] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001521376s","start":"2026-02-11T01:54:41.741178Z","end":"2026-02-11T01:54:43.7427Z","steps":["trace[88246237] 'agreement among raft nodes before linearized reading' (duration: 2.00071324s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:43.742875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:41.74116Z","time spent":"2.00165764s","remote":"127.0.0.1:53672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/11 01:54:43 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T01:54:43.855091Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:43.95008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:36.949534Z","time spent":"7.000539056s","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-11T01:54:43.987521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:36.987229Z","time spent":"7.000286853s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-11T01:54:44.335025Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"37939c4a65e8a73d","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-11T01:54:44.355315Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:44.574933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998730867s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-11T01:54:44.57501Z","caller":"traceutil/trace.go:171","msg":"trace[1782960121] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.998854911s","start":"2026-02-11T01:54:41.576137Z","end":"2026-02-11T01:54:44.574992Z","steps":["trace[1782960121] 'agreement among raft nodes before linearized reading' (duration: 2.998762068s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:44.575055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:41.5761Z","time spent":"2.998946215s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/02/11 01:54:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T01:54:44.774704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999389215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-11T01:54:44.774766Z","caller":"traceutil/trace.go:171","msg":"trace[914991731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"9.999487079s","start":"2026-02-11T01:54:34.775265Z","end":"2026-02-11T01:54:44.774752Z","steps":["trace[914991731] 'agreement among raft nodes before linearized reading' (duration: 9.999404696s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:44.774801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:34.775252Z","time spent":"9.99954056s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/02/11 01:54:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T01:54:44.778199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.999525277s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-11T01:54:44.778436Z","caller":"traceutil/trace.go:171","msg":"trace[592292447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.999676262s","start":"2026-02-11T01:54:41.778596Z","end":"2026-02-11T01:54:44.778272Z","steps":["trace[592292447] 'agreement among raft nodes before linearized reading' (duration: 2.999571528s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:44.77859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:41.778576Z","time spent":"2.999999343s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/11 01:54:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T01:54:44.812963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998269647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-11T01:54:44.8131Z","caller":"traceutil/trace.go:171","msg":"trace[1495510228] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.998427952s","start":"2026-02-11T01:54:41.814656Z","end":"2026-02-11T01:54:44.813084Z","steps":["trace[1495510228] 'agreement among raft nodes before linearized reading' (duration: 2.998290558s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:44.813265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:41.814643Z","time spent":"2.998609767s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/02/11 01:54:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T01:54:44.855868Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:44.96981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000544019s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:54:44.970045Z","caller":"traceutil/trace.go:171","msg":"trace[1753923030] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000760066s","start":"2026-02-11T01:54:42.969224Z","end":"2026-02-11T01:54:44.969984Z","steps":["trace[1753923030] 'agreement among raft nodes before linearized reading' (duration: 2.000553559s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:44.970248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:42.969216Z","time spent":"2.001015654s","remote":"127.0.0.1:53672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/11 01:54:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T01:54:45.356694Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:45.857386Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:46.358199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538076,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:46.850746Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-02-11T01:54:46.850977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.462000347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-11T01:54:46.851099Z","caller":"traceutil/trace.go:171","msg":"trace[339929432] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; }","duration":"8.462179923s","start":"2026-02-11T01:54:38.388901Z","end":"2026-02-11T01:54:46.851081Z","steps":["trace[339929432] 'agreement among raft nodes before linearized reading' (duration: 8.462034638s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:46.851174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:38.388882Z","time spent":"8.462277276s","remote":"127.0.0.1:54140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":0,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:54:46.851231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.568488s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-11T01:54:46.851313Z","caller":"traceutil/trace.go:171","msg":"trace[563278864] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; }","duration":"8.568607754s","start":"2026-02-11T01:54:38.282688Z","end":"2026-02-11T01:54:46.851296Z","steps":["trace[563278864] 'agreement among raft nodes before linearized reading' (duration: 8.568517901s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:46.851363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:38.282678Z","time spent":"8.568675047s","remote":"127.0.0.1:54144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":0,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:54:46.851378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"12.790267979s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-02-11T01:54:46.851451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.910171617s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-11T01:54:46.851481Z","caller":"traceutil/trace.go:171","msg":"trace[2112357869] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; }","duration":"9.910236109s","start":"2026-02-11T01:54:36.941237Z","end":"2026-02-11T01:54:46.851474Z","steps":["trace[2112357869] 'agreement among raft nodes before linearized reading' (duration: 9.910205298s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:46.851512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:36.941218Z","time spent":"9.910286381s","remote":"127.0.0.1:43186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-02-11T01:54:46.851466Z","caller":"traceutil/trace.go:171","msg":"trace[816921828] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; }","duration":"12.790389393s","start":"2026-02-11T01:54:34.061067Z","end":"2026-02-11T01:54:46.851456Z","steps":["trace[816921828] 'agreement among raft nodes before linearized reading' (duration: 12.790270709s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:46.85162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:34.06105Z","time spent":"12.790557247s","remote":"127.0.0.1:54006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":0,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:54:46.851683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.018850181s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-11T01:54:46.851897Z","caller":"traceutil/trace.go:171","msg":"trace[890766167] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; }","duration":"13.019062398s","start":"2026-02-11T01:54:33.832823Z","end":"2026-02-11T01:54:46.851886Z","steps":["trace[890766167] 'agreement among raft nodes before linearized reading' (duration: 13.018849701s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:46.852266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:33.832765Z","time spent":"13.019484852s","remote":"127.0.0.1:54110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":0,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:54:46.851422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.587246756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-11T01:54:46.852498Z","caller":"traceutil/trace.go:171","msg":"trace[1344053204] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; }","duration":"8.588354861s","start":"2026-02-11T01:54:38.264131Z","end":"2026-02-11T01:54:46.852486Z","steps":["trace[1344053204] 'agreement among raft nodes before linearized reading' (duration: 8.587283397s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:46.852561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:38.264111Z","time spent":"8.588436294s","remote":"127.0.0.1:53782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":0,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:54:46.852087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.968540706s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/cilium-config-agent\" ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-11T01:54:46.852669Z","caller":"traceutil/trace.go:171","msg":"trace[717864144] range","detail":"{range_begin:/registry/rolebindings/kube-system/cilium-config-agent; range_end:; }","duration":"13.969130385s","start":"2026-02-11T01:54:32.88351Z","end":"2026-02-11T01:54:46.85264Z","steps":["trace[717864144] 'agreement among raft nodes before linearized reading' (duration: 13.968566607s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:46.852722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:32.883492Z","time spent":"13.969212879s","remote":"127.0.0.1:54034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":0,"request content":"key:\"/registry/rolebindings/kube-system/cilium-config-agent\" "} {"level":"warn","ts":"2026-02-11T01:54:46.851154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.953804749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-02-11T01:54:46.852766Z","caller":"traceutil/trace.go:171","msg":"trace[345426874] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; }","duration":"9.955487704s","start":"2026-02-11T01:54:36.897272Z","end":"2026-02-11T01:54:46.85276Z","steps":["trace[345426874] 'agreement among raft nodes before linearized reading' (duration: 9.95382905s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:46.852802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:36.897254Z","time spent":"9.955539495s","remote":"127.0.0.1:44560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":0,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:54:46.970445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999833208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-11T01:54:46.970506Z","caller":"traceutil/trace.go:171","msg":"trace[1462976590] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999973652s","start":"2026-02-11T01:54:44.970519Z","end":"2026-02-11T01:54:46.970493Z","steps":["trace[1462976590] 'agreement among raft nodes before linearized reading' (duration: 1.9999025s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:46.970545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:44.970395Z","time spent":"2.000142867s","remote":"127.0.0.1:53676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/11 01:54:46 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-11T01:54:47.351775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538085,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:47.852507Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088373868538085,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-11T01:54:48.109543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999951378s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-11T01:54:48.109641Z","caller":"traceutil/trace.go:171","msg":"trace[1863152259] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000045051s","start":"2026-02-11T01:54:46.109558Z","end":"2026-02-11T01:54:48.109603Z","steps":["trace[1863152259] 'agreement among raft nodes before linearized reading' (duration: 1.999946047s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:48.109966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:46.10947Z","time spent":"2.000481705s","remote":"127.0.0.1:53672","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-11T01:54:48.151427Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"15.341917724s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-11T01:54:48.155321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:41.154683Z","time spent":"7.00063055s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-02-11T01:54:48.213687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.988987209s","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-11T01:54:48.213804Z","caller":"traceutil/trace.go:171","msg":"trace[261867063] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1030; }","duration":"9.989115203s","start":"2026-02-11T01:54:38.224673Z","end":"2026-02-11T01:54:48.213788Z","steps":["trace[261867063] 'range keys from in-memory index tree' (duration: 9.988972429s)"],"step_count":1} {"level":"info","ts":"2026-02-11T01:54:48.222278Z","caller":"traceutil/trace.go:171","msg":"trace[225314429] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1034; }","duration":"5.464278271s","start":"2026-02-11T01:54:42.757988Z","end":"2026-02-11T01:54:48.222266Z","steps":["trace[225314429] 'process raft request' (duration: 5.463843607s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:48.222377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:42.757969Z","time spent":"5.464342972s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":46,"response count":0,"response size":466,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:54:48.222817Z","caller":"traceutil/trace.go:171","msg":"trace[1177924043] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"4.268640079s","start":"2026-02-11T01:54:43.95417Z","end":"2026-02-11T01:54:48.22281Z","steps":["trace[1177924043] 'process raft request' (duration: 4.268481784s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:48.222894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:43.954144Z","time spent":"4.268697491s","remote":"127.0.0.1:53738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-11T01:54:48.223102Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"324.22µs","request":"header: lease_revoke:","response":"size:27","error":"lease not found"} {"level":"info","ts":"2026-02-11T01:54:48.223177Z","caller":"traceutil/trace.go:171","msg":"trace[1831146063] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1035; }","duration":"4.230850552s","start":"2026-02-11T01:54:43.992318Z","end":"2026-02-11T01:54:48.223169Z","steps":["trace[1831146063] 'process raft request' (duration: 4.230428958s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:48.223219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:43.992299Z","time spent":"4.230899194s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":569,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-11T01:54:48.22357Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"37939c4a65e8a73d","error":"lease not found"} {"level":"info","ts":"2026-02-11T01:54:48.223759Z","caller":"traceutil/trace.go:171","msg":"trace[1949312816] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"3.445615075s","start":"2026-02-11T01:54:44.778133Z","end":"2026-02-11T01:54:48.223748Z","steps":["trace[1949312816] 'process raft request' (duration: 3.445000215s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:48.223849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:44.778117Z","time spent":"3.445701938s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":415,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:54:48.224338Z","caller":"traceutil/trace.go:171","msg":"trace[1817525658] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"3.446100562s","start":"2026-02-11T01:54:44.778231Z","end":"2026-02-11T01:54:48.224331Z","steps":["trace[1817525658] 'process raft request' (duration: 3.446016499s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:48.224386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:44.778199Z","time spent":"3.446157763s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:54:48.224538Z","caller":"traceutil/trace.go:171","msg":"trace[1964180718] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"3.408021442s","start":"2026-02-11T01:54:44.81651Z","end":"2026-02-11T01:54:48.224531Z","steps":["trace[1964180718] 'process raft request' (duration: 3.407769764s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:48.224597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:44.81649Z","time spent":"3.408065784s","remote":"127.0.0.1:53960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-11T01:54:48.227782Z","caller":"traceutil/trace.go:171","msg":"trace[2099963525] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1075; }","duration":"1.376869511s","start":"2026-02-11T01:54:46.850905Z","end":"2026-02-11T01:54:48.227775Z","steps":["trace[2099963525] 'read index received' (duration: 1.300931293s)","trace[2099963525] 'applied index is now lower than readState.Index' (duration: 75.937768ms)"],"step_count":2} {"level":"warn","ts":"2026-02-11T01:54:48.227854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.979946878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:54:48.227874Z","caller":"traceutil/trace.go:171","msg":"trace[226763508] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1039; }","duration":"1.97999919s","start":"2026-02-11T01:54:46.247869Z","end":"2026-02-11T01:54:48.227868Z","steps":["trace[226763508] 'agreement among raft nodes before linearized reading' (duration: 1.979948228s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:48.22789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:46.247854Z","time spent":"1.980032611s","remote":"127.0.0.1:44570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:54:48.228012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.26244097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7045"} {"level":"info","ts":"2026-02-11T01:54:48.228031Z","caller":"traceutil/trace.go:171","msg":"trace[1382492749] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1039; }","duration":"5.262483022s","start":"2026-02-11T01:54:42.965544Z","end":"2026-02-11T01:54:48.228027Z","steps":["trace[1382492749] 'agreement among raft nodes before linearized reading' (duration: 5.26242914s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:48.228072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:42.965532Z","time spent":"5.262534403s","remote":"127.0.0.1:53874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7067,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-02-11T01:54:48.228165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.449164696s","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-11T01:54:48.228185Z","caller":"traceutil/trace.go:171","msg":"trace[1456420067] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1039; }","duration":"5.449222958s","start":"2026-02-11T01:54:42.778957Z","end":"2026-02-11T01:54:48.22818Z","steps":["trace[1456420067] 'agreement among raft nodes before linearized reading' (duration: 5.449195057s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:48.228212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:42.778901Z","time spent":"5.449306361s","remote":"127.0.0.1:43180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:54:48.228314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.229326761s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:54:48.228332Z","caller":"traceutil/trace.go:171","msg":"trace[1113781250] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:0; response_revision:1039; }","duration":"6.229383082s","start":"2026-02-11T01:54:41.998945Z","end":"2026-02-11T01:54:48.228328Z","steps":["trace[1113781250] 'agreement among raft nodes before linearized reading' (duration: 6.229344541s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:48.228367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:41.998926Z","time spent":"6.229436505s","remote":"127.0.0.1:53708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/162.253.55.209\" "} {"level":"warn","ts":"2026-02-11T01:54:48.228469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.293703492s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/kube-proxy\" ","response":"range_response_count:1 size:450"} {"level":"info","ts":"2026-02-11T01:54:48.228487Z","caller":"traceutil/trace.go:171","msg":"trace[569626000] range","detail":"{range_begin:/registry/rolebindings/kube-system/kube-proxy; range_end:; response_count:1; response_revision:1039; }","duration":"8.293746423s","start":"2026-02-11T01:54:39.934737Z","end":"2026-02-11T01:54:48.228483Z","steps":["trace[569626000] 'agreement among raft nodes before linearized reading' (duration: 8.293714132s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:48.228499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:39.934722Z","time spent":"8.293774394s","remote":"127.0.0.1:54034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":472,"request content":"key:\"/registry/rolebindings/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-02-11T01:54:48.228587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.324411515s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:54:48.228625Z","caller":"traceutil/trace.go:171","msg":"trace[484296035] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1039; }","duration":"1.324434086s","start":"2026-02-11T01:54:46.904169Z","end":"2026-02-11T01:54:48.228603Z","steps":["trace[484296035] 'agreement among raft nodes before linearized reading' (duration: 1.324402015s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:48.228646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:46.904153Z","time spent":"1.324487868s","remote":"127.0.0.1:44584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:54:48.228832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.198967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-11T01:54:48.228861Z","caller":"traceutil/trace.go:171","msg":"trace[1813205994] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1039; }","duration":"903.264769ms","start":"2026-02-11T01:54:47.32559Z","end":"2026-02-11T01:54:48.228855Z","steps":["trace[1813205994] 'agreement among raft nodes before linearized reading' (duration: 903.210347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:48.228978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:47.325568Z","time spent":"903.404004ms","remote":"127.0.0.1:54046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":52,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-11T01:54:48.229083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251428924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-11T01:54:48.229101Z","caller":"traceutil/trace.go:171","msg":"trace[1031543178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1039; }","duration":"1.251486486s","start":"2026-02-11T01:54:46.97761Z","end":"2026-02-11T01:54:48.229097Z","steps":["trace[1031543178] 'agreement among raft nodes before linearized reading' (duration: 1.251443934s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:48.229133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:46.97759Z","time spent":"1.251538968s","remote":"127.0.0.1:53676","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-11T01:54:48.229222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.302922789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/cilium-config-agent\" ","response":"range_response_count:1 size:790"} {"level":"info","ts":"2026-02-11T01:54:48.229239Z","caller":"traceutil/trace.go:171","msg":"trace[1555107676] range","detail":"{range_begin:/registry/rolebindings/kube-system/cilium-config-agent; range_end:; response_count:1; response_revision:1039; }","duration":"1.302977451s","start":"2026-02-11T01:54:46.926258Z","end":"2026-02-11T01:54:48.229236Z","steps":["trace[1555107676] 'agreement among raft nodes before linearized reading' (duration: 1.30294515s)"],"step_count":1} {"level":"warn","ts":"2026-02-11T01:54:48.229265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-11T01:54:46.926244Z","time spent":"1.303004812s","remote":"127.0.0.1:54034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":812,"request content":"key:\"/registry/rolebindings/kube-system/cilium-config-agent\" "}