{"level":"warn","ts":"2026-02-25T06:10:33.998777Z","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-25T06:10:33.998854Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.30: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.30:2380","--initial-cluster=instance=https://199.204.45.30:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.30:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.30: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-25T06:10:33.998911Z","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-25T06:10:33.998919Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-02-25T06:10:33.998933Z","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-25T06:10:33.999538Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"]} {"level":"info","ts":"2026-02-25T06:10:33.999632Z","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":8,"max-cpu-available":8,"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.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.30: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-25T06:10:34.003782Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.897273ms"} {"level":"info","ts":"2026-02-25T06:10:34.010278Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c6cf68dde442ffa5","cluster-id":"98b7ecdca9045526"} {"level":"info","ts":"2026-02-25T06:10:34.010379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T06:10:34.010401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 0"} {"level":"info","ts":"2026-02-25T06:10:34.010418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c6cf68dde442ffa5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T06:10:34.010431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became follower at term 1"} {"level":"info","ts":"2026-02-25T06:10:34.010471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"warn","ts":"2026-02-25T06:10:34.017292Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T06:10:34.018323Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T06:10:34.019256Z","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-25T06:10:34.021157Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c6cf68dde442ffa5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T06:10:34.021943Z","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-25T06:10:34.022101Z","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-25T06:10:34.023411Z","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-25T06:10:34.024074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 switched to configuration voters=(14325784241915494309)"} {"level":"info","ts":"2026-02-25T06:10:34.022098Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c6cf68dde442ffa5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T06:10:34.030818Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","added-peer-id":"c6cf68dde442ffa5","added-peer-peer-urls":["https://199.204.45.30:2380"]} {"level":"info","ts":"2026-02-25T06:10:34.032325Z","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-25T06:10:34.032461Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-02-25T06:10:34.032731Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c6cf68dde442ffa5","initial-advertise-peer-urls":["https://199.204.45.30:2380"],"listen-peer-urls":["https://199.204.45.30:2380"],"advertise-client-urls":["https://199.204.45.30:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.30:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T06:10:34.032783Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T06:10:34.032561Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.30:2380"} {"level":"info","ts":"2026-02-25T06:10:34.611551Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T06:10:34.611616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T06:10:34.611641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgPreVoteResp from c6cf68dde442ffa5 at term 1"} {"level":"info","ts":"2026-02-25T06:10:34.611722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became candidate at term 2"} {"level":"info","ts":"2026-02-25T06:10:34.611734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 received MsgVoteResp from c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-02-25T06:10:34.611777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c6cf68dde442ffa5 became leader at term 2"} {"level":"info","ts":"2026-02-25T06:10:34.611793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c6cf68dde442ffa5 elected leader c6cf68dde442ffa5 at term 2"} {"level":"info","ts":"2026-02-25T06:10:34.650726Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c6cf68dde442ffa5","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.30:2379]}","request-path":"/0/members/c6cf68dde442ffa5/attributes","cluster-id":"98b7ecdca9045526","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T06:10:34.651078Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:10:34.651458Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:10:34.652188Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T06:10:34.652339Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"98b7ecdca9045526","local-member-id":"c6cf68dde442ffa5","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:10:34.652506Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:10:34.652534Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T06:10:34.652559Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T06:10:34.652901Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T06:10:34.655814Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.30:2379"} {"level":"info","ts":"2026-02-25T06:10:34.656193Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T06:10:52.810875Z","caller":"traceutil/trace.go:171","msg":"trace[2118891313] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"350.924076ms","start":"2026-02-25T06:10:52.459935Z","end":"2026-02-25T06:10:52.810859Z","steps":["trace[2118891313] 'process raft request' (duration: 350.831023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:52.811289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:52.459916Z","time spent":"351.022017ms","remote":"127.0.0.1:51248","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-25T06:10:53.736441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.115304ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T06:10:53.736621Z","caller":"traceutil/trace.go:171","msg":"trace[808085115] linearizableReadLoop","detail":"{readStateIndex:467; appliedIndex:466; }","duration":"356.205058ms","start":"2026-02-25T06:10:53.380392Z","end":"2026-02-25T06:10:53.736597Z","steps":["trace[808085115] 'read index received' (duration: 121.536292ms)","trace[808085115] 'applied index is now lower than readState.Index' (duration: 234.666566ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:10:53.73683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.397212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:10:53.736918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.743878ms","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-25T06:10:53.736943Z","caller":"traceutil/trace.go:171","msg":"trace[235182388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:453; }","duration":"356.553434ms","start":"2026-02-25T06:10:53.380361Z","end":"2026-02-25T06:10:53.736914Z","steps":["trace[235182388] 'agreement among raft nodes before linearized reading' (duration: 356.3546ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:10:53.736966Z","caller":"traceutil/trace.go:171","msg":"trace[1920695299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:453; }","duration":"122.83353ms","start":"2026-02-25T06:10:53.614122Z","end":"2026-02-25T06:10:53.736955Z","steps":["trace[1920695299] 'agreement among raft nodes before linearized reading' (duration: 122.614686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:10:53.737013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:10:53.380327Z","time spent":"356.674177ms","remote":"127.0.0.1:50952","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-25T06:11:05.098523Z","caller":"traceutil/trace.go:171","msg":"trace[1744403811] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"164.139102ms","start":"2026-02-25T06:11:04.934356Z","end":"2026-02-25T06:11:05.098495Z","steps":["trace[1744403811] 'process raft request' (duration: 164.023339ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:05.493405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.389889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:11:05.493479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.309138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4020"} {"level":"info","ts":"2026-02-25T06:11:05.493528Z","caller":"traceutil/trace.go:171","msg":"trace[92958440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"128.564312ms","start":"2026-02-25T06:11:05.364942Z","end":"2026-02-25T06:11:05.493506Z","steps":["trace[92958440] 'range keys from in-memory index tree' (duration: 128.294967ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:05.493535Z","caller":"traceutil/trace.go:171","msg":"trace[1233327318] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:514; }","duration":"309.401621ms","start":"2026-02-25T06:11:05.184117Z","end":"2026-02-25T06:11:05.493518Z","steps":["trace[1233327318] 'range keys from in-memory index tree' (duration: 309.141845ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:05.49358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:05.184098Z","time spent":"309.470782ms","remote":"127.0.0.1:51146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4044,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-02-25T06:11:07.883304Z","caller":"traceutil/trace.go:171","msg":"trace[438455324] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"102.776782ms","start":"2026-02-25T06:11:07.780459Z","end":"2026-02-25T06:11:07.883235Z","steps":["trace[438455324] 'process raft request' (duration: 100.785409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:11.791838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.048257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-knl9w\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-25T06:11:11.791932Z","caller":"traceutil/trace.go:171","msg":"trace[2035943827] range","detail":"{range_begin:/registry/pods/kube-system/cilium-knl9w; range_end:; response_count:1; response_revision:533; }","duration":"185.177469ms","start":"2026-02-25T06:11:11.606729Z","end":"2026-02-25T06:11:11.791906Z","steps":["trace[2035943827] 'range keys from in-memory index tree' (duration: 184.918084ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:11.963271Z","caller":"traceutil/trace.go:171","msg":"trace[763952417] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"158.128303ms","start":"2026-02-25T06:11:11.805115Z","end":"2026-02-25T06:11:11.963243Z","steps":["trace[763952417] 'process raft request' (duration: 157.974439ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:12.571942Z","caller":"traceutil/trace.go:171","msg":"trace[226161491] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"166.305197ms","start":"2026-02-25T06:11:12.405057Z","end":"2026-02-25T06:11:12.571362Z","steps":["trace[226161491] 'process raft request' (duration: 120.635523ms)","trace[226161491] 'compare' (duration: 45.532731ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:13.602594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.019231ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T06:11:13.602744Z","caller":"traceutil/trace.go:171","msg":"trace[48106337] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"232.9702ms","start":"2026-02-25T06:11:13.369752Z","end":"2026-02-25T06:11:13.602722Z","steps":["trace[48106337] 'read index received' (duration: 65.052µs)","trace[48106337] 'applied index is now lower than readState.Index' (duration: 232.903588ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:13.602837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.085062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:13.602865Z","caller":"traceutil/trace.go:171","msg":"trace[1112932621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"233.119713ms","start":"2026-02-25T06:11:13.369733Z","end":"2026-02-25T06:11:13.602853Z","steps":["trace[1112932621] 'agreement among raft nodes before linearized reading' (duration: 233.046171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:14.928313Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302007809679005,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T06:11:14.933549Z","caller":"traceutil/trace.go:171","msg":"trace[1327987763] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"505.278708ms","start":"2026-02-25T06:11:14.428248Z","end":"2026-02-25T06:11:14.933527Z","steps":["trace[1327987763] 'read index received' (duration: 505.088514ms)","trace[1327987763] 'applied index is now lower than readState.Index' (duration: 189.374µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:14.933603Z","caller":"traceutil/trace.go:171","msg":"trace[228683779] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"873.444291ms","start":"2026-02-25T06:11:14.060127Z","end":"2026-02-25T06:11:14.933571Z","steps":["trace[228683779] 'process raft request' (duration: 873.211116ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:14.933827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.981786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T06:11:14.933862Z","caller":"traceutil/trace.go:171","msg":"trace[776922016] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:539; }","duration":"350.068537ms","start":"2026-02-25T06:11:14.583784Z","end":"2026-02-25T06:11:14.933853Z","steps":["trace[776922016] 'agreement among raft nodes before linearized reading' (duration: 349.856862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:14.933854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:14.060108Z","time spent":"873.628084ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:14.933887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:14.583765Z","time spent":"350.117028ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T06:11:14.933935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.642655ms","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-25T06:11:14.934041Z","caller":"traceutil/trace.go:171","msg":"trace[758815221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"505.817239ms","start":"2026-02-25T06:11:14.428197Z","end":"2026-02-25T06:11:14.934015Z","steps":["trace[758815221] 'agreement among raft nodes before linearized reading' (duration: 505.450441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:14.934106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:14.428175Z","time spent":"505.916922ms","remote":"127.0.0.1:51248","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-25T06:11:17.044529Z","caller":"traceutil/trace.go:171","msg":"trace[1879772216] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"277.524719ms","start":"2026-02-25T06:11:16.766977Z","end":"2026-02-25T06:11:17.044502Z","steps":["trace[1879772216] 'process raft request' (duration: 234.009651ms)","trace[1879772216] 'compare' (duration: 43.316744ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:17.054769Z","caller":"traceutil/trace.go:171","msg":"trace[783089568] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:584; }","duration":"117.056157ms","start":"2026-02-25T06:11:16.937704Z","end":"2026-02-25T06:11:17.05476Z","steps":["trace[783089568] 'read index received' (duration: 63.28752ms)","trace[783089568] 'applied index is now lower than readState.Index' (duration: 53.767997ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:17.05498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.259921ms","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-25T06:11:17.055059Z","caller":"traceutil/trace.go:171","msg":"trace[1156559587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:569; }","duration":"117.386544ms","start":"2026-02-25T06:11:16.937659Z","end":"2026-02-25T06:11:17.055046Z","steps":["trace[1156559587] 'agreement among raft nodes before linearized reading' (duration: 117.21544ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:17.054972Z","caller":"traceutil/trace.go:171","msg":"trace[1243794231] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"205.955504ms","start":"2026-02-25T06:11:16.84899Z","end":"2026-02-25T06:11:17.054946Z","steps":["trace[1243794231] 'process raft request' (duration: 205.685608ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:17.055103Z","caller":"traceutil/trace.go:171","msg":"trace[355539749] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"104.523119ms","start":"2026-02-25T06:11:16.950544Z","end":"2026-02-25T06:11:17.055067Z","steps":["trace[355539749] 'process raft request' (duration: 104.186371ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:27.393609Z","caller":"traceutil/trace.go:171","msg":"trace[60640223] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"125.324812ms","start":"2026-02-25T06:11:27.268259Z","end":"2026-02-25T06:11:27.393584Z","steps":["trace[60640223] 'process raft request' (duration: 125.180669ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:32.778777Z","caller":"traceutil/trace.go:171","msg":"trace[1564920078] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"139.981036ms","start":"2026-02-25T06:11:32.63877Z","end":"2026-02-25T06:11:32.778751Z","steps":["trace[1564920078] 'process raft request' (duration: 139.782862ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:32.997199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.803834ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-02-25T06:11:33.663337Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302007809679349,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T06:11:33.925164Z","caller":"traceutil/trace.go:171","msg":"trace[173657136] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"788.876097ms","start":"2026-02-25T06:11:33.136248Z","end":"2026-02-25T06:11:33.925124Z","steps":["trace[173657136] 'process raft request' (duration: 787.996338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:33.9254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:33.136226Z","time spent":"789.043091ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:34.267736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.035759ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:11:34.267865Z","caller":"traceutil/trace.go:171","msg":"trace[1138940678] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:745; }","duration":"1.104966188s","start":"2026-02-25T06:11:33.162877Z","end":"2026-02-25T06:11:34.267843Z","steps":["trace[1138940678] 'read index received' (duration: 761.410521ms)","trace[1138940678] 'applied index is now lower than readState.Index' (duration: 343.554337ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:11:34.267974Z","caller":"traceutil/trace.go:171","msg":"trace[279440504] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"1.128872269s","start":"2026-02-25T06:11:33.139089Z","end":"2026-02-25T06:11:34.267961Z","steps":["trace[279440504] 'process raft request' (duration: 828.489952ms)","trace[279440504] 'compare' (duration: 299.860926ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:34.268032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:33.139065Z","time spent":"1.12893587s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:34.268399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105526181s","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-25T06:11:34.268431Z","caller":"traceutil/trace.go:171","msg":"trace[1064116551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:724; }","duration":"1.105589782s","start":"2026-02-25T06:11:33.162829Z","end":"2026-02-25T06:11:34.268419Z","steps":["trace[1064116551] 'agreement among raft nodes before linearized reading' (duration: 1.105448688s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:34.26846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:33.162808Z","time spent":"1.105645953s","remote":"127.0.0.1:51248","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-25T06:11:34.26851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.312175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:11:34.268603Z","caller":"traceutil/trace.go:171","msg":"trace[1707528146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:724; }","duration":"878.458838ms","start":"2026-02-25T06:11:33.390119Z","end":"2026-02-25T06:11:34.268578Z","steps":["trace[1707528146] 'agreement among raft nodes before linearized reading' (duration: 878.309505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:34.268648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:33.390094Z","time spent":"878.54175ms","remote":"127.0.0.1:50952","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-25T06:11:34.26867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.86134ms","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-25T06:11:34.268735Z","caller":"traceutil/trace.go:171","msg":"trace[1034755367] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:724; }","duration":"851.987303ms","start":"2026-02-25T06:11:33.416731Z","end":"2026-02-25T06:11:34.268718Z","steps":["trace[1034755367] 'agreement among raft nodes before linearized reading' (duration: 851.815069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:34.268769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:33.416705Z","time spent":"852.056754ms","remote":"127.0.0.1:51248","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-25T06:11:34.925702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.366052ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:11:34.925893Z","caller":"traceutil/trace.go:171","msg":"trace[123585559] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:747; }","duration":"142.634722ms","start":"2026-02-25T06:11:34.783237Z","end":"2026-02-25T06:11:34.925872Z","steps":["trace[123585559] 'read index received' (duration: 139.956395ms)","trace[123585559] 'applied index is now lower than readState.Index' (duration: 2.677157ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:34.926025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.782215ms","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-25T06:11:34.926046Z","caller":"traceutil/trace.go:171","msg":"trace[1213331279] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:726; }","duration":"142.838097ms","start":"2026-02-25T06:11:34.783197Z","end":"2026-02-25T06:11:34.926035Z","steps":["trace[1213331279] 'agreement among raft nodes before linearized reading' (duration: 142.717814ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:34.926532Z","caller":"traceutil/trace.go:171","msg":"trace[519439943] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"653.03176ms","start":"2026-02-25T06:11:34.273473Z","end":"2026-02-25T06:11:34.926505Z","steps":["trace[519439943] 'process raft request' (duration: 652.322255ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:11:34.926611Z","caller":"traceutil/trace.go:171","msg":"trace[1939772049] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"653.847647ms","start":"2026-02-25T06:11:34.272753Z","end":"2026-02-25T06:11:34.926601Z","steps":["trace[1939772049] 'process raft request' (duration: 491.507245ms)","trace[1939772049] 'compare' (duration: 160.935922ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:11:34.926652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:34.272719Z","time spent":"653.912148ms","remote":"127.0.0.1:51248","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-25T06:11:34.926697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:34.273459Z","time spent":"653.155482ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:11:36.348002Z","caller":"traceutil/trace.go:171","msg":"trace[1890074812] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"412.545553ms","start":"2026-02-25T06:11:35.935416Z","end":"2026-02-25T06:11:36.347961Z","steps":["trace[1890074812] 'process raft request' (duration: 412.195936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:11:36.348893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:11:35.935393Z","time spent":"412.709387ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:11:36.531255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.856914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:11:36.531405Z","caller":"traceutil/trace.go:171","msg":"trace[928189454] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"177.157779ms","start":"2026-02-25T06:11:36.35422Z","end":"2026-02-25T06:11:36.531378Z","steps":["trace[928189454] 'process raft request' (duration: 12.040739ms)","trace[928189454] 'compare' (duration: 164.600789ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:01.57664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302007809679593,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:12:02.077362Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302007809679593,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-25T06:12:02.180197Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.126906449s","expected-duration":"1s"} {"level":"info","ts":"2026-02-25T06:12:02.180723Z","caller":"traceutil/trace.go:171","msg":"trace[316012576] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"1.105002604s","start":"2026-02-25T06:12:01.075695Z","end":"2026-02-25T06:12:02.180698Z","steps":["trace[316012576] 'read index received' (duration: 1.104705798s)","trace[316012576] 'applied index is now lower than readState.Index' (duration: 296.046µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:02.18093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.105211759s","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-25T06:12:02.180973Z","caller":"traceutil/trace.go:171","msg":"trace[723742508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:805; }","duration":"1.10530274s","start":"2026-02-25T06:12:01.07566Z","end":"2026-02-25T06:12:02.180963Z","steps":["trace[723742508] 'agreement among raft nodes before linearized reading' (duration: 1.105137657s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:02.181003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:01.075648Z","time spent":"1.105347451s","remote":"127.0.0.1:51248","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-25T06:12:02.181039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"814.154261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:02.181145Z","caller":"traceutil/trace.go:171","msg":"trace[85907223] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"814.308313ms","start":"2026-02-25T06:12:01.366808Z","end":"2026-02-25T06:12:02.181117Z","steps":["trace[85907223] 'agreement among raft nodes before linearized reading' (duration: 814.124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:02.181145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05607214s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T06:12:02.181266Z","caller":"traceutil/trace.go:171","msg":"trace[1451172752] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:805; }","duration":"1.056248086s","start":"2026-02-25T06:12:01.124996Z","end":"2026-02-25T06:12:02.181244Z","steps":["trace[1451172752] 'agreement among raft nodes before linearized reading' (duration: 1.055907458s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:02.181195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:01.366786Z","time spent":"814.402197ms","remote":"127.0.0.1:50952","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-25T06:12:02.181323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:01.124979Z","time spent":"1.056333847s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-25T06:12:02.180984Z","caller":"traceutil/trace.go:171","msg":"trace[718801247] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"1.127691914s","start":"2026-02-25T06:12:01.053155Z","end":"2026-02-25T06:12:02.180847Z","steps":["trace[718801247] 'process raft request' (duration: 1.127389398s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:02.181473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:01.053135Z","time spent":"1.128252807s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:02.362967Z","caller":"traceutil/trace.go:171","msg":"trace[1198921477] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"175.459723ms","start":"2026-02-25T06:12:02.187457Z","end":"2026-02-25T06:12:02.362917Z","steps":["trace[1198921477] 'process raft request' (duration: 110.035418ms)","trace[1198921477] 'compare' (duration: 65.113518ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:04.587259Z","caller":"traceutil/trace.go:171","msg":"trace[586520292] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"396.039071ms","start":"2026-02-25T06:12:04.191135Z","end":"2026-02-25T06:12:04.587174Z","steps":["trace[586520292] 'process raft request' (duration: 395.640213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:04.587628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.252537ms","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-25T06:12:04.587781Z","caller":"traceutil/trace.go:171","msg":"trace[1772531036] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:838; }","duration":"353.791067ms","start":"2026-02-25T06:12:04.233273Z","end":"2026-02-25T06:12:04.587064Z","steps":["trace[1772531036] 'read index received' (duration: 353.466681ms)","trace[1772531036] 'applied index is now lower than readState.Index' (duration: 323.086µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:04.587916Z","caller":"traceutil/trace.go:171","msg":"trace[435504182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:810; }","duration":"354.661855ms","start":"2026-02-25T06:12:04.233197Z","end":"2026-02-25T06:12:04.587859Z","steps":["trace[435504182] 'agreement among raft nodes before linearized reading' (duration: 354.041142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:04.587782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:04.191116Z","time spent":"396.275896ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:04.588058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:04.233168Z","time spent":"354.87956ms","remote":"127.0.0.1:51248","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-25T06:12:04.588204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.03972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:04.588308Z","caller":"traceutil/trace.go:171","msg":"trace[1240053873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:810; }","duration":"223.168392ms","start":"2026-02-25T06:12:04.365076Z","end":"2026-02-25T06:12:04.588245Z","steps":["trace[1240053873] 'agreement among raft nodes before linearized reading' (duration: 223.06448ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:04.588692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.826368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:12:04.589036Z","caller":"traceutil/trace.go:171","msg":"trace[1551893920] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:810; }","duration":"186.207266ms","start":"2026-02-25T06:12:04.40282Z","end":"2026-02-25T06:12:04.589027Z","steps":["trace[1551893920] 'agreement among raft nodes before linearized reading' (duration: 185.40621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:04.588881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.956775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T06:12:04.589207Z","caller":"traceutil/trace.go:171","msg":"trace[2076840178] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:810; }","duration":"219.474346ms","start":"2026-02-25T06:12:04.369668Z","end":"2026-02-25T06:12:04.589142Z","steps":["trace[2076840178] 'agreement among raft nodes before linearized reading' (duration: 218.773771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:05.401613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.4543ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:05.402053Z","caller":"traceutil/trace.go:171","msg":"trace[1046378659] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"807.007324ms","start":"2026-02-25T06:12:04.595001Z","end":"2026-02-25T06:12:05.402009Z","steps":["trace[1046378659] 'process raft request' (duration: 509.044633ms)","trace[1046378659] 'compare' (duration: 297.257967ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:05.402256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:04.594988Z","time spent":"807.152777ms","remote":"127.0.0.1:51248","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-25T06:12:05.402271Z","caller":"traceutil/trace.go:171","msg":"trace[1711346585] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"805.734267ms","start":"2026-02-25T06:12:04.596503Z","end":"2026-02-25T06:12:05.402237Z","steps":["trace[1711346585] 'process raft request' (duration: 805.628074ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:05.402272Z","caller":"traceutil/trace.go:171","msg":"trace[1907015965] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:839; }","duration":"641.953535ms","start":"2026-02-25T06:12:04.7603Z","end":"2026-02-25T06:12:05.402253Z","steps":["trace[1907015965] 'read index received' (duration: 343.76642ms)","trace[1907015965] 'applied index is now lower than readState.Index' (duration: 298.126984ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:05.402392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:04.596495Z","time spent":"805.840029ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:05.402803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.464906ms","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-25T06:12:05.402884Z","caller":"traceutil/trace.go:171","msg":"trace[822895783] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"807.552685ms","start":"2026-02-25T06:12:04.595313Z","end":"2026-02-25T06:12:05.402866Z","steps":["trace[822895783] 'process raft request' (duration: 806.476232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:05.403164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:04.595306Z","time spent":"807.77875ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:05.402917Z","caller":"traceutil/trace.go:171","msg":"trace[1581838406] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:813; }","duration":"642.65821ms","start":"2026-02-25T06:12:04.760234Z","end":"2026-02-25T06:12:05.402892Z","steps":["trace[1581838406] 'agreement among raft nodes before linearized reading' (duration: 642.192229ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:05.403393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:04.760206Z","time spent":"643.167571ms","remote":"127.0.0.1:51248","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-25T06:12:18.061848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.835154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:18.062015Z","caller":"traceutil/trace.go:171","msg":"trace[444389257] transaction","detail":"{read_only:false; response_revision:899; number_of_response:1; }","duration":"224.879527ms","start":"2026-02-25T06:12:17.837098Z","end":"2026-02-25T06:12:18.061977Z","steps":["trace[444389257] 'process raft request' (duration: 72.798288ms)","trace[444389257] 'compare' (duration: 151.553859ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:20.116765Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":18421302007809679829,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T06:12:20.189311Z","caller":"traceutil/trace.go:171","msg":"trace[96490664] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"679.38139ms","start":"2026-02-25T06:12:19.509902Z","end":"2026-02-25T06:12:20.189284Z","steps":["trace[96490664] 'process raft request' (duration: 679.209267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:20.189489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:19.509878Z","time spent":"679.514644ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:21.252748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.079071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:21.252964Z","caller":"traceutil/trace.go:171","msg":"trace[665605400] linearizableReadLoop","detail":"{readStateIndex:939; appliedIndex:935; }","duration":"1.636532035s","start":"2026-02-25T06:12:19.616409Z","end":"2026-02-25T06:12:21.252941Z","steps":["trace[665605400] 'read index received' (duration: 572.749223ms)","trace[665605400] 'applied index is now lower than readState.Index' (duration: 1.063782082s)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:21.252957Z","caller":"traceutil/trace.go:171","msg":"trace[1709128793] transaction","detail":"{read_only:false; response_revision:903; number_of_response:1; }","duration":"1.74114572s","start":"2026-02-25T06:12:19.511786Z","end":"2026-02-25T06:12:21.252932Z","steps":["trace[1709128793] 'process raft request' (duration: 1.614772742s)","trace[1709128793] 'compare' (duration: 125.921908ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:21.253095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:19.511771Z","time spent":"1.741244603s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:21.253197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.63678743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:21.25323Z","caller":"traceutil/trace.go:171","msg":"trace[546781711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"1.63685728s","start":"2026-02-25T06:12:19.616366Z","end":"2026-02-25T06:12:21.253223Z","steps":["trace[546781711] 'agreement among raft nodes before linearized reading' (duration: 1.636804319s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.253247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:19.616343Z","time spent":"1.636901042s","remote":"127.0.0.1:50952","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-25T06:12:21.25329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.153664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:21.253311Z","caller":"traceutil/trace.go:171","msg":"trace[68019668] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:905; }","duration":"887.219235ms","start":"2026-02-25T06:12:20.366084Z","end":"2026-02-25T06:12:21.253303Z","steps":["trace[68019668] 'agreement among raft nodes before linearized reading' (duration: 887.180674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.253342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:20.36606Z","time spent":"887.277017ms","remote":"127.0.0.1:50964","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-25T06:12:21.253356Z","caller":"traceutil/trace.go:171","msg":"trace[959256750] transaction","detail":"{read_only:false; response_revision:904; number_of_response:1; }","duration":"1.740456327s","start":"2026-02-25T06:12:19.512892Z","end":"2026-02-25T06:12:21.253349Z","steps":["trace[959256750] 'process raft request' (duration: 1.739963477s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.253386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:19.512862Z","time spent":"1.740507427s","remote":"127.0.0.1:51248","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-25T06:12:21.253489Z","caller":"traceutil/trace.go:171","msg":"trace[1910025217] transaction","detail":"{read_only:false; response_revision:905; number_of_response:1; }","duration":"1.74061199s","start":"2026-02-25T06:12:19.512871Z","end":"2026-02-25T06:12:21.253483Z","steps":["trace[1910025217] 'process raft request' (duration: 1.740037368s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.253523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:19.512834Z","time spent":"1.740665001s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:21.253709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.524154ms","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-25T06:12:21.254109Z","caller":"traceutil/trace.go:171","msg":"trace[95022244] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:905; }","duration":"555.006035ms","start":"2026-02-25T06:12:20.699082Z","end":"2026-02-25T06:12:21.254088Z","steps":["trace[95022244] 'agreement among raft nodes before linearized reading' (duration: 554.405813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:21.254149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:20.699038Z","time spent":"555.102697ms","remote":"127.0.0.1:51248","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":"info","ts":"2026-02-25T06:12:33.726035Z","caller":"traceutil/trace.go:171","msg":"trace[2053653491] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"385.062524ms","start":"2026-02-25T06:12:33.340945Z","end":"2026-02-25T06:12:33.726008Z","steps":["trace[2053653491] 'read index received' (duration: 384.916821ms)","trace[2053653491] 'applied index is now lower than readState.Index' (duration: 144.693µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:33.726109Z","caller":"traceutil/trace.go:171","msg":"trace[1991693448] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"385.265319ms","start":"2026-02-25T06:12:33.340822Z","end":"2026-02-25T06:12:33.726087Z","steps":["trace[1991693448] 'process raft request' (duration: 385.012953ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:33.726215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:33.340807Z","time spent":"385.33502ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:33.726435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.444442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-02-25T06:12:33.726496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.608496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:33.726555Z","caller":"traceutil/trace.go:171","msg":"trace[347897154] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"343.689867ms","start":"2026-02-25T06:12:33.382854Z","end":"2026-02-25T06:12:33.726544Z","steps":["trace[347897154] 'agreement among raft nodes before linearized reading' (duration: 343.578775ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:33.726553Z","caller":"traceutil/trace.go:171","msg":"trace[1775145241] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:937; }","duration":"385.639015ms","start":"2026-02-25T06:12:33.340888Z","end":"2026-02-25T06:12:33.726527Z","steps":["trace[1775145241] 'agreement among raft nodes before linearized reading' (duration: 385.261038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:33.726595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:33.340862Z","time spent":"385.725308ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T06:12:33.726606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:33.382844Z","time spent":"343.734707ms","remote":"127.0.0.1:50952","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-25T06:12:33.726575Z","caller":"traceutil/trace.go:171","msg":"trace[717834303] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"383.562864ms","start":"2026-02-25T06:12:33.34295Z","end":"2026-02-25T06:12:33.726513Z","steps":["trace[717834303] 'process raft request' (duration: 383.442682ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:33.726592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.530486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:33.726715Z","caller":"traceutil/trace.go:171","msg":"trace[372156066] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:939; }","duration":"304.68269ms","start":"2026-02-25T06:12:33.422006Z","end":"2026-02-25T06:12:33.726689Z","steps":["trace[372156066] 'agreement among raft nodes before linearized reading' (duration: 304.510696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:33.726764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:33.421996Z","time spent":"304.755931ms","remote":"127.0.0.1:51108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:12:33.726809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:33.342931Z","time spent":"383.750358ms","remote":"127.0.0.1:51248","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-25T06:12:33.72839Z","caller":"traceutil/trace.go:171","msg":"trace[27007438] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"385.916963ms","start":"2026-02-25T06:12:33.342453Z","end":"2026-02-25T06:12:33.728369Z","steps":["trace[27007438] 'process raft request' (duration: 383.86578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:33.728486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:33.342435Z","time spent":"386.006554ms","remote":"127.0.0.1:51248","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-25T06:12:34.912365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.362345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:34.912471Z","caller":"traceutil/trace.go:171","msg":"trace[1722225267] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:941; }","duration":"461.538949ms","start":"2026-02-25T06:12:34.450902Z","end":"2026-02-25T06:12:34.912441Z","steps":["trace[1722225267] 'count revisions from in-memory index tree' (duration: 461.222711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:34.912525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:34.450881Z","time spent":"461.631491ms","remote":"127.0.0.1:42940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-02-25T06:12:40.17712Z","caller":"traceutil/trace.go:171","msg":"trace[1481219411] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"419.035909ms","start":"2026-02-25T06:12:39.75806Z","end":"2026-02-25T06:12:40.177096Z","steps":["trace[1481219411] 'process raft request' (duration: 418.911647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:40.17725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:39.75805Z","time spent":"419.133901ms","remote":"127.0.0.1:51248","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-25T06:12:40.828922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.444187ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:40.829065Z","caller":"traceutil/trace.go:171","msg":"trace[1374782438] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1011; }","duration":"1.049551072s","start":"2026-02-25T06:12:39.779499Z","end":"2026-02-25T06:12:40.82905Z","steps":["trace[1374782438] 'read index received' (duration: 397.476322ms)","trace[1374782438] 'applied index is now lower than readState.Index' (duration: 652.07408ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:40.829204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.164381ms","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-25T06:12:40.829213Z","caller":"traceutil/trace.go:171","msg":"trace[1757723717] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"1.067417972s","start":"2026-02-25T06:12:39.761777Z","end":"2026-02-25T06:12:40.829195Z","steps":["trace[1757723717] 'process raft request' (duration: 728.640258ms)","trace[1757723717] 'compare' (duration: 338.239392ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:40.829293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:39.761757Z","time spent":"1.067497404s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:40.829293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.049776368s","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-25T06:12:40.829157Z","caller":"traceutil/trace.go:171","msg":"trace[1986897832] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"1.065719398s","start":"2026-02-25T06:12:39.763403Z","end":"2026-02-25T06:12:40.829123Z","steps":["trace[1986897832] 'process raft request' (duration: 1.065607925s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:40.829359Z","caller":"traceutil/trace.go:171","msg":"trace[537836970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:976; }","duration":"1.049883759s","start":"2026-02-25T06:12:39.77946Z","end":"2026-02-25T06:12:40.829343Z","steps":["trace[537836970] 'agreement among raft nodes before linearized reading' (duration: 1.049663875s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:40.829231Z","caller":"traceutil/trace.go:171","msg":"trace[1360712763] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:976; }","duration":"421.225183ms","start":"2026-02-25T06:12:40.407999Z","end":"2026-02-25T06:12:40.829224Z","steps":["trace[1360712763] 'agreement among raft nodes before linearized reading' (duration: 421.127661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:40.829395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:39.779447Z","time spent":"1.049939511s","remote":"127.0.0.1:51248","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-25T06:12:40.829436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:40.407966Z","time spent":"421.462168ms","remote":"127.0.0.1:51248","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-25T06:12:40.829431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:39.763392Z","time spent":"1.065970553s","remote":"127.0.0.1:51248","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-25T06:12:41.536525Z","caller":"traceutil/trace.go:171","msg":"trace[709637305] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"703.245219ms","start":"2026-02-25T06:12:40.833254Z","end":"2026-02-25T06:12:41.5365Z","steps":["trace[709637305] 'process raft request' (duration: 689.200323ms)","trace[709637305] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/leases/kube-system/cilium-operator-resource-lock; req_size:474; } (duration: 13.363522ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:41.53679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:40.833234Z","time spent":"703.366691ms","remote":"127.0.0.1:51248","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-25T06:12:41.563098Z","caller":"traceutil/trace.go:171","msg":"trace[515716270] linearizableReadLoop","detail":"{readStateIndex:1016; appliedIndex:1014; }","duration":"175.792387ms","start":"2026-02-25T06:12:41.38727Z","end":"2026-02-25T06:12:41.563062Z","steps":["trace[515716270] 'read index received' (duration: 135.17531ms)","trace[515716270] 'applied index is now lower than readState.Index' (duration: 40.615847ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:41.563226Z","caller":"traceutil/trace.go:171","msg":"trace[1922596058] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"729.582725ms","start":"2026-02-25T06:12:40.833633Z","end":"2026-02-25T06:12:41.563216Z","steps":["trace[1922596058] 'process raft request' (duration: 729.246478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:41.563345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:40.833623Z","time spent":"729.634316ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:41.563461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.641303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:41.563549Z","caller":"traceutil/trace.go:171","msg":"trace[1647359895] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:978; }","duration":"137.768725ms","start":"2026-02-25T06:12:41.425758Z","end":"2026-02-25T06:12:41.563527Z","steps":["trace[1647359895] 'agreement among raft nodes before linearized reading' (duration: 137.580652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:41.56372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.447711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:41.563758Z","caller":"traceutil/trace.go:171","msg":"trace[1955141476] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:978; }","duration":"176.537733ms","start":"2026-02-25T06:12:41.387209Z","end":"2026-02-25T06:12:41.563747Z","steps":["trace[1955141476] 'agreement among raft nodes before linearized reading' (duration: 176.471892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:42.110412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.017084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T06:12:42.110522Z","caller":"traceutil/trace.go:171","msg":"trace[354793111] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:979; }","duration":"253.214598ms","start":"2026-02-25T06:12:41.857283Z","end":"2026-02-25T06:12:42.110497Z","steps":["trace[354793111] 'count revisions from in-memory index tree' (duration: 252.868841ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:42.511775Z","caller":"traceutil/trace.go:171","msg":"trace[603766946] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"130.156934ms","start":"2026-02-25T06:12:42.38159Z","end":"2026-02-25T06:12:42.511747Z","steps":["trace[603766946] 'process raft request' (duration: 129.9473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:43.524704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.109463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T06:12:43.524883Z","caller":"traceutil/trace.go:171","msg":"trace[2022160060] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:982; }","duration":"690.345768ms","start":"2026-02-25T06:12:42.834469Z","end":"2026-02-25T06:12:43.524815Z","steps":["trace[2022160060] 'range keys from in-memory index tree' (duration: 689.876386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:43.525034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:42.834447Z","time spent":"690.572832ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T06:12:43.525259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.550521ms","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-25T06:12:43.52537Z","caller":"traceutil/trace.go:171","msg":"trace[1663372940] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:982; }","duration":"690.743415ms","start":"2026-02-25T06:12:42.8346Z","end":"2026-02-25T06:12:43.525344Z","steps":["trace[1663372940] 'range keys from in-memory index tree' (duration: 690.230174ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:43.52549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:42.834568Z","time spent":"690.875338ms","remote":"127.0.0.1:51248","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-25T06:12:44.325933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.216315369s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-02-25T06:12:44.326226Z","caller":"traceutil/trace.go:171","msg":"trace[1214253173] linearizableReadLoop","detail":"{readStateIndex:1021; appliedIndex:1020; }","duration":"1.116133456s","start":"2026-02-25T06:12:43.21007Z","end":"2026-02-25T06:12:44.326203Z","steps":["trace[1214253173] 'read index received' (duration: 39.141µs)","trace[1214253173] 'applied index is now lower than readState.Index' (duration: 1.116093155s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:44.32687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.116781679s","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-25T06:12:44.326949Z","caller":"traceutil/trace.go:171","msg":"trace[538051128] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:982; }","duration":"1.116847851s","start":"2026-02-25T06:12:43.210063Z","end":"2026-02-25T06:12:44.326911Z","steps":["trace[538051128] 'agreement among raft nodes before linearized reading' (duration: 1.116743278s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:44.327002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:43.210005Z","time spent":"1.116982383s","remote":"127.0.0.1:51420","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":"info","ts":"2026-02-25T06:12:44.327427Z","caller":"traceutil/trace.go:171","msg":"trace[1627700203] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"794.982543ms","start":"2026-02-25T06:12:43.532428Z","end":"2026-02-25T06:12:44.32741Z","steps":["trace[1627700203] 'process raft request' (duration: 793.639855ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:44.327536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:43.532403Z","time spent":"795.074405ms","remote":"127.0.0.1:51248","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-25T06:12:44.623671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.160284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:44.623948Z","caller":"traceutil/trace.go:171","msg":"trace[494573580] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1021; }","duration":"297.648995ms","start":"2026-02-25T06:12:44.326272Z","end":"2026-02-25T06:12:44.623921Z","steps":["trace[494573580] 'read index received' (duration: 808.357µs)","trace[494573580] 'applied index is now lower than readState.Index' (duration: 296.838658ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:44.624061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.258309643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:44.624102Z","caller":"traceutil/trace.go:171","msg":"trace[130111473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"1.258390784s","start":"2026-02-25T06:12:43.365698Z","end":"2026-02-25T06:12:44.624088Z","steps":["trace[130111473] 'agreement among raft nodes before linearized reading' (duration: 1.258304102s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:44.624122Z","caller":"traceutil/trace.go:171","msg":"trace[1531648072] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"1.091579926s","start":"2026-02-25T06:12:43.532514Z","end":"2026-02-25T06:12:44.624094Z","steps":["trace[1531648072] 'process raft request' (duration: 794.885621ms)","trace[1531648072] 'compare' (duration: 295.833407ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:44.624151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:43.365677Z","time spent":"1.258461616s","remote":"127.0.0.1:50952","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-25T06:12:44.624228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.081979724s","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":"warn","ts":"2026-02-25T06:12:44.624254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:43.532457Z","time spent":"1.09172852s","remote":"127.0.0.1:51248","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-25T06:12:44.624292Z","caller":"traceutil/trace.go:171","msg":"trace[2100983016] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:984; }","duration":"1.082068556s","start":"2026-02-25T06:12:43.542198Z","end":"2026-02-25T06:12:44.624267Z","steps":["trace[2100983016] 'agreement among raft nodes before linearized reading' (duration: 1.081866282s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:44.624328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:43.542185Z","time spent":"1.082132527s","remote":"127.0.0.1:51248","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-25T06:12:44.62431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055821573s","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-25T06:12:44.624414Z","caller":"traceutil/trace.go:171","msg":"trace[1926727005] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:984; }","duration":"1.055993287s","start":"2026-02-25T06:12:43.568396Z","end":"2026-02-25T06:12:44.624389Z","steps":["trace[1926727005] 'agreement among raft nodes before linearized reading' (duration: 1.05563764s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:44.624468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:43.568377Z","time spent":"1.056081598s","remote":"127.0.0.1:51248","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-25T06:12:44.624246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.316764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T06:12:44.625124Z","caller":"traceutil/trace.go:171","msg":"trace[1377464801] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:984; }","duration":"377.291225ms","start":"2026-02-25T06:12:44.247805Z","end":"2026-02-25T06:12:44.625097Z","steps":["trace[1377464801] 'agreement among raft nodes before linearized reading' (duration: 376.201451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:44.625279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:44.247778Z","time spent":"377.395077ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T06:12:44.945851Z","caller":"traceutil/trace.go:171","msg":"trace[1814036738] linearizableReadLoop","detail":"{readStateIndex:1025; appliedIndex:1023; }","duration":"312.00775ms","start":"2026-02-25T06:12:44.633817Z","end":"2026-02-25T06:12:44.945825Z","steps":["trace[1814036738] 'read index received' (duration: 20.741968ms)","trace[1814036738] 'applied index is now lower than readState.Index' (duration: 291.264892ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:44.945898Z","caller":"traceutil/trace.go:171","msg":"trace[765497981] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"315.064664ms","start":"2026-02-25T06:12:44.630799Z","end":"2026-02-25T06:12:44.945864Z","steps":["trace[765497981] 'process raft request' (duration: 220.282285ms)","trace[765497981] 'compare' (duration: 94.525493ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:44.945898Z","caller":"traceutil/trace.go:171","msg":"trace[1097681455] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"311.403707ms","start":"2026-02-25T06:12:44.634459Z","end":"2026-02-25T06:12:44.945863Z","steps":["trace[1097681455] 'process raft request' (duration: 311.310735ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:44.946063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:44.630779Z","time spent":"315.193087ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:44.946067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.11227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:12:44.946063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:44.634449Z","time spent":"311.526799ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:44.946069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.226624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:44.946111Z","caller":"traceutil/trace.go:171","msg":"trace[749639007] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:987; }","duration":"182.215183ms","start":"2026-02-25T06:12:44.763882Z","end":"2026-02-25T06:12:44.946097Z","steps":["trace[749639007] 'agreement among raft nodes before linearized reading' (duration: 182.113161ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:44.946158Z","caller":"traceutil/trace.go:171","msg":"trace[1782777913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:987; }","duration":"312.346116ms","start":"2026-02-25T06:12:44.633792Z","end":"2026-02-25T06:12:44.946138Z","steps":["trace[1782777913] 'agreement among raft nodes before linearized reading' (duration: 312.179693ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:44.946198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:44.633782Z","time spent":"312.408238ms","remote":"127.0.0.1:50952","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-25T06:12:45.991791Z","caller":"traceutil/trace.go:171","msg":"trace[806573817] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"191.168822ms","start":"2026-02-25T06:12:45.80059Z","end":"2026-02-25T06:12:45.991759Z","steps":["trace[806573817] 'process raft request' (duration: 191.030148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:47.484747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.194888ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:47.48494Z","caller":"traceutil/trace.go:171","msg":"trace[1204422290] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:988; }","duration":"409.399113ms","start":"2026-02-25T06:12:47.075523Z","end":"2026-02-25T06:12:47.484923Z","steps":["trace[1204422290] 'range keys from in-memory index tree' (duration: 409.178188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:47.485545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.302287181s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:47.48574Z","caller":"traceutil/trace.go:171","msg":"trace[764513156] transaction","detail":"{read_only:false; response_revision:990; number_of_response:1; }","duration":"790.498779ms","start":"2026-02-25T06:12:46.695224Z","end":"2026-02-25T06:12:47.485723Z","steps":["trace[764513156] 'process raft request' (duration: 790.439608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:47.485807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:46.695189Z","time spent":"790.59108ms","remote":"127.0.0.1:51042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":790,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T06:12:47.486002Z","caller":"traceutil/trace.go:171","msg":"trace[261900819] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1027; }","duration":"1.354538473s","start":"2026-02-25T06:12:46.13145Z","end":"2026-02-25T06:12:47.485988Z","steps":["trace[261900819] 'read index received' (duration: 51.732862ms)","trace[261900819] 'applied index is now lower than readState.Index' (duration: 1.302803831s)"],"step_count":2} {"level":"info","ts":"2026-02-25T06:12:47.486177Z","caller":"traceutil/trace.go:171","msg":"trace[559662579] transaction","detail":"{read_only:false; response_revision:989; number_of_response:1; }","duration":"1.442440056s","start":"2026-02-25T06:12:46.043713Z","end":"2026-02-25T06:12:47.486153Z","steps":["trace[559662579] 'process raft request' (duration: 139.490411ms)","trace[559662579] 'compare' (duration: 1.302095347s)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:47.486316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:46.043695Z","time spent":"1.442561159s","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T06:12:47.48635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.35489312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-25T06:12:47.486404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152630455s","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-25T06:12:47.48645Z","caller":"traceutil/trace.go:171","msg":"trace[113765584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:990; }","duration":"1.152681846s","start":"2026-02-25T06:12:46.333755Z","end":"2026-02-25T06:12:47.486437Z","steps":["trace[113765584] 'agreement among raft nodes before linearized reading' (duration: 1.152469741s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:47.486444Z","caller":"traceutil/trace.go:171","msg":"trace[2048126740] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:990; }","duration":"1.355046183s","start":"2026-02-25T06:12:46.131375Z","end":"2026-02-25T06:12:47.486421Z","steps":["trace[2048126740] 'agreement among raft nodes before linearized reading' (duration: 1.35487721s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:47.486345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121449847s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T06:12:47.486596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.8853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-25T06:12:47.486574Z","caller":"traceutil/trace.go:171","msg":"trace[16712969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:990; }","duration":"1.121714552s","start":"2026-02-25T06:12:46.364845Z","end":"2026-02-25T06:12:47.486559Z","steps":["trace[16712969] 'agreement among raft nodes before linearized reading' (duration: 1.121431426s)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:47.48667Z","caller":"traceutil/trace.go:171","msg":"trace[1693309142] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:990; }","duration":"857.981851ms","start":"2026-02-25T06:12:46.628677Z","end":"2026-02-25T06:12:47.486658Z","steps":["trace[1693309142] 'agreement among raft nodes before linearized reading' (duration: 857.597913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:47.486684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:46.364835Z","time spent":"1.121837686s","remote":"127.0.0.1:50952","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-25T06:12:47.486782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.934651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T06:12:47.486702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:46.628654Z","time spent":"858.040683ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-25T06:12:47.486841Z","caller":"traceutil/trace.go:171","msg":"trace[1130874973] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:990; }","duration":"537.034243ms","start":"2026-02-25T06:12:46.949792Z","end":"2026-02-25T06:12:47.486826Z","steps":["trace[1130874973] 'agreement among raft nodes before linearized reading' (duration: 536.43197ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:47.486892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:46.949767Z","time spent":"537.113126ms","remote":"127.0.0.1:51248","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-25T06:12:47.486564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.651332ms","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-25T06:12:47.487117Z","caller":"traceutil/trace.go:171","msg":"trace[119702312] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:990; }","duration":"828.247904ms","start":"2026-02-25T06:12:46.658851Z","end":"2026-02-25T06:12:47.487099Z","steps":["trace[119702312] 'agreement among raft nodes before linearized reading' (duration: 827.443198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:47.486462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.493352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T06:12:47.487362Z","caller":"traceutil/trace.go:171","msg":"trace[2114749639] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:990; }","duration":"537.418171ms","start":"2026-02-25T06:12:46.949907Z","end":"2026-02-25T06:12:47.487326Z","steps":["trace[2114749639] 'agreement among raft nodes before linearized reading' (duration: 536.39778ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:47.486514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:46.131351Z","time spent":"1.355146136s","remote":"127.0.0.1:51060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":6,"response size":31,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-02-25T06:12:47.487413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:46.949873Z","time spent":"537.526124ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T06:12:47.486493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:46.33369Z","time spent":"1.152795928s","remote":"127.0.0.1:51248","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-25T06:12:47.488521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:46.658826Z","time spent":"828.468148ms","remote":"127.0.0.1:51248","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-25T06:12:47.901789Z","caller":"traceutil/trace.go:171","msg":"trace[524932124] transaction","detail":"{read_only:false; response_revision:991; number_of_response:1; }","duration":"411.178029ms","start":"2026-02-25T06:12:47.490583Z","end":"2026-02-25T06:12:47.901761Z","steps":["trace[524932124] 'process raft request' (duration: 410.873573ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:47.902042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:47.490563Z","time spent":"411.385484ms","remote":"127.0.0.1:51042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-25T06:12:48.039019Z","caller":"traceutil/trace.go:171","msg":"trace[151774586] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"544.775637ms","start":"2026-02-25T06:12:47.494222Z","end":"2026-02-25T06:12:48.038997Z","steps":["trace[151774586] 'process raft request' (duration: 544.681425ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:48.039074Z","caller":"traceutil/trace.go:171","msg":"trace[825608616] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"545.234276ms","start":"2026-02-25T06:12:47.493822Z","end":"2026-02-25T06:12:48.039056Z","steps":["trace[825608616] 'process raft request' (duration: 545.037342ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:48.039218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:47.494213Z","time spent":"544.91275ms","remote":"127.0.0.1:51248","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-25T06:12:48.039274Z","caller":"traceutil/trace.go:171","msg":"trace[531732050] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"544.197835ms","start":"2026-02-25T06:12:47.495064Z","end":"2026-02-25T06:12:48.039262Z","steps":["trace[531732050] 'process raft request' (duration: 543.885088ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:48.03927Z","caller":"traceutil/trace.go:171","msg":"trace[1625929931] linearizableReadLoop","detail":"{readStateIndex:1035; appliedIndex:1029; }","duration":"545.092564ms","start":"2026-02-25T06:12:47.494161Z","end":"2026-02-25T06:12:48.039254Z","steps":["trace[1625929931] 'read index received' (duration: 407.428871ms)","trace[1625929931] 'applied index is now lower than readState.Index' (duration: 137.662493ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:48.039221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:47.493814Z","time spent":"545.344519ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:48.039343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:47.495014Z","time spent":"544.291627ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:48.039059Z","caller":"traceutil/trace.go:171","msg":"trace[493437263] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"545.314759ms","start":"2026-02-25T06:12:47.493697Z","end":"2026-02-25T06:12:48.039012Z","steps":["trace[493437263] 'process raft request' (duration: 545.084464ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:48.040957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:47.493665Z","time spent":"547.234179ms","remote":"127.0.0.1:51248","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-25T06:12:48.039397Z","caller":"traceutil/trace.go:171","msg":"trace[1656344332] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"534.370787ms","start":"2026-02-25T06:12:47.505013Z","end":"2026-02-25T06:12:48.039384Z","steps":["trace[1656344332] 'process raft request' (duration: 534.163943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:48.041412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:47.504993Z","time spent":"536.36505ms","remote":"127.0.0.1:51248","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-25T06:12:48.039423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.821142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T06:12:48.041548Z","caller":"traceutil/trace.go:171","msg":"trace[1962047867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:997; }","duration":"541.948358ms","start":"2026-02-25T06:12:47.49958Z","end":"2026-02-25T06:12:48.041528Z","steps":["trace[1962047867] 'agreement among raft nodes before linearized reading' (duration: 539.808722ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:48.041631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:47.499572Z","time spent":"542.04148ms","remote":"127.0.0.1:50952","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-25T06:12:48.039531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.36365ms","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-25T06:12:48.041702Z","caller":"traceutil/trace.go:171","msg":"trace[2144103962] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:997; }","duration":"547.552056ms","start":"2026-02-25T06:12:47.494133Z","end":"2026-02-25T06:12:48.041685Z","steps":["trace[2144103962] 'agreement among raft nodes before linearized reading' (duration: 545.298918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:48.041752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:47.49412Z","time spent":"547.616667ms","remote":"127.0.0.1:51248","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":"warn","ts":"2026-02-25T06:12:48.039535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.829381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.30\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T06:12:48.041863Z","caller":"traceutil/trace.go:171","msg":"trace[994554491] range","detail":"{range_begin:/registry/masterleases/199.204.45.30; range_end:; response_count:1; response_revision:997; }","duration":"263.16216ms","start":"2026-02-25T06:12:47.778659Z","end":"2026-02-25T06:12:48.041821Z","steps":["trace[994554491] 'agreement among raft nodes before linearized reading' (duration: 260.775299ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:48.03895Z","caller":"traceutil/trace.go:171","msg":"trace[1477110758] transaction","detail":"{read_only:false; response_revision:992; number_of_response:1; }","duration":"545.488572ms","start":"2026-02-25T06:12:47.493425Z","end":"2026-02-25T06:12:48.038914Z","steps":["trace[1477110758] 'process raft request' (duration: 545.158045ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:48.042002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:47.493395Z","time spent":"548.567937ms","remote":"127.0.0.1:51042","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":772,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-25T06:12:48.483575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.016744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T06:12:48.484063Z","caller":"traceutil/trace.go:171","msg":"trace[185414416] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"396.751166ms","start":"2026-02-25T06:12:48.087291Z","end":"2026-02-25T06:12:48.484043Z","steps":["trace[185414416] 'process raft request' (duration: 396.682074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:48.484148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:48.087264Z","time spent":"396.847308ms","remote":"127.0.0.1:50998","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-25T06:12:48.484151Z","caller":"traceutil/trace.go:171","msg":"trace[253279321] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"437.802311ms","start":"2026-02-25T06:12:48.046316Z","end":"2026-02-25T06:12:48.484118Z","steps":["trace[253279321] 'process raft request' (duration: 111.157684ms)","trace[253279321] 'compare' (duration: 325.859881ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T06:12:48.484333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:48.046295Z","time spent":"437.948965ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:53.978931Z","caller":"traceutil/trace.go:171","msg":"trace[202191198] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"484.466847ms","start":"2026-02-25T06:12:53.494435Z","end":"2026-02-25T06:12:53.978902Z","steps":["trace[202191198] 'process raft request' (duration: 484.195451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:53.979138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:53.494404Z","time spent":"484.604219ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T06:12:54.363098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.464965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-25T06:12:54.363191Z","caller":"traceutil/trace.go:171","msg":"trace[2046971595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1026; }","duration":"289.622488ms","start":"2026-02-25T06:12:54.073548Z","end":"2026-02-25T06:12:54.363171Z","steps":["trace[2046971595] 'range keys from in-memory index tree' (duration: 289.24145ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:54.363199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.350776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T06:12:54.363288Z","caller":"traceutil/trace.go:171","msg":"trace[2049671446] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1026; }","duration":"293.484269ms","start":"2026-02-25T06:12:54.06978Z","end":"2026-02-25T06:12:54.363264Z","steps":["trace[2049671446] 'range keys from in-memory index tree' (duration: 293.190473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:54.363295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.462564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-25T06:12:54.363347Z","caller":"traceutil/trace.go:171","msg":"trace[1911517425] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1026; }","duration":"289.515585ms","start":"2026-02-25T06:12:54.073817Z","end":"2026-02-25T06:12:54.363333Z","steps":["trace[1911517425] 'range keys from in-memory index tree' (duration: 289.273379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:54.36321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.095714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-02-25T06:12:54.363356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.057777ms","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-25T06:12:54.363398Z","caller":"traceutil/trace.go:171","msg":"trace[1148918042] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1026; }","duration":"296.308029ms","start":"2026-02-25T06:12:54.067072Z","end":"2026-02-25T06:12:54.36338Z","steps":["trace[1148918042] 'range keys from in-memory index tree' (duration: 295.827409ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:54.363457Z","caller":"traceutil/trace.go:171","msg":"trace[909236444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1026; }","duration":"290.2037ms","start":"2026-02-25T06:12:54.073229Z","end":"2026-02-25T06:12:54.363432Z","steps":["trace[909236444] 'range keys from in-memory index tree' (duration: 289.859893ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:12:54.704931Z","caller":"traceutil/trace.go:171","msg":"trace[1134413704] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"336.336572ms","start":"2026-02-25T06:12:54.368575Z","end":"2026-02-25T06:12:54.704912Z","steps":["trace[1134413704] 'process raft request' (duration: 336.200509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:54.705048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:54.368554Z","time spent":"336.436014ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:54.706396Z","caller":"traceutil/trace.go:171","msg":"trace[1951831670] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"332.661084ms","start":"2026-02-25T06:12:54.373716Z","end":"2026-02-25T06:12:54.706377Z","steps":["trace[1951831670] 'process raft request' (duration: 332.632003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:54.706522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:54.373699Z","time spent":"332.759326ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:54.706809Z","caller":"traceutil/trace.go:171","msg":"trace[11575369] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"338.226863ms","start":"2026-02-25T06:12:54.368575Z","end":"2026-02-25T06:12:54.706802Z","steps":["trace[11575369] 'process raft request' (duration: 337.626949ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:54.706845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:54.368554Z","time spent":"338.271783ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:54.70692Z","caller":"traceutil/trace.go:171","msg":"trace[1064394815] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"337.864815ms","start":"2026-02-25T06:12:54.369049Z","end":"2026-02-25T06:12:54.706914Z","steps":["trace[1064394815] 'process raft request' (duration: 337.253391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:54.706956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:54.369041Z","time spent":"337.897985ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:54.707093Z","caller":"traceutil/trace.go:171","msg":"trace[1061458956] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"337.915725ms","start":"2026-02-25T06:12:54.369168Z","end":"2026-02-25T06:12:54.707083Z","steps":["trace[1061458956] 'process raft request' (duration: 337.159559ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T06:12:54.707172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T06:12:54.369143Z","time spent":"338.004848ms","remote":"127.0.0.1:51248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T06:12:55.789113Z","caller":"traceutil/trace.go:171","msg":"trace[770860227] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"149.470133ms","start":"2026-02-25T06:12:55.639605Z","end":"2026-02-25T06:12:55.789075Z","steps":["trace[770860227] 'process raft request' (duration: 149.225938ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T06:16:19.524051Z","caller":"traceutil/trace.go:171","msg":"trace[1454787362] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"107.245354ms","start":"2026-02-25T06:16:19.416775Z","end":"2026-02-25T06:16:19.524021Z","steps":["trace[1454787362] 'process raft request' (duration: 107.06426ms)"],"step_count":1}