{"level":"warn","ts":"2026-02-09T18:53:47.967415Z","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-09T18:53:47.967499Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-09T18:53:47.967562Z","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-09T18:53:47.96757Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-02-09T18:53:47.967586Z","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-09T18:53:47.968159Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-02-09T18:53:47.968226Z","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://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-09T18:53:47.973002Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.601379ms"} {"level":"info","ts":"2026-02-09T18:53:47.978802Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-02-09T18:53:47.97885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-02-09T18:53:47.978864Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-02-09T18:53:47.978872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-09T18:53:47.978878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-02-09T18:53:47.978905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-02-09T18:53:47.984394Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-09T18:53:47.994282Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-09T18:53:48.003069Z","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-09T18:53:48.007413Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-09T18:53:48.017752Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-09T18:53:48.017888Z","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-09T18:53:48.017914Z","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-09T18:53:48.017922Z","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-09T18:53:48.018969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-02-09T18:53:48.019043Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-02-09T18:53:48.021356Z","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-09T18:53:48.02236Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-02-09T18:53:48.022464Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-02-09T18:53:48.024262Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-09T18:53:48.024352Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-09T18:53:48.079372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-02-09T18:53:48.079466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-09T18:53:48.079497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-02-09T18:53:48.079539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-02-09T18:53:48.07957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-02-09T18:53:48.079589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-02-09T18:53:48.079635Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-02-09T18:53:48.081219Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:53:48.08282Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-02-09T18:53:48.082881Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T18:53:48.083095Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-09T18:53:48.083132Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-09T18:53:48.0832Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-09T18:53:48.086492Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-02-09T18:53:48.086644Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-09T18:53:48.140185Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:53:48.142487Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:53:48.143308Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-09T18:54:08.070335Z","caller":"traceutil/trace.go:171","msg":"trace[1158716703] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"394.333965ms","start":"2026-02-09T18:54:07.675984Z","end":"2026-02-09T18:54:08.070318Z","steps":["trace[1158716703] 'process raft request' (duration: 394.172441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:08.070761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:07.675973Z","time spent":"394.429477ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:14.327077Z","caller":"traceutil/trace.go:171","msg":"trace[1622972799] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"235.473316ms","start":"2026-02-09T18:54:14.091585Z","end":"2026-02-09T18:54:14.327058Z","steps":["trace[1622972799] 'process raft request' (duration: 235.353713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:19.119507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.460096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:54:19.119596Z","caller":"traceutil/trace.go:171","msg":"trace[1650658876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:478; }","duration":"171.599259ms","start":"2026-02-09T18:54:18.947982Z","end":"2026-02-09T18:54:19.119581Z","steps":["trace[1650658876] 'range keys from in-memory index tree' (duration: 171.392204ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:26.394811Z","caller":"traceutil/trace.go:171","msg":"trace[1598140334] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"589.237504ms","start":"2026-02-09T18:54:25.805554Z","end":"2026-02-09T18:54:26.394791Z","steps":["trace[1598140334] 'process raft request' (duration: 588.923216ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:26.394964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:25.805533Z","time spent":"589.345077ms","remote":"127.0.0.1:57138","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:26.576027Z","caller":"traceutil/trace.go:171","msg":"trace[1458726242] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"113.368665ms","start":"2026-02-09T18:54:26.462638Z","end":"2026-02-09T18:54:26.576006Z","steps":["trace[1458726242] 'process raft request' (duration: 40.861404ms)","trace[1458726242] 'compare' (duration: 72.417349ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:54:40.306569Z","caller":"traceutil/trace.go:171","msg":"trace[1385512224] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"239.045971ms","start":"2026-02-09T18:54:40.067497Z","end":"2026-02-09T18:54:40.306543Z","steps":["trace[1385512224] 'process raft request' (duration: 156.320629ms)","trace[1385512224] 'compare' (duration: 82.532457ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:41.098731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.08718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:54:41.098955Z","caller":"traceutil/trace.go:171","msg":"trace[789492512] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:695; }","duration":"718.967665ms","start":"2026-02-09T18:54:40.379972Z","end":"2026-02-09T18:54:41.09894Z","steps":["trace[789492512] 'read index received' (duration: 432.338912ms)","trace[789492512] 'applied index is now lower than readState.Index' (duration: 286.627913ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:54:41.099432Z","caller":"traceutil/trace.go:171","msg":"trace[119464019] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"1.031456559s","start":"2026-02-09T18:54:40.067962Z","end":"2026-02-09T18:54:41.099418Z","steps":["trace[119464019] 'process raft request' (duration: 1.030933416s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:41.099505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:40.067958Z","time spent":"1.03150937s","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1456,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:41.099469Z","caller":"traceutil/trace.go:171","msg":"trace[372767969] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"1.031746825s","start":"2026-02-09T18:54:40.067693Z","end":"2026-02-09T18:54:41.09944Z","steps":["trace[372767969] 'process raft request' (duration: 744.658331ms)","trace[372767969] 'compare' (duration: 285.994688ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:41.099644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:40.067689Z","time spent":"1.031863979s","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:54:41.09971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.751184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:54:41.099742Z","caller":"traceutil/trace.go:171","msg":"trace[376551565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:677; }","duration":"719.818975ms","start":"2026-02-09T18:54:40.379912Z","end":"2026-02-09T18:54:41.099731Z","steps":["trace[376551565] 'agreement among raft nodes before linearized reading' (duration: 719.752413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:41.099769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:40.3799Z","time spent":"719.863506ms","remote":"127.0.0.1:56986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-09T18:54:41.099948Z","caller":"traceutil/trace.go:171","msg":"trace[695053931] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"1.032100224s","start":"2026-02-09T18:54:40.067836Z","end":"2026-02-09T18:54:41.099936Z","steps":["trace[695053931] 'process raft request' (duration: 1.031005878s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:41.099992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.286514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T18:54:41.101363Z","caller":"traceutil/trace.go:171","msg":"trace[87419276] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:677; }","duration":"444.368746ms","start":"2026-02-09T18:54:40.655643Z","end":"2026-02-09T18:54:41.100012Z","steps":["trace[87419276] 'agreement among raft nodes before linearized reading' (duration: 444.263593ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:41.101396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:40.655624Z","time spent":"445.763408ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T18:54:41.101607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.369895ms","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-09T18:54:41.101629Z","caller":"traceutil/trace.go:171","msg":"trace[1946484519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:677; }","duration":"241.410277ms","start":"2026-02-09T18:54:40.86021Z","end":"2026-02-09T18:54:41.10162Z","steps":["trace[1946484519] 'agreement among raft nodes before linearized reading' (duration: 241.349065ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:41.10174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.949273ms","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-09T18:54:41.101758Z","caller":"traceutil/trace.go:171","msg":"trace[1571557337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:677; }","duration":"243.000604ms","start":"2026-02-09T18:54:40.85875Z","end":"2026-02-09T18:54:41.101751Z","steps":["trace[1571557337] 'agreement among raft nodes before linearized reading' (duration: 242.951603ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:41.10186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.215887ms","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-09T18:54:41.101876Z","caller":"traceutil/trace.go:171","msg":"trace[2058325517] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:677; }","duration":"376.270049ms","start":"2026-02-09T18:54:40.725601Z","end":"2026-02-09T18:54:41.101871Z","steps":["trace[2058325517] 'agreement among raft nodes before linearized reading' (duration: 376.222997ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:41.101894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:40.72559Z","time spent":"376.300169ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-09T18:54:41.101991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.403472ms","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-09T18:54:41.102007Z","caller":"traceutil/trace.go:171","msg":"trace[990694037] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:677; }","duration":"440.440143ms","start":"2026-02-09T18:54:40.661562Z","end":"2026-02-09T18:54:41.102002Z","steps":["trace[990694037] 'agreement among raft nodes before linearized reading' (duration: 440.395072ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:41.102025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:40.66155Z","time spent":"440.470113ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-09T18:54:41.106731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:40.067832Z","time spent":"1.032144835s","remote":"127.0.0.1:57120","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:41.571176Z","caller":"traceutil/trace.go:171","msg":"trace[343702396] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"441.729323ms","start":"2026-02-09T18:54:41.129426Z","end":"2026-02-09T18:54:41.571155Z","steps":["trace[343702396] 'process raft request' (duration: 441.696872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:41.571343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:41.12942Z","time spent":"441.825225ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:41.571602Z","caller":"traceutil/trace.go:171","msg":"trace[723387766] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"463.965778ms","start":"2026-02-09T18:54:41.107624Z","end":"2026-02-09T18:54:41.57159Z","steps":["trace[723387766] 'process raft request' (duration: 388.679462ms)","trace[723387766] 'compare' (duration: 74.57651ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:41.571662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:41.107609Z","time spent":"464.024819ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:41.571778Z","caller":"traceutil/trace.go:171","msg":"trace[1758762495] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"463.838785ms","start":"2026-02-09T18:54:41.107931Z","end":"2026-02-09T18:54:41.571769Z","steps":["trace[1758762495] 'read index received' (duration: 388.384654ms)","trace[1758762495] 'applied index is now lower than readState.Index' (duration: 75.453211ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:41.571839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.917617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:54:41.57187Z","caller":"traceutil/trace.go:171","msg":"trace[1789569586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:683; }","duration":"463.969358ms","start":"2026-02-09T18:54:41.107893Z","end":"2026-02-09T18:54:41.571862Z","steps":["trace[1789569586] 'agreement among raft nodes before linearized reading' (duration: 463.912496ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:41.571895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:41.107886Z","time spent":"464.004159ms","remote":"127.0.0.1:56970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-09T18:54:41.572016Z","caller":"traceutil/trace.go:171","msg":"trace[92474131] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"446.614848ms","start":"2026-02-09T18:54:41.125393Z","end":"2026-02-09T18:54:41.572007Z","steps":["trace[92474131] 'process raft request' (duration: 445.597034ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:41.572068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:41.125376Z","time spent":"446.66628ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:41.57289Z","caller":"traceutil/trace.go:171","msg":"trace[356248136] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"447.397457ms","start":"2026-02-09T18:54:41.125478Z","end":"2026-02-09T18:54:41.572876Z","steps":["trace[356248136] 'process raft request' (duration: 445.548693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:41.572962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:41.125472Z","time spent":"447.454928ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:41.5731Z","caller":"traceutil/trace.go:171","msg":"trace[1111853507] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"447.572571ms","start":"2026-02-09T18:54:41.125517Z","end":"2026-02-09T18:54:41.573089Z","steps":["trace[1111853507] 'process raft request' (duration: 445.538903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:41.573147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:41.125501Z","time spent":"447.619842ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:41.573342Z","caller":"traceutil/trace.go:171","msg":"trace[2065477950] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"445.927563ms","start":"2026-02-09T18:54:41.127406Z","end":"2026-02-09T18:54:41.573333Z","steps":["trace[2065477950] 'process raft request' (duration: 443.68234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:41.57339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:41.1274Z","time spent":"445.963873ms","remote":"127.0.0.1:57418","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3754,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:54:41.770962Z","caller":"traceutil/trace.go:171","msg":"trace[260723888] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"184.018402ms","start":"2026-02-09T18:54:41.586915Z","end":"2026-02-09T18:54:41.770933Z","steps":["trace[260723888] 'read index received' (duration: 183.898849ms)","trace[260723888] 'applied index is now lower than readState.Index' (duration: 117.013µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:41.771062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.132375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:54:41.771088Z","caller":"traceutil/trace.go:171","msg":"trace[1537606788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:684; }","duration":"184.200726ms","start":"2026-02-09T18:54:41.58688Z","end":"2026-02-09T18:54:41.77108Z","steps":["trace[1537606788] 'agreement among raft nodes before linearized reading' (duration: 184.131665ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:41.771329Z","caller":"traceutil/trace.go:171","msg":"trace[1608114203] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"184.551655ms","start":"2026-02-09T18:54:41.58677Z","end":"2026-02-09T18:54:41.771321Z","steps":["trace[1608114203] 'process raft request' (duration: 184.083214ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:42.161691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.017642ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:54:42.161904Z","caller":"traceutil/trace.go:171","msg":"trace[662033855] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:705; }","duration":"267.275767ms","start":"2026-02-09T18:54:41.894614Z","end":"2026-02-09T18:54:42.16189Z","steps":["trace[662033855] 'read index received' (duration: 22.0063ms)","trace[662033855] 'applied index is now lower than readState.Index' (duration: 245.268497ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:42.162113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.494662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-xj7t5\" ","response":"range_response_count:1 size:4820"} {"level":"info","ts":"2026-02-09T18:54:42.162145Z","caller":"traceutil/trace.go:171","msg":"trace[29983428] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-xj7t5; range_end:; response_count:1; response_revision:686; }","duration":"267.556244ms","start":"2026-02-09T18:54:41.894578Z","end":"2026-02-09T18:54:42.162134Z","steps":["trace[29983428] 'agreement among raft nodes before linearized reading' (duration: 267.357279ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:54:42.162431Z","caller":"traceutil/trace.go:171","msg":"trace[1885625155] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"378.763638ms","start":"2026-02-09T18:54:41.783654Z","end":"2026-02-09T18:54:42.162418Z","steps":["trace[1885625155] 'process raft request' (duration: 132.945197ms)","trace[1885625155] 'compare' (duration: 244.784446ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:42.162495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:41.783633Z","time spent":"378.826229ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:54:42.162626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:54:41.784208Z","time spent":"378.41668ms","remote":"127.0.0.1:57006","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-09T18:54:42.169006Z","caller":"traceutil/trace.go:171","msg":"trace[1805078348] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"267.954293ms","start":"2026-02-09T18:54:41.894775Z","end":"2026-02-09T18:54:42.162729Z","steps":["trace[1805078348] 'process raft request' (duration: 267.068232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:54:47.48213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.185512ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-09T18:54:47.482231Z","caller":"traceutil/trace.go:171","msg":"trace[1360324247] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"100.47946ms","start":"2026-02-09T18:54:47.381731Z","end":"2026-02-09T18:54:47.482211Z","steps":["trace[1360324247] 'read index received' (duration: 23.44µs)","trace[1360324247] 'applied index is now lower than readState.Index' (duration: 100.45464ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:54:47.482305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.561172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:54:47.482329Z","caller":"traceutil/trace.go:171","msg":"trace[936041010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:699; }","duration":"100.621334ms","start":"2026-02-09T18:54:47.381699Z","end":"2026-02-09T18:54:47.48232Z","steps":["trace[936041010] 'agreement among raft nodes before linearized reading' (duration: 100.562453ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:07.859109Z","caller":"traceutil/trace.go:171","msg":"trace[1805164427] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"110.447164ms","start":"2026-02-09T18:55:07.748649Z","end":"2026-02-09T18:55:07.859096Z","steps":["trace[1805164427] 'process raft request' (duration: 110.167907ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:07.859323Z","caller":"traceutil/trace.go:171","msg":"trace[632262225] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"106.042061ms","start":"2026-02-09T18:55:07.752882Z","end":"2026-02-09T18:55:07.858924Z","steps":["trace[632262225] 'read index received' (duration: 105.877107ms)","trace[632262225] 'applied index is now lower than readState.Index' (duration: 164.424µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:07.859468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.486081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T18:55:07.859492Z","caller":"traceutil/trace.go:171","msg":"trace[741432024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:762; }","duration":"100.556522ms","start":"2026-02-09T18:55:07.75893Z","end":"2026-02-09T18:55:07.859486Z","steps":["trace[741432024] 'agreement among raft nodes before linearized reading' (duration: 100.448279ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:07.861015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.1278ms","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-09T18:55:07.861034Z","caller":"traceutil/trace.go:171","msg":"trace[160503838] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:762; }","duration":"108.16155ms","start":"2026-02-09T18:55:07.752867Z","end":"2026-02-09T18:55:07.861028Z","steps":["trace[160503838] 'agreement among raft nodes before linearized reading' (duration: 106.482171ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:10.202736Z","caller":"traceutil/trace.go:171","msg":"trace[37794714] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"323.029588ms","start":"2026-02-09T18:55:09.879664Z","end":"2026-02-09T18:55:10.202693Z","steps":["trace[37794714] 'process raft request' (duration: 322.852754ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:10.202926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:09.879635Z","time spent":"323.187541ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:10.492285Z","caller":"traceutil/trace.go:171","msg":"trace[1717981724] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"612.173439ms","start":"2026-02-09T18:55:09.880098Z","end":"2026-02-09T18:55:10.492272Z","steps":["trace[1717981724] 'process raft request' (duration: 610.982381ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:10.492436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:09.880076Z","time spent":"612.296623ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:10.493087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.316362ms","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-09T18:55:10.493125Z","caller":"traceutil/trace.go:171","msg":"trace[1592641968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:768; }","duration":"586.360134ms","start":"2026-02-09T18:55:09.906748Z","end":"2026-02-09T18:55:10.493108Z","steps":["trace[1592641968] 'agreement among raft nodes before linearized reading' (duration: 586.237211ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:10.493148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:09.906742Z","time spent":"586.400665ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-09T18:55:10.493792Z","caller":"traceutil/trace.go:171","msg":"trace[844735405] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:795; }","duration":"585.107064ms","start":"2026-02-09T18:55:09.906764Z","end":"2026-02-09T18:55:10.491872Z","steps":["trace[844735405] 'read index received' (duration: 296.495074ms)","trace[844735405] 'applied index is now lower than readState.Index' (duration: 288.61121ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:10.493979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.391263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:10.494056Z","caller":"traceutil/trace.go:171","msg":"trace[933362836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"110.536456ms","start":"2026-02-09T18:55:10.3835Z","end":"2026-02-09T18:55:10.494037Z","steps":["trace[933362836] 'agreement among raft nodes before linearized reading' (duration: 110.442454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:10.494384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.493983ms","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-09T18:55:10.494465Z","caller":"traceutil/trace.go:171","msg":"trace[1000984626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"585.574656ms","start":"2026-02-09T18:55:09.908882Z","end":"2026-02-09T18:55:10.494457Z","steps":["trace[1000984626] 'agreement among raft nodes before linearized reading' (duration: 585.433092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:10.494526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:09.908845Z","time spent":"585.662427ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T18:55:10.494754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.925497ms","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-09T18:55:10.494845Z","caller":"traceutil/trace.go:171","msg":"trace[912627044] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:768; }","duration":"587.02619ms","start":"2026-02-09T18:55:09.90781Z","end":"2026-02-09T18:55:10.494836Z","steps":["trace[912627044] 'agreement among raft nodes before linearized reading' (duration: 586.094558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:10.494889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:09.907805Z","time spent":"587.076861ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-09T18:55:10.618802Z","caller":"traceutil/trace.go:171","msg":"trace[1429906048] transaction","detail":"{read_only:false; response_revision:771; number_of_response:1; }","duration":"108.434317ms","start":"2026-02-09T18:55:10.510336Z","end":"2026-02-09T18:55:10.61877Z","steps":["trace[1429906048] 'process raft request' (duration: 108.355595ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:10.619252Z","caller":"traceutil/trace.go:171","msg":"trace[484394207] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"118.097764ms","start":"2026-02-09T18:55:10.501142Z","end":"2026-02-09T18:55:10.61924Z","steps":["trace[484394207] 'process raft request' (duration: 115.446912ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:20.808858Z","caller":"traceutil/trace.go:171","msg":"trace[1645398014] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"134.700144ms","start":"2026-02-09T18:55:20.674143Z","end":"2026-02-09T18:55:20.808844Z","steps":["trace[1645398014] 'process raft request' (duration: 134.676834ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:20.809167Z","caller":"traceutil/trace.go:171","msg":"trace[2105954590] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"138.614986ms","start":"2026-02-09T18:55:20.670547Z","end":"2026-02-09T18:55:20.809162Z","steps":["trace[2105954590] 'process raft request' (duration: 138.193536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:22.291267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.754288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-09T18:55:22.291869Z","caller":"traceutil/trace.go:171","msg":"trace[526644549] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"418.544343ms","start":"2026-02-09T18:55:21.873314Z","end":"2026-02-09T18:55:22.291858Z","steps":["trace[526644549] 'process raft request' (duration: 168.146811ms)","trace[526644549] 'compare' (duration: 249.572092ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:22.291905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:21.873303Z","time spent":"418.585243ms","remote":"127.0.0.1:57006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:33.199112Z","caller":"traceutil/trace.go:171","msg":"trace[1848808990] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"467.133582ms","start":"2026-02-09T18:55:32.731964Z","end":"2026-02-09T18:55:33.199097Z","steps":["trace[1848808990] 'read index received' (duration: 466.987959ms)","trace[1848808990] 'applied index is now lower than readState.Index' (duration: 145.083µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:55:33.199263Z","caller":"traceutil/trace.go:171","msg":"trace[1889527956] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"801.388424ms","start":"2026-02-09T18:55:32.397869Z","end":"2026-02-09T18:55:33.199258Z","steps":["trace[1889527956] 'process raft request' (duration: 801.134798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:33.199338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:32.39786Z","time spent":"801.431585ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:55:33.199473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.511732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-09T18:55:33.199491Z","caller":"traceutil/trace.go:171","msg":"trace[360981065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:880; }","duration":"467.548783ms","start":"2026-02-09T18:55:32.731935Z","end":"2026-02-09T18:55:33.199484Z","steps":["trace[360981065] 'agreement among raft nodes before linearized reading' (duration: 467.484771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:33.199504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:32.731906Z","time spent":"467.594624ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T18:55:33.199586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.853733ms","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-09T18:55:33.199599Z","caller":"traceutil/trace.go:171","msg":"trace[1810571405] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:880; }","duration":"402.897134ms","start":"2026-02-09T18:55:32.796697Z","end":"2026-02-09T18:55:33.199594Z","steps":["trace[1810571405] 'agreement among raft nodes before linearized reading' (duration: 402.859323ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:33.19961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:32.796684Z","time spent":"402.923694ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-09T18:55:33.199707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.769987ms","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-09T18:55:33.199721Z","caller":"traceutil/trace.go:171","msg":"trace[1595937756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:880; }","duration":"312.801558ms","start":"2026-02-09T18:55:32.886916Z","end":"2026-02-09T18:55:33.199718Z","steps":["trace[1595937756] 'agreement among raft nodes before linearized reading' (duration: 312.764467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:33.199733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:32.886908Z","time spent":"312.822288ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T18:55:33.199802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.549754ms","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-09T18:55:33.199813Z","caller":"traceutil/trace.go:171","msg":"trace[298551726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:880; }","duration":"344.583734ms","start":"2026-02-09T18:55:32.855226Z","end":"2026-02-09T18:55:33.19981Z","steps":["trace[298551726] 'agreement among raft nodes before linearized reading' (duration: 344.549273ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:33.199824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:32.855213Z","time spent":"344.608655ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-09T18:55:35.713646Z","caller":"traceutil/trace.go:171","msg":"trace[1561602191] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"506.542068ms","start":"2026-02-09T18:55:35.207091Z","end":"2026-02-09T18:55:35.713633Z","steps":["trace[1561602191] 'process raft request' (duration: 506.062507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:35.713955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:35.207074Z","time spent":"506.792005ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:35.713388Z","caller":"traceutil/trace.go:171","msg":"trace[134892311] linearizableReadLoop","detail":"{readStateIndex:921; appliedIndex:920; }","duration":"423.61882ms","start":"2026-02-09T18:55:35.289747Z","end":"2026-02-09T18:55:35.713365Z","steps":["trace[134892311] 'read index received' (duration: 423.322013ms)","trace[134892311] 'applied index is now lower than readState.Index' (duration: 295.967µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:35.714498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.741466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-09T18:55:35.714682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.797957ms","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-09T18:55:35.714772Z","caller":"traceutil/trace.go:171","msg":"trace[938070875] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:886; }","duration":"418.89515ms","start":"2026-02-09T18:55:35.295865Z","end":"2026-02-09T18:55:35.71476Z","steps":["trace[938070875] 'agreement among raft nodes before linearized reading' (duration: 418.744456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:35.714804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:35.295859Z","time spent":"418.938431ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-09T18:55:35.714917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.953604ms","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-09T18:55:35.714953Z","caller":"traceutil/trace.go:171","msg":"trace[1207734209] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:886; }","duration":"357.009476ms","start":"2026-02-09T18:55:35.357931Z","end":"2026-02-09T18:55:35.714941Z","steps":["trace[1207734209] 'agreement among raft nodes before linearized reading' (duration: 356.940225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:35.714979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:35.357919Z","time spent":"357.052307ms","remote":"127.0.0.1:57290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-02-09T18:55:35.715163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.885458ms","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-09T18:55:35.71519Z","caller":"traceutil/trace.go:171","msg":"trace[100561278] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:886; }","duration":"412.918779ms","start":"2026-02-09T18:55:35.302264Z","end":"2026-02-09T18:55:35.715183Z","steps":["trace[100561278] 'agreement among raft nodes before linearized reading' (duration: 412.850747ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:35.715214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:35.30226Z","time spent":"412.949239ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T18:55:35.715308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.062713ms","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-09T18:55:35.715328Z","caller":"traceutil/trace.go:171","msg":"trace[1041176376] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:886; }","duration":"413.093603ms","start":"2026-02-09T18:55:35.302229Z","end":"2026-02-09T18:55:35.715323Z","steps":["trace[1041176376] 'agreement among raft nodes before linearized reading' (duration: 413.022921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:35.715343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:35.302223Z","time spent":"413.117474ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-09T18:55:35.715686Z","caller":"traceutil/trace.go:171","msg":"trace[648759547] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:886; }","duration":"424.807118ms","start":"2026-02-09T18:55:35.289719Z","end":"2026-02-09T18:55:35.714527Z","steps":["trace[648759547] 'agreement among raft nodes before linearized reading' (duration: 424.341668ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:35.71572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:35.289708Z","time spent":"426.003836ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T18:55:46.422542Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093788293550884,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T18:55:46.926443Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093788293550884,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T18:55:47.433364Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093788293550884,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T18:55:47.441939Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.524886938s","expected-duration":"1s"} {"level":"info","ts":"2026-02-09T18:55:47.445322Z","caller":"traceutil/trace.go:171","msg":"trace[816574542] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"1.528323729s","start":"2026-02-09T18:55:45.916981Z","end":"2026-02-09T18:55:47.445304Z","steps":["trace[816574542] 'process raft request' (duration: 1.528228307s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:47.445426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.916965Z","time spent":"1.528402881s","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:47.448397Z","caller":"traceutil/trace.go:171","msg":"trace[346242984] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"1.530009839s","start":"2026-02-09T18:55:45.918373Z","end":"2026-02-09T18:55:47.448382Z","steps":["trace[346242984] 'process raft request' (duration: 1.529472116s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:47.44906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.918366Z","time spent":"1.53007071s","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:47.450339Z","caller":"traceutil/trace.go:171","msg":"trace[137287462] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"852.581535ms","start":"2026-02-09T18:55:46.597744Z","end":"2026-02-09T18:55:47.450325Z","steps":["trace[137287462] 'process raft request' (duration: 850.271411ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:47.450414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:46.597726Z","time spent":"852.649537ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:47.450762Z","caller":"traceutil/trace.go:171","msg":"trace[184853842] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"638.441867ms","start":"2026-02-09T18:55:46.81224Z","end":"2026-02-09T18:55:47.450682Z","steps":["trace[184853842] 'process raft request' (duration: 636.148803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:47.450827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:46.812191Z","time spent":"638.60398ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:55:47.454225Z","caller":"traceutil/trace.go:171","msg":"trace[506226551] linearizableReadLoop","detail":"{readStateIndex:989; appliedIndex:985; }","duration":"1.532424465s","start":"2026-02-09T18:55:45.921785Z","end":"2026-02-09T18:55:47.454209Z","steps":["trace[506226551] 'read index received' (duration: 1.525993124s)","trace[506226551] 'applied index is now lower than readState.Index' (duration: 6.430481ms)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:55:47.454722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.532924327s","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-09T18:55:47.454836Z","caller":"traceutil/trace.go:171","msg":"trace[1583956948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"1.533022439s","start":"2026-02-09T18:55:45.921774Z","end":"2026-02-09T18:55:47.454796Z","steps":["trace[1583956948] 'agreement among raft nodes before linearized reading' (duration: 1.532765013s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:47.454914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.921768Z","time spent":"1.533118371s","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T18:55:47.455176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.530558442s","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-09T18:55:47.455254Z","caller":"traceutil/trace.go:171","msg":"trace[700576733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:951; }","duration":"1.530643354s","start":"2026-02-09T18:55:45.924599Z","end":"2026-02-09T18:55:47.455243Z","steps":["trace[700576733] 'agreement among raft nodes before linearized reading' (duration: 1.53049303s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:47.455346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:45.924595Z","time spent":"1.530740396s","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T18:55:47.45549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072244595s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:47.456691Z","caller":"traceutil/trace.go:171","msg":"trace[797951624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"1.073461344s","start":"2026-02-09T18:55:46.383216Z","end":"2026-02-09T18:55:47.456677Z","steps":["trace[797951624] 'agreement among raft nodes before linearized reading' (duration: 1.072235336s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:47.456944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:46.383206Z","time spent":"1.07372574s","remote":"127.0.0.1:56986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T18:55:47.455538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"840.87764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T18:55:47.45715Z","caller":"traceutil/trace.go:171","msg":"trace[201748153] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:951; }","duration":"842.507019ms","start":"2026-02-09T18:55:46.614629Z","end":"2026-02-09T18:55:47.457136Z","steps":["trace[201748153] 'agreement among raft nodes before linearized reading' (duration: 840.882791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:47.457191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:46.614618Z","time spent":"842.56013ms","remote":"127.0.0.1:57430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-02-09T18:55:47.456169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.086237724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-09T18:55:47.457372Z","caller":"traceutil/trace.go:171","msg":"trace[756007340] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:951; }","duration":"1.087488313s","start":"2026-02-09T18:55:46.369874Z","end":"2026-02-09T18:55:47.457362Z","steps":["trace[756007340] 'agreement among raft nodes before linearized reading' (duration: 1.086230424s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:47.45741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:46.369856Z","time spent":"1.087544175s","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-09T18:55:57.678419Z","caller":"traceutil/trace.go:171","msg":"trace[871491719] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"133.850403ms","start":"2026-02-09T18:55:57.544532Z","end":"2026-02-09T18:55:57.678382Z","steps":["trace[871491719] 'process raft request' (duration: 133.72199ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:57.831342Z","caller":"traceutil/trace.go:171","msg":"trace[1326533911] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"235.146193ms","start":"2026-02-09T18:55:57.596118Z","end":"2026-02-09T18:55:57.831265Z","steps":["trace[1326533911] 'process raft request' (duration: 235.099101ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:57.83248Z","caller":"traceutil/trace.go:171","msg":"trace[1905482586] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"287.153504ms","start":"2026-02-09T18:55:57.545301Z","end":"2026-02-09T18:55:57.832455Z","steps":["trace[1905482586] 'process raft request' (duration: 285.625909ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:57.833198Z","caller":"traceutil/trace.go:171","msg":"trace[413370349] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"287.163755ms","start":"2026-02-09T18:55:57.54602Z","end":"2026-02-09T18:55:57.833184Z","steps":["trace[413370349] 'process raft request' (duration: 285.053855ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:57.833765Z","caller":"traceutil/trace.go:171","msg":"trace[156121506] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"286.796686ms","start":"2026-02-09T18:55:57.546928Z","end":"2026-02-09T18:55:57.833725Z","steps":["trace[156121506] 'process raft request' (duration: 284.184505ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:57.834267Z","caller":"traceutil/trace.go:171","msg":"trace[1757268378] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"286.758095ms","start":"2026-02-09T18:55:57.547413Z","end":"2026-02-09T18:55:57.834171Z","steps":["trace[1757268378] 'process raft request' (duration: 283.746344ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:57.834856Z","caller":"traceutil/trace.go:171","msg":"trace[1878822255] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"286.523349ms","start":"2026-02-09T18:55:57.548186Z","end":"2026-02-09T18:55:57.83471Z","steps":["trace[1878822255] 'process raft request' (duration: 283.004777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:59.891946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533033951s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-02-09T18:55:59.893443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.40355925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T18:55:59.893494Z","caller":"traceutil/trace.go:171","msg":"trace[1008093207] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1017; }","duration":"1.403646852s","start":"2026-02-09T18:55:58.489834Z","end":"2026-02-09T18:55:59.89348Z","steps":["trace[1008093207] 'count revisions from in-memory index tree' (duration: 1.403492888s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:59.894082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:58.489791Z","time spent":"1.404224886s","remote":"127.0.0.1:57344","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-09T18:55:59.894457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.395300616s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-09T18:55:59.925349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.40314ms","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-09T18:55:59.925555Z","caller":"traceutil/trace.go:171","msg":"trace[1621824810] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1017; }","duration":"238.615286ms","start":"2026-02-09T18:55:59.686899Z","end":"2026-02-09T18:55:59.925515Z","steps":["trace[1621824810] 'range keys from in-memory index tree' (duration: 238.298367ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:55:59.928405Z","caller":"traceutil/trace.go:171","msg":"trace[1756931592] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1017; }","duration":"1.533146664s","start":"2026-02-09T18:55:58.358857Z","end":"2026-02-09T18:55:59.892004Z","steps":["trace[1756931592] 'range keys from in-memory index tree' (duration: 1.532908188s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:59.928493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:58.358839Z","time spent":"1.569627091s","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-09T18:55:59.928666Z","caller":"traceutil/trace.go:171","msg":"trace[539313391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"1.395367057s","start":"2026-02-09T18:55:58.499123Z","end":"2026-02-09T18:55:59.89449Z","steps":["trace[539313391] 'range keys from in-memory index tree' (duration: 1.395246905s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:59.928699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:58.499111Z","time spent":"1.429580021s","remote":"127.0.0.1:56986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T18:55:59.929769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.636674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T18:55:59.92981Z","caller":"traceutil/trace.go:171","msg":"trace[1217273138] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1017; }","duration":"650.713725ms","start":"2026-02-09T18:55:59.279084Z","end":"2026-02-09T18:55:59.929798Z","steps":["trace[1217273138] 'count revisions from in-memory index tree' (duration: 650.573022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:59.929839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:59.279047Z","time spent":"650.782617ms","remote":"127.0.0.1:57210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":4,"response size":31,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-02-09T18:55:59.930045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.149406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:510"} {"level":"info","ts":"2026-02-09T18:55:59.930074Z","caller":"traceutil/trace.go:171","msg":"trace[57571954] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1017; }","duration":"588.214027ms","start":"2026-02-09T18:55:59.34185Z","end":"2026-02-09T18:55:59.930064Z","steps":["trace[57571954] 'range keys from in-memory index tree' (duration: 588.018653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:59.930099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:59.341831Z","time spent":"588.261568ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":534,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-09T18:55:59.930213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.816861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:55:59.930237Z","caller":"traceutil/trace.go:171","msg":"trace[168917409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"545.877652ms","start":"2026-02-09T18:55:59.384352Z","end":"2026-02-09T18:55:59.93023Z","steps":["trace[168917409] 'range keys from in-memory index tree' (duration: 545.74824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:55:59.930259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:59.384331Z","time spent":"545.923614ms","remote":"127.0.0.1:56970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-09T18:56:00.343509Z","caller":"traceutil/trace.go:171","msg":"trace[415274270] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"407.525123ms","start":"2026-02-09T18:55:59.935961Z","end":"2026-02-09T18:56:00.343486Z","steps":["trace[415274270] 'process raft request' (duration: 407.38262ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:00.343666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:59.935941Z","time spent":"407.634076ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:56:00.35451Z","caller":"traceutil/trace.go:171","msg":"trace[2040487257] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"411.495566ms","start":"2026-02-09T18:55:59.942999Z","end":"2026-02-09T18:56:00.354495Z","steps":["trace[2040487257] 'process raft request' (duration: 411.469466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:00.354631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:59.942993Z","time spent":"411.569538ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":495,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:56:00.35487Z","caller":"traceutil/trace.go:171","msg":"trace[152328583] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"416.450513ms","start":"2026-02-09T18:55:59.938402Z","end":"2026-02-09T18:56:00.354853Z","steps":["trace[152328583] 'process raft request' (duration: 415.824458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:00.354918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:59.938378Z","time spent":"416.516814ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:56:00.355003Z","caller":"traceutil/trace.go:171","msg":"trace[1294465899] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"414.677441ms","start":"2026-02-09T18:55:59.94032Z","end":"2026-02-09T18:56:00.354997Z","steps":["trace[1294465899] 'process raft request' (duration: 413.975475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:00.355031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:59.940306Z","time spent":"414.710182ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:56:00.355146Z","caller":"traceutil/trace.go:171","msg":"trace[678651784] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"414.285722ms","start":"2026-02-09T18:55:59.940853Z","end":"2026-02-09T18:56:00.355139Z","steps":["trace[678651784] 'process raft request' (duration: 413.478723ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:00.355183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:59.940843Z","time spent":"414.323143ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:56:00.355708Z","caller":"traceutil/trace.go:171","msg":"trace[1080886977] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"414.034616ms","start":"2026-02-09T18:55:59.941648Z","end":"2026-02-09T18:56:00.355683Z","steps":["trace[1080886977] 'process raft request' (duration: 412.734336ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:00.355758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:59.941641Z","time spent":"414.093898ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:56:00.355855Z","caller":"traceutil/trace.go:171","msg":"trace[1005792531] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"413.539334ms","start":"2026-02-09T18:55:59.942309Z","end":"2026-02-09T18:56:00.355849Z","steps":["trace[1005792531] 'process raft request' (duration: 412.120321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:56:00.355892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:55:59.942303Z","time spent":"413.570325ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:58:28.386305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093788293552597,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-09T18:58:28.393092Z","caller":"traceutil/trace.go:171","msg":"trace[557278786] linearizableReadLoop","detail":"{readStateIndex:1693; appliedIndex:1692; }","duration":"507.814656ms","start":"2026-02-09T18:58:27.885257Z","end":"2026-02-09T18:58:28.393072Z","steps":["trace[557278786] 'read index received' (duration: 507.635672ms)","trace[557278786] 'applied index is now lower than readState.Index' (duration: 177.844µs)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:58:28.393377Z","caller":"traceutil/trace.go:171","msg":"trace[116991963] transaction","detail":"{read_only:false; response_revision:1623; number_of_response:1; }","duration":"508.200285ms","start":"2026-02-09T18:58:27.885152Z","end":"2026-02-09T18:58:28.393352Z","steps":["trace[116991963] 'process raft request' (duration: 507.798966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:58:28.39347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:58:27.88514Z","time spent":"508.265617ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:58:28.393734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.468722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-09T18:58:28.393769Z","caller":"traceutil/trace.go:171","msg":"trace[79601493] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1623; }","duration":"508.516403ms","start":"2026-02-09T18:58:27.885241Z","end":"2026-02-09T18:58:28.393757Z","steps":["trace[79601493] 'agreement among raft nodes before linearized reading' (duration: 508.377859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:58:28.393797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:58:27.885236Z","time spent":"508.555573ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-09T18:58:28.394045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.013914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-02-09T18:58:28.394079Z","caller":"traceutil/trace.go:171","msg":"trace[1261552865] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1623; }","duration":"309.099946ms","start":"2026-02-09T18:58:28.084967Z","end":"2026-02-09T18:58:28.394067Z","steps":["trace[1261552865] 'agreement among raft nodes before linearized reading' (duration: 309.013094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:58:28.394106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:58:28.08495Z","time spent":"309.149707ms","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-09T18:58:59.80376Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093788293552959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T18:59:00.304161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313093788293552959,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-09T18:59:00.477222Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.384613118s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-09T18:59:00.480145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.534972433s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:59:00.480205Z","caller":"traceutil/trace.go:171","msg":"trace[224526453] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1767; }","duration":"1.535076846s","start":"2026-02-09T18:58:58.945108Z","end":"2026-02-09T18:59:00.480185Z","steps":["trace[224526453] 'range keys from in-memory index tree' (duration: 1.53484823s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:00.480243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:58:58.945087Z","time spent":"1.535148537s","remote":"127.0.0.1:56986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T18:59:00.480448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.47885753s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:59:00.480478Z","caller":"traceutil/trace.go:171","msg":"trace[127012959] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:1767; }","duration":"1.47890052s","start":"2026-02-09T18:58:59.001567Z","end":"2026-02-09T18:59:00.480467Z","steps":["trace[127012959] 'count revisions from in-memory index tree' (duration: 1.478815118s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:00.480506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:58:59.001559Z","time spent":"1.478938721s","remote":"127.0.0.1:57180","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":29,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-02-09T18:59:00.480816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.43642974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-09T18:59:00.48088Z","caller":"traceutil/trace.go:171","msg":"trace[1509628851] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1767; }","duration":"1.436481501s","start":"2026-02-09T18:58:59.04436Z","end":"2026-02-09T18:59:00.480842Z","steps":["trace[1509628851] 'count revisions from in-memory index tree' (duration: 1.436366148s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:00.480917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:58:59.044354Z","time spent":"1.436550213s","remote":"127.0.0.1:57352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-02-09T18:59:00.483685Z","caller":"traceutil/trace.go:171","msg":"trace[476673290] linearizableReadLoop","detail":"{readStateIndex:1846; appliedIndex:1844; }","duration":"1.179930203s","start":"2026-02-09T18:58:59.303444Z","end":"2026-02-09T18:59:00.483374Z","steps":["trace[476673290] 'read index received' (duration: 1.174136966s)","trace[476673290] 'applied index is now lower than readState.Index' (duration: 5.792177ms)"],"step_count":2} {"level":"info","ts":"2026-02-09T18:59:00.484012Z","caller":"traceutil/trace.go:171","msg":"trace[2140127231] transaction","detail":"{read_only:false; response_revision:1768; number_of_response:1; }","duration":"1.39147573s","start":"2026-02-09T18:58:59.092523Z","end":"2026-02-09T18:59:00.483998Z","steps":["trace[2140127231] 'process raft request' (duration: 1.385052458s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:00.484095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:58:59.09251Z","time spent":"1.391529011s","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-09T18:59:00.484235Z","caller":"traceutil/trace.go:171","msg":"trace[1021310985] transaction","detail":"{read_only:false; response_revision:1769; number_of_response:1; }","duration":"1.152497677s","start":"2026-02-09T18:58:59.331728Z","end":"2026-02-09T18:59:00.484226Z","steps":["trace[1021310985] 'process raft request' (duration: 1.151524374s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:00.484283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:58:59.331712Z","time spent":"1.152545278s","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-09T18:59:00.488461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184993882s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-02-09T18:59:00.48861Z","caller":"traceutil/trace.go:171","msg":"trace[1427576093] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1769; }","duration":"1.185125406s","start":"2026-02-09T18:58:59.303418Z","end":"2026-02-09T18:59:00.488543Z","steps":["trace[1427576093] 'agreement among raft nodes before linearized reading' (duration: 1.183013176s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:00.488651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:58:59.303406Z","time spent":"1.185234798s","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-09T18:59:00.489182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161697544s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-09T18:59:00.489289Z","caller":"traceutil/trace.go:171","msg":"trace[50484251] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1769; }","duration":"1.161789996s","start":"2026-02-09T18:58:59.327423Z","end":"2026-02-09T18:59:00.489213Z","steps":["trace[50484251] 'agreement among raft nodes before linearized reading' (duration: 1.161606671s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:00.489355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:58:59.327417Z","time spent":"1.161928249s","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-09T18:59:00.489584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107305491s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:59:00.489625Z","caller":"traceutil/trace.go:171","msg":"trace[835136775] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1769; }","duration":"1.107371233s","start":"2026-02-09T18:58:59.382239Z","end":"2026-02-09T18:59:00.48961Z","steps":["trace[835136775] 'agreement among raft nodes before linearized reading' (duration: 1.107310152s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:00.489655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:58:59.382223Z","time spent":"1.107425414s","remote":"127.0.0.1:56970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T18:59:00.489689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157552186s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-09T18:59:00.489723Z","caller":"traceutil/trace.go:171","msg":"trace[1370162843] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1769; }","duration":"1.157598496s","start":"2026-02-09T18:58:59.332115Z","end":"2026-02-09T18:59:00.489713Z","steps":["trace[1370162843] 'agreement among raft nodes before linearized reading' (duration: 1.157473804s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:00.489785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:58:59.33211Z","time spent":"1.157666439s","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-09T18:59:00.489823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.942513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-09T18:59:00.489851Z","caller":"traceutil/trace.go:171","msg":"trace[349407241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1769; }","duration":"536.030295ms","start":"2026-02-09T18:58:59.953812Z","end":"2026-02-09T18:59:00.489842Z","steps":["trace[349407241] 'agreement among raft nodes before linearized reading' (duration: 535.990904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:00.489874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:58:59.953777Z","time spent":"536.092646ms","remote":"127.0.0.1:56986","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-09T18:59:00.489922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157813922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-09T18:59:00.489935Z","caller":"traceutil/trace.go:171","msg":"trace[1380028197] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1769; }","duration":"1.157845273s","start":"2026-02-09T18:58:59.332085Z","end":"2026-02-09T18:59:00.489931Z","steps":["trace[1380028197] 'agreement among raft nodes before linearized reading' (duration: 1.157786211s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:00.489948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:58:59.332078Z","time spent":"1.157866883s","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-09T18:59:00.490025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.157967636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T18:59:00.490038Z","caller":"traceutil/trace.go:171","msg":"trace[1062113352] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1769; }","duration":"1.157999226s","start":"2026-02-09T18:58:59.332034Z","end":"2026-02-09T18:59:00.490034Z","steps":["trace[1062113352] 'agreement among raft nodes before linearized reading' (duration: 1.157957895s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:00.490066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:58:59.332027Z","time spent":"1.158034297s","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-09T18:59:00.491162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161361236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-09T18:59:00.491181Z","caller":"traceutil/trace.go:171","msg":"trace[1322972161] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1769; }","duration":"1.161402137s","start":"2026-02-09T18:58:59.329774Z","end":"2026-02-09T18:59:00.491176Z","steps":["trace[1322972161] 'agreement among raft nodes before linearized reading' (duration: 1.160229679s)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:00.491195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-09T18:58:59.329764Z","time spent":"1.161428227s","remote":"127.0.0.1:57194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-02-09T18:59:03.715995Z","caller":"traceutil/trace.go:171","msg":"trace[1245466244] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"206.152209ms","start":"2026-02-09T18:59:03.509817Z","end":"2026-02-09T18:59:03.71597Z","steps":["trace[1245466244] 'process raft request' (duration: 206.027566ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:59:06.651598Z","caller":"traceutil/trace.go:171","msg":"trace[845028794] transaction","detail":"{read_only:false; response_revision:1800; number_of_response:1; }","duration":"116.156818ms","start":"2026-02-09T18:59:06.53542Z","end":"2026-02-09T18:59:06.651577Z","steps":["trace[845028794] 'process raft request' (duration: 116.052025ms)"],"step_count":1} {"level":"info","ts":"2026-02-09T18:59:06.65215Z","caller":"traceutil/trace.go:171","msg":"trace[1379551245] linearizableReadLoop","detail":"{readStateIndex:1878; appliedIndex:1877; }","duration":"113.809743ms","start":"2026-02-09T18:59:06.538328Z","end":"2026-02-09T18:59:06.652137Z","steps":["trace[1379551245] 'read index received' (duration: 113.075286ms)","trace[1379551245] 'applied index is now lower than readState.Index' (duration: 732.857µs)"],"step_count":2} {"level":"warn","ts":"2026-02-09T18:59:06.652297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.962476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-09T18:59:06.652325Z","caller":"traceutil/trace.go:171","msg":"trace[1365149254] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1800; }","duration":"114.021538ms","start":"2026-02-09T18:59:06.538296Z","end":"2026-02-09T18:59:06.652317Z","steps":["trace[1365149254] 'agreement among raft nodes before linearized reading' (duration: 113.909276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:06.652518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.553989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-09T18:59:06.652545Z","caller":"traceutil/trace.go:171","msg":"trace[1856231412] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1800; }","duration":"108.589399ms","start":"2026-02-09T18:59:06.543947Z","end":"2026-02-09T18:59:06.652537Z","steps":["trace[1856231412] 'agreement among raft nodes before linearized reading' (duration: 108.491437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:06.652655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.588665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-09T18:59:06.652677Z","caller":"traceutil/trace.go:171","msg":"trace[1048570218] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1800; }","duration":"101.630486ms","start":"2026-02-09T18:59:06.55104Z","end":"2026-02-09T18:59:06.652671Z","steps":["trace[1048570218] 'agreement among raft nodes before linearized reading' (duration: 101.569904ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:06.652774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.864199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-02-09T18:59:06.652794Z","caller":"traceutil/trace.go:171","msg":"trace[1420339620] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1800; }","duration":"106.892899ms","start":"2026-02-09T18:59:06.545895Z","end":"2026-02-09T18:59:06.652788Z","steps":["trace[1420339620] 'agreement among raft nodes before linearized reading' (duration: 106.834368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-09T18:59:06.65289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.465183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-09T18:59:06.652915Z","caller":"traceutil/trace.go:171","msg":"trace[73123213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1800; }","duration":"107.517454ms","start":"2026-02-09T18:59:06.545391Z","end":"2026-02-09T18:59:06.652909Z","steps":["trace[73123213] 'agreement among raft nodes before linearized reading' (duration: 107.456103ms)"],"step_count":1}