{"level":"warn","ts":"2026-04-02T16:39:40.418185Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T16:39:40.4183Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.69: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.69:2380","--initial-cluster=instance=https://199.204.45.69:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.69:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.69: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-04-02T16:39:40.418388Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T16:39:40.418404Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-02T16:39:40.41843Z","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-04-02T16:39:40.419672Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"]} {"level":"info","ts":"2026-04-02T16:39:40.419862Z","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.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.69: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-04-02T16:39:40.425244Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.904377ms"} {"level":"info","ts":"2026-04-02T16:39:40.431876Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fe334179879fd0aa","cluster-id":"d79a8cccc23ced73"} {"level":"info","ts":"2026-04-02T16:39:40.431972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T16:39:40.432003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 0"} {"level":"info","ts":"2026-04-02T16:39:40.432022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fe334179879fd0aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T16:39:40.432047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 1"} {"level":"info","ts":"2026-04-02T16:39:40.432183Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"warn","ts":"2026-04-02T16:39:40.440214Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T16:39:40.442019Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T16:39:40.443094Z","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-04-02T16:39:40.445174Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fe334179879fd0aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T16:39:40.445429Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fe334179879fd0aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T16:39:40.44736Z","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-04-02T16:39:40.448001Z","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-04-02T16:39:40.448067Z","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-04-02T16:39:40.448417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"info","ts":"2026-04-02T16:39:40.449105Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","added-peer-id":"fe334179879fd0aa","added-peer-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-02T16:39:40.451239Z","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-04-02T16:39:40.451414Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-02T16:39:40.451439Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-02T16:39:40.451756Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fe334179879fd0aa","initial-advertise-peer-urls":["https://199.204.45.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T16:39:40.451798Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T16:39:41.333527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T16:39:41.333602Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T16:39:41.333639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgPreVoteResp from fe334179879fd0aa at term 1"} {"level":"info","ts":"2026-04-02T16:39:41.333667Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became candidate at term 2"} {"level":"info","ts":"2026-04-02T16:39:41.333677Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgVoteResp from fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-02T16:39:41.333699Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became leader at term 2"} {"level":"info","ts":"2026-04-02T16:39:41.33372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fe334179879fd0aa elected leader fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-02T16:39:41.33511Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fe334179879fd0aa","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.69:2379]}","request-path":"/0/members/fe334179879fd0aa/attributes","cluster-id":"d79a8cccc23ced73","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T16:39:41.335137Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T16:39:41.335119Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:39:41.335218Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T16:39:41.336149Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:39:41.336296Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:39:41.336365Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T16:39:41.336289Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T16:39:41.336429Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T16:39:41.341804Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.69:2379"} {"level":"info","ts":"2026-04-02T16:39:41.342723Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T16:39:43.058555Z","caller":"traceutil/trace.go:171","msg":"trace[1513284344] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"335.13404ms","start":"2026-04-02T16:39:42.723379Z","end":"2026-04-02T16:39:43.058513Z","steps":["trace[1513284344] 'process raft request' (duration: 334.740911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:43.059377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:42.723363Z","time spent":"335.293793ms","remote":"127.0.0.1:33078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:39:43.069714Z","caller":"traceutil/trace.go:171","msg":"trace[2144426269] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"345.386942ms","start":"2026-04-02T16:39:42.724313Z","end":"2026-04-02T16:39:43.0697Z","steps":["trace[2144426269] 'process raft request' (duration: 345.183687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:43.069788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:42.724301Z","time spent":"345.452754ms","remote":"127.0.0.1:33144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:39:43.069861Z","caller":"traceutil/trace.go:171","msg":"trace[1496073392] linearizableReadLoop","detail":"{readStateIndex:8; appliedIndex:6; }","duration":"345.547246ms","start":"2026-04-02T16:39:42.724288Z","end":"2026-04-02T16:39:43.069836Z","steps":["trace[1496073392] 'read index received' (duration: 333.84766ms)","trace[1496073392] 'applied index is now lower than readState.Index' (duration: 11.698296ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:39:43.069961Z","caller":"traceutil/trace.go:171","msg":"trace[75243146] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"345.508424ms","start":"2026-04-02T16:39:42.724441Z","end":"2026-04-02T16:39:43.06995Z","steps":["trace[75243146] 'process raft request' (duration: 345.107955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:43.070056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:42.724427Z","time spent":"345.567876ms","remote":"127.0.0.1:33144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:39:43.070146Z","caller":"traceutil/trace.go:171","msg":"trace[536887205] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"345.650697ms","start":"2026-04-02T16:39:42.72447Z","end":"2026-04-02T16:39:43.07012Z","steps":["trace[536887205] 'process raft request' (duration: 345.180926ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:43.070185Z","caller":"traceutil/trace.go:171","msg":"trace[389624626] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"336.959143ms","start":"2026-04-02T16:39:42.733211Z","end":"2026-04-02T16:39:43.07017Z","steps":["trace[389624626] 'process raft request' (duration: 336.508622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:43.070232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:42.733188Z","time spent":"337.018803ms","remote":"127.0.0.1:32802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:39:43.070258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:42.724458Z","time spent":"345.743999ms","remote":"127.0.0.1:33144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:39:43.069817Z","caller":"traceutil/trace.go:171","msg":"trace[2095207801] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"345.681817ms","start":"2026-04-02T16:39:42.72411Z","end":"2026-04-02T16:39:43.069792Z","steps":["trace[2095207801] 'process raft request' (duration: 345.288819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:43.070655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:42.724095Z","time spent":"346.310073ms","remote":"127.0.0.1:33144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:39:43.070867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.534519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T16:39:43.070947Z","caller":"traceutil/trace.go:171","msg":"trace[874567542] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"346.648231ms","start":"2026-04-02T16:39:42.724281Z","end":"2026-04-02T16:39:43.070929Z","steps":["trace[874567542] 'agreement among raft nodes before linearized reading' (duration: 345.949184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:43.070987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:42.724147Z","time spent":"346.830975ms","remote":"127.0.0.1:60926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-02T16:39:43.071836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.402009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T16:39:43.071895Z","caller":"traceutil/trace.go:171","msg":"trace[1584383033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"347.488311ms","start":"2026-04-02T16:39:42.724389Z","end":"2026-04-02T16:39:43.071877Z","steps":["trace[1584383033] 'agreement among raft nodes before linearized reading' (duration: 347.300076ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:43.072497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:42.724378Z","time spent":"348.105654ms","remote":"127.0.0.1:60830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T16:39:43.072491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.552066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T16:39:43.072569Z","caller":"traceutil/trace.go:171","msg":"trace[1176340516] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"277.645488ms","start":"2026-04-02T16:39:42.794904Z","end":"2026-04-02T16:39:43.07255Z","steps":["trace[1176340516] 'agreement among raft nodes before linearized reading' (duration: 277.445184ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:43.072711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.572489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-02T16:39:43.072709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.147028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T16:39:43.072702Z","caller":"traceutil/trace.go:171","msg":"trace[547731586] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"345.355601ms","start":"2026-04-02T16:39:42.724827Z","end":"2026-04-02T16:39:43.070182Z","steps":["trace[547731586] 'process raft request' (duration: 344.858229ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:43.072793Z","caller":"traceutil/trace.go:171","msg":"trace[1930530401] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"329.297871ms","start":"2026-04-02T16:39:42.74346Z","end":"2026-04-02T16:39:43.072758Z","steps":["trace[1930530401] 'agreement among raft nodes before linearized reading' (duration: 329.048736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:43.072806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.896618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-02T16:39:43.072847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:42.743448Z","time spent":"329.391674ms","remote":"127.0.0.1:33016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-02T16:39:43.072851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:42.724807Z","time spent":"347.995352ms","remote":"127.0.0.1:33144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:39:43.072865Z","caller":"traceutil/trace.go:171","msg":"trace[803019653] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"338.96119ms","start":"2026-04-02T16:39:42.733883Z","end":"2026-04-02T16:39:43.072844Z","steps":["trace[803019653] 'agreement among raft nodes before linearized reading' (duration: 338.884468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:43.0729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:42.733874Z","time spent":"339.01882ms","remote":"127.0.0.1:60956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-02T16:39:43.072909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.282786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T16:39:43.072953Z","caller":"traceutil/trace.go:171","msg":"trace[357366836] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"298.341059ms","start":"2026-04-02T16:39:42.774602Z","end":"2026-04-02T16:39:43.072943Z","steps":["trace[357366836] 'agreement among raft nodes before linearized reading' (duration: 298.275717ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:43.072759Z","caller":"traceutil/trace.go:171","msg":"trace[156373028] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"338.634462ms","start":"2026-04-02T16:39:42.734112Z","end":"2026-04-02T16:39:43.072747Z","steps":["trace[156373028] 'agreement among raft nodes before linearized reading' (duration: 338.56205ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:43.07307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:39:42.734102Z","time spent":"338.95866ms","remote":"127.0.0.1:60956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-02T16:39:44.286041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.386962ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:39:44.286257Z","caller":"traceutil/trace.go:171","msg":"trace[766869506] linearizableReadLoop","detail":"{readStateIndex:168; appliedIndex:167; }","duration":"200.083079ms","start":"2026-04-02T16:39:44.086094Z","end":"2026-04-02T16:39:44.286177Z","steps":["trace[766869506] 'read index received' (duration: 47.228307ms)","trace[766869506] 'applied index is now lower than readState.Index' (duration: 152.853222ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:39:44.286264Z","caller":"traceutil/trace.go:171","msg":"trace[364126643] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"207.570225ms","start":"2026-04-02T16:39:44.078644Z","end":"2026-04-02T16:39:44.286214Z","steps":["trace[364126643] 'process raft request' (duration: 54.686032ms)","trace[364126643] 'compare' (duration: 152.213006ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:39:44.286486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.375627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:39:44.286531Z","caller":"traceutil/trace.go:171","msg":"trace[1555850741] range","detail":"{range_begin:/registry/clusterroles/system:controller:statefulset-controller; range_end:; response_count:0; response_revision:163; }","duration":"200.453037ms","start":"2026-04-02T16:39:44.086063Z","end":"2026-04-02T16:39:44.286516Z","steps":["trace[1555850741] 'agreement among raft nodes before linearized reading' (duration: 200.275434ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:39:45.309924Z","caller":"traceutil/trace.go:171","msg":"trace[211160561] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"114.328128ms","start":"2026-04-02T16:39:45.195567Z","end":"2026-04-02T16:39:45.309895Z","steps":["trace[211160561] 'process raft request' (duration: 109.978526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:39:45.875847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.725692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:39:45.875974Z","caller":"traceutil/trace.go:171","msg":"trace[530151104] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"120.038781ms","start":"2026-04-02T16:39:45.755908Z","end":"2026-04-02T16:39:45.875947Z","steps":["trace[530151104] 'process raft request' (duration: 18.115664ms)","trace[530151104] 'compare' (duration: 101.572219ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:11.696835Z","caller":"traceutil/trace.go:171","msg":"trace[619136981] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"109.989527ms","start":"2026-04-02T16:40:11.586805Z","end":"2026-04-02T16:40:11.696795Z","steps":["trace[619136981] 'process raft request' (duration: 109.631839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:12.263678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.841316ms","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-04-02T16:40:12.264417Z","caller":"traceutil/trace.go:171","msg":"trace[994214775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:475; }","duration":"479.652483ms","start":"2026-04-02T16:40:11.784735Z","end":"2026-04-02T16:40:12.264388Z","steps":["trace[994214775] 'range keys from in-memory index tree' (duration: 478.608751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:12.264481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:11.784699Z","time spent":"479.764646ms","remote":"127.0.0.1:32884","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":"info","ts":"2026-04-02T16:40:12.265001Z","caller":"traceutil/trace.go:171","msg":"trace[269533258] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"465.282181ms","start":"2026-04-02T16:40:11.799687Z","end":"2026-04-02T16:40:12.26497Z","steps":["trace[269533258] 'process raft request' (duration: 464.79473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:12.265295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:11.799651Z","time spent":"465.452273ms","remote":"127.0.0.1:60932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:40:14.776376Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003268836103287,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:40:15.277276Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003268836103287,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:40:15.71771Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.626851755s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T16:40:15.718312Z","caller":"traceutil/trace.go:171","msg":"trace[456799502] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.627594281s","start":"2026-04-02T16:40:14.090681Z","end":"2026-04-02T16:40:15.718275Z","steps":["trace[456799502] 'process raft request' (duration: 1.627332066s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:15.718505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:14.090651Z","time spent":"1.627750315s","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:40:16.111595Z","caller":"traceutil/trace.go:171","msg":"trace[872714008] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"2.011533333s","start":"2026-04-02T16:40:14.100004Z","end":"2026-04-02T16:40:16.111537Z","steps":["trace[872714008] 'process raft request' (duration: 2.011093484s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:16.111671Z","caller":"traceutil/trace.go:171","msg":"trace[1176493698] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"2.007335706s","start":"2026-04-02T16:40:14.104277Z","end":"2026-04-02T16:40:16.111613Z","steps":["trace[1176493698] 'process raft request' (duration: 2.00707491s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:16.111765Z","caller":"traceutil/trace.go:171","msg":"trace[572803308] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"2.001039876s","start":"2026-04-02T16:40:14.110619Z","end":"2026-04-02T16:40:16.111659Z","steps":["trace[572803308] 'process raft request' (duration: 2.000805761s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:16.111869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:14.099992Z","time spent":"2.011782189s","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:40:16.111948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:14.104265Z","time spent":"2.007572942s","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:40:16.111973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:14.110594Z","time spent":"2.00129026s","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:40:16.112051Z","caller":"traceutil/trace.go:171","msg":"trace[310925970] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"1.984179403s","start":"2026-04-02T16:40:14.12786Z","end":"2026-04-02T16:40:16.112039Z","steps":["trace[310925970] 'process raft request' (duration: 1.983615651s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:16.112101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:14.12784Z","time spent":"1.984238016s","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:40:16.112161Z","caller":"traceutil/trace.go:171","msg":"trace[126215568] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"1.975901061s","start":"2026-04-02T16:40:14.136246Z","end":"2026-04-02T16:40:16.112147Z","steps":["trace[126215568] 'process raft request' (duration: 1.975420291s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:16.112199Z","caller":"traceutil/trace.go:171","msg":"trace[1524747390] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.972180233s","start":"2026-04-02T16:40:14.140008Z","end":"2026-04-02T16:40:16.112188Z","steps":["trace[1524747390] 'process raft request' (duration: 1.971911948s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:16.112212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:14.136233Z","time spent":"1.975953522s","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:40:16.11224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:14.139991Z","time spent":"1.972230264s","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:40:16.112345Z","caller":"traceutil/trace.go:171","msg":"trace[246209428] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"1.947983408s","start":"2026-04-02T16:40:14.16434Z","end":"2026-04-02T16:40:16.112323Z","steps":["trace[246209428] 'process raft request' (duration: 1.947843256s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:16.112439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:14.164311Z","time spent":"1.94809303s","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:40:16.112489Z","caller":"traceutil/trace.go:171","msg":"trace[820619105] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"1.941233598s","start":"2026-04-02T16:40:14.171239Z","end":"2026-04-02T16:40:16.112472Z","steps":["trace[820619105] 'process raft request' (duration: 1.941020324s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:16.112564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:14.171214Z","time spent":"1.94131133s","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:40:16.112596Z","caller":"traceutil/trace.go:171","msg":"trace[1481382003] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"1.972205664s","start":"2026-04-02T16:40:14.140064Z","end":"2026-04-02T16:40:16.11227Z","steps":["trace[1481382003] 'process raft request' (duration: 1.97204862s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:16.112676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:14.140061Z","time spent":"1.972575871s","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:40:16.112824Z","caller":"traceutil/trace.go:171","msg":"trace[2122316927] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.918582496s","start":"2026-04-02T16:40:14.194202Z","end":"2026-04-02T16:40:16.112784Z","steps":["trace[2122316927] 'process raft request' (duration: 1.918259319s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:16.113043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:14.194158Z","time spent":"1.91881601s","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:40:16.118042Z","caller":"traceutil/trace.go:171","msg":"trace[1987822794] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:531; }","duration":"1.842263283s","start":"2026-04-02T16:40:14.275759Z","end":"2026-04-02T16:40:16.118022Z","steps":["trace[1987822794] 'read index received' (duration: 1.442297256s)","trace[1987822794] 'applied index is now lower than readState.Index' (duration: 399.960827ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:16.118093Z","caller":"traceutil/trace.go:171","msg":"trace[368099822] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"389.969658ms","start":"2026-04-02T16:40:15.728109Z","end":"2026-04-02T16:40:16.118079Z","steps":["trace[368099822] 'process raft request' (duration: 389.903327ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:16.118214Z","caller":"traceutil/trace.go:171","msg":"trace[1771453721] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"1.803138308s","start":"2026-04-02T16:40:14.315063Z","end":"2026-04-02T16:40:16.118201Z","steps":["trace[1771453721] 'process raft request' (duration: 1.802761349s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:16.118312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:14.315019Z","time spent":"1.803251801s","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:40:16.118453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:15.728077Z","time spent":"390.04963ms","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:40:16.118597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337783854s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.69\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T16:40:16.118687Z","caller":"traceutil/trace.go:171","msg":"trace[1357904076] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"392.422429ms","start":"2026-04-02T16:40:15.726251Z","end":"2026-04-02T16:40:16.118673Z","steps":["trace[1357904076] 'process raft request' (duration: 391.716185ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:16.118745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:15.726211Z","time spent":"392.510772ms","remote":"127.0.0.1:33144","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:40:16.118838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.84307761s","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-04-02T16:40:16.118716Z","caller":"traceutil/trace.go:171","msg":"trace[2012747188] range","detail":"{range_begin:/registry/masterleases/199.204.45.69; range_end:; response_count:1; response_revision:528; }","duration":"1.338005608s","start":"2026-04-02T16:40:14.780656Z","end":"2026-04-02T16:40:16.118661Z","steps":["trace[2012747188] 'agreement among raft nodes before linearized reading' (duration: 1.337711952s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:16.118894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:14.780625Z","time spent":"1.338258453s","remote":"127.0.0.1:60876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.69\" "} {"level":"info","ts":"2026-04-02T16:40:16.118869Z","caller":"traceutil/trace.go:171","msg":"trace[1678640452] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"1.843142772s","start":"2026-04-02T16:40:14.275719Z","end":"2026-04-02T16:40:16.118862Z","steps":["trace[1678640452] 'agreement among raft nodes before linearized reading' (duration: 1.84305489s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:16.119106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:14.275677Z","time spent":"1.843364806s","remote":"127.0.0.1:32884","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-04-02T16:40:16.11913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028351826s","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-04-02T16:40:16.119163Z","caller":"traceutil/trace.go:171","msg":"trace[1802511393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:528; }","duration":"1.02843079s","start":"2026-04-02T16:40:15.090723Z","end":"2026-04-02T16:40:16.119153Z","steps":["trace[1802511393] 'agreement among raft nodes before linearized reading' (duration: 1.028350038s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:16.119188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:15.090696Z","time spent":"1.02848626s","remote":"127.0.0.1:32884","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-04-02T16:40:16.119208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.522158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T16:40:16.119265Z","caller":"traceutil/trace.go:171","msg":"trace[642289041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"376.58097ms","start":"2026-04-02T16:40:15.742676Z","end":"2026-04-02T16:40:16.119257Z","steps":["trace[642289041] 'agreement among raft nodes before linearized reading' (duration: 376.411626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:16.119322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:15.742622Z","time spent":"376.692782ms","remote":"127.0.0.1:32884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T16:40:16.119355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.078697104s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:40:16.119429Z","caller":"traceutil/trace.go:171","msg":"trace[122576151] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:528; }","duration":"1.078821536s","start":"2026-04-02T16:40:15.040599Z","end":"2026-04-02T16:40:16.11942Z","steps":["trace[122576151] 'agreement among raft nodes before linearized reading' (duration: 1.078697683s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:16.119492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:15.040568Z","time spent":"1.078880657s","remote":"127.0.0.1:60834","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-04-02T16:40:16.119497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.204623627s","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-04-02T16:40:16.119546Z","caller":"traceutil/trace.go:171","msg":"trace[2026758815] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:528; }","duration":"1.204685588s","start":"2026-04-02T16:40:14.914831Z","end":"2026-04-02T16:40:16.119516Z","steps":["trace[2026758815] 'agreement among raft nodes before linearized reading' (duration: 1.204637127s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:16.119573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:14.914809Z","time spent":"1.20475834s","remote":"127.0.0.1:32884","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-04-02T16:40:16.784584Z","caller":"traceutil/trace.go:171","msg":"trace[1560130725] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"125.459109ms","start":"2026-04-02T16:40:16.659099Z","end":"2026-04-02T16:40:16.784558Z","steps":["trace[1560130725] 'process raft request' (duration: 85.269466ms)","trace[1560130725] 'compare' (duration: 40.059581ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:17.40302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.661315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:40:17.403157Z","caller":"traceutil/trace.go:171","msg":"trace[1942465982] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"590.637132ms","start":"2026-04-02T16:40:16.812499Z","end":"2026-04-02T16:40:17.403136Z","steps":["trace[1942465982] 'process raft request' (duration: 282.765763ms)","trace[1942465982] 'compare' (duration: 307.44538ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:17.403249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:16.812461Z","time spent":"590.730934ms","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65412,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:40:17.454907Z","caller":"traceutil/trace.go:171","msg":"trace[1714665499] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"622.628312ms","start":"2026-04-02T16:40:16.832211Z","end":"2026-04-02T16:40:17.45484Z","steps":["trace[1714665499] 'process raft request' (duration: 621.980838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:17.455139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:16.832169Z","time spent":"622.840116ms","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98698,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:40:17.454702Z","caller":"traceutil/trace.go:171","msg":"trace[502544152] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"543.068374ms","start":"2026-04-02T16:40:16.911605Z","end":"2026-04-02T16:40:17.454673Z","steps":["trace[502544152] 'process raft request' (duration: 542.91262ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:17.455285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:16.911126Z","time spent":"544.084445ms","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:40:17.677849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.893375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:40:17.678101Z","caller":"traceutil/trace.go:171","msg":"trace[495943740] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"251.007675ms","start":"2026-04-02T16:40:17.427064Z","end":"2026-04-02T16:40:17.678071Z","steps":["trace[495943740] 'process raft request' (duration: 145.841383ms)","trace[495943740] 'compare' (duration: 103.085779ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:17.678641Z","caller":"traceutil/trace.go:171","msg":"trace[721881141] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"194.532338ms","start":"2026-04-02T16:40:17.484098Z","end":"2026-04-02T16:40:17.67863Z","steps":["trace[721881141] 'process raft request' (duration: 194.249513ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:17.678794Z","caller":"traceutil/trace.go:171","msg":"trace[2030915048] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"137.02611ms","start":"2026-04-02T16:40:17.541722Z","end":"2026-04-02T16:40:17.678748Z","steps":["trace[2030915048] 'process raft request' (duration: 136.812286ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:17.888181Z","caller":"traceutil/trace.go:171","msg":"trace[1218458818] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"139.612954ms","start":"2026-04-02T16:40:17.748527Z","end":"2026-04-02T16:40:17.88814Z","steps":["trace[1218458818] 'process raft request' (duration: 87.356445ms)","trace[1218458818] 'compare' (duration: 51.972833ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:18.214914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.001961ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:40:18.215279Z","caller":"traceutil/trace.go:171","msg":"trace[233453591] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"305.735851ms","start":"2026-04-02T16:40:17.909484Z","end":"2026-04-02T16:40:18.215219Z","steps":["trace[233453591] 'process raft request' (duration: 117.309131ms)","trace[233453591] 'compare' (duration: 187.666085ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:18.215402Z","caller":"traceutil/trace.go:171","msg":"trace[1549173413] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"218.401597ms","start":"2026-04-02T16:40:17.996979Z","end":"2026-04-02T16:40:18.215381Z","steps":["trace[1549173413] 'process raft request' (duration: 218.277235ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:18.215465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:17.909453Z","time spent":"305.904305ms","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20851,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:40:18.215409Z","caller":"traceutil/trace.go:171","msg":"trace[480157286] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"267.292696ms","start":"2026-04-02T16:40:17.948094Z","end":"2026-04-02T16:40:18.215387Z","steps":["trace[480157286] 'read index received' (duration: 78.635822ms)","trace[480157286] 'applied index is now lower than readState.Index' (duration: 188.654424ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:18.215625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.521833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-02T16:40:18.215667Z","caller":"traceutil/trace.go:171","msg":"trace[368868533] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:591; }","duration":"267.604164ms","start":"2026-04-02T16:40:17.94805Z","end":"2026-04-02T16:40:18.215654Z","steps":["trace[368868533] 'agreement among raft nodes before linearized reading' (duration: 267.40123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:18.215778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.749799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:40:18.215819Z","caller":"traceutil/trace.go:171","msg":"trace[1859834134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"175.82153ms","start":"2026-04-02T16:40:18.039987Z","end":"2026-04-02T16:40:18.215809Z","steps":["trace[1859834134] 'agreement among raft nodes before linearized reading' (duration: 175.756729ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:18.44092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.446048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-04-02T16:40:18.441046Z","caller":"traceutil/trace.go:171","msg":"trace[1111045192] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:596; }","duration":"117.633453ms","start":"2026-04-02T16:40:18.323385Z","end":"2026-04-02T16:40:18.441019Z","steps":["trace[1111045192] 'range keys from in-memory index tree' (duration: 117.092511ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:18.615006Z","caller":"traceutil/trace.go:171","msg":"trace[41969755] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"111.971456ms","start":"2026-04-02T16:40:18.502998Z","end":"2026-04-02T16:40:18.614969Z","steps":["trace[41969755] 'process raft request' (duration: 79.397895ms)","trace[41969755] 'compare' (duration: 31.907137ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:19.256679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.549214ms","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-04-02T16:40:19.25683Z","caller":"traceutil/trace.go:171","msg":"trace[16615570] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:598; }","duration":"118.711108ms","start":"2026-04-02T16:40:19.138099Z","end":"2026-04-02T16:40:19.25681Z","steps":["trace[16615570] 'range keys from in-memory index tree' (duration: 118.528984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:19.257271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.105782ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:40:19.257402Z","caller":"traceutil/trace.go:171","msg":"trace[1467418932] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"540.554265ms","start":"2026-04-02T16:40:18.716825Z","end":"2026-04-02T16:40:19.25738Z","steps":["trace[1467418932] 'read index received' (duration: 406.053014ms)","trace[1467418932] 'applied index is now lower than readState.Index' (duration: 134.499781ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:19.257797Z","caller":"traceutil/trace.go:171","msg":"trace[1378393065] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"565.198872ms","start":"2026-04-02T16:40:18.692531Z","end":"2026-04-02T16:40:19.25773Z","steps":["trace[1378393065] 'process raft request' (duration: 430.513458ms)","trace[1378393065] 'compare' (duration: 133.902968ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:19.257857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.011914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"warn","ts":"2026-04-02T16:40:19.257878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.073915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:40:19.257925Z","caller":"traceutil/trace.go:171","msg":"trace[906846134] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:599; }","duration":"541.133317ms","start":"2026-04-02T16:40:18.716771Z","end":"2026-04-02T16:40:19.257905Z","steps":["trace[906846134] 'agreement among raft nodes before linearized reading' (duration: 540.861411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:19.257996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:18.716744Z","time spent":"541.236819ms","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":65460,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-04-02T16:40:19.257994Z","caller":"traceutil/trace.go:171","msg":"trace[32256553] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"217.26284ms","start":"2026-04-02T16:40:19.040702Z","end":"2026-04-02T16:40:19.257965Z","steps":["trace[32256553] 'agreement among raft nodes before linearized reading' (duration: 217.056955ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:19.258031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:18.692518Z","time spent":"565.383076ms","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:40:22.329729Z","caller":"traceutil/trace.go:171","msg":"trace[2117102167] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"102.73316ms","start":"2026-04-02T16:40:22.226946Z","end":"2026-04-02T16:40:22.32968Z","steps":["trace[2117102167] 'process raft request' (duration: 102.153179ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:22.888996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.860656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:40:22.889169Z","caller":"traceutil/trace.go:171","msg":"trace[1284291940] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"555.828229ms","start":"2026-04-02T16:40:22.333315Z","end":"2026-04-02T16:40:22.889143Z","steps":["trace[1284291940] 'process raft request' (duration: 128.650518ms)","trace[1284291940] 'compare' (duration: 426.550988ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:22.889403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:22.333293Z","time spent":"556.061644ms","remote":"127.0.0.1:32884","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-04-02T16:40:22.889992Z","caller":"traceutil/trace.go:171","msg":"trace[1847931063] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"554.889301ms","start":"2026-04-02T16:40:22.335091Z","end":"2026-04-02T16:40:22.88998Z","steps":["trace[1847931063] 'process raft request' (duration: 554.730457ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:22.890068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:22.335066Z","time spent":"554.967281ms","remote":"127.0.0.1:32884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:40:22.890437Z","caller":"traceutil/trace.go:171","msg":"trace[1879100242] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"551.613453ms","start":"2026-04-02T16:40:22.338812Z","end":"2026-04-02T16:40:22.890426Z","steps":["trace[1879100242] 'process raft request' (duration: 551.128573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:22.890516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:22.338787Z","time spent":"551.689904ms","remote":"127.0.0.1:32884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:40:22.890549Z","caller":"traceutil/trace.go:171","msg":"trace[345993847] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"503.433543ms","start":"2026-04-02T16:40:22.387081Z","end":"2026-04-02T16:40:22.890514Z","steps":["trace[345993847] 'process raft request' (duration: 503.347131ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:22.890587Z","caller":"traceutil/trace.go:171","msg":"trace[825629244] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"551.633794ms","start":"2026-04-02T16:40:22.338905Z","end":"2026-04-02T16:40:22.890539Z","steps":["trace[825629244] 'process raft request' (duration: 551.345408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:22.890776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:22.387051Z","time spent":"503.619546ms","remote":"127.0.0.1:32954","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:40:22.890833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:22.338899Z","time spent":"551.792976ms","remote":"127.0.0.1:32884","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-04-02T16:40:23.707279Z","caller":"traceutil/trace.go:171","msg":"trace[1458767832] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"390.626884ms","start":"2026-04-02T16:40:23.316615Z","end":"2026-04-02T16:40:23.707242Z","steps":["trace[1458767832] 'process raft request' (duration: 389.89549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:23.707481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:23.316562Z","time spent":"390.806078ms","remote":"127.0.0.1:60932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036193,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:40:23.940373Z","caller":"traceutil/trace.go:171","msg":"trace[1611319252] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"176.146294ms","start":"2026-04-02T16:40:23.764195Z","end":"2026-04-02T16:40:23.940342Z","steps":["trace[1611319252] 'process raft request' (duration: 175.98841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:24.39871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.251222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:40:24.398853Z","caller":"traceutil/trace.go:171","msg":"trace[1774086690] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"446.524716ms","start":"2026-04-02T16:40:23.952307Z","end":"2026-04-02T16:40:24.398832Z","steps":["trace[1774086690] 'read index received' (duration: 73.98795ms)","trace[1774086690] 'applied index is now lower than readState.Index' (duration: 372.535336ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:24.398959Z","caller":"traceutil/trace.go:171","msg":"trace[559068134] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"458.711283ms","start":"2026-04-02T16:40:23.940236Z","end":"2026-04-02T16:40:24.398947Z","steps":["trace[559068134] 'process raft request' (duration: 86.104904ms)","trace[559068134] 'compare' (duration: 372.096119ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:24.399022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:23.940201Z","time spent":"458.786565ms","remote":"127.0.0.1:32884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:40:24.399079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.966702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:40:24.399195Z","caller":"traceutil/trace.go:171","msg":"trace[642996916] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"358.132095ms","start":"2026-04-02T16:40:24.041033Z","end":"2026-04-02T16:40:24.399165Z","steps":["trace[642996916] 'agreement among raft nodes before linearized reading' (duration: 357.963662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:24.399243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:24.041011Z","time spent":"358.222637ms","remote":"127.0.0.1:60834","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-04-02T16:40:24.399501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.19959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:40:24.399545Z","caller":"traceutil/trace.go:171","msg":"trace[1366814183] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:621; }","duration":"447.276631ms","start":"2026-04-02T16:40:23.952256Z","end":"2026-04-02T16:40:24.399533Z","steps":["trace[1366814183] 'agreement among raft nodes before linearized reading' (duration: 447.210679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:24.399571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:23.952236Z","time spent":"447.328272ms","remote":"127.0.0.1:32978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"warn","ts":"2026-04-02T16:40:24.399903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.755579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qvbss\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-02T16:40:24.400015Z","caller":"traceutil/trace.go:171","msg":"trace[1033588885] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qvbss; range_end:; response_count:1; response_revision:621; }","duration":"319.949443ms","start":"2026-04-02T16:40:24.080039Z","end":"2026-04-02T16:40:24.399988Z","steps":["trace[1033588885] 'agreement among raft nodes before linearized reading' (duration: 319.499354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:24.400083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:24.080002Z","time spent":"320.065895ms","remote":"127.0.0.1:32816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-qvbss\" "} {"level":"info","ts":"2026-04-02T16:40:24.646708Z","caller":"traceutil/trace.go:171","msg":"trace[1347532662] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"232.285419ms","start":"2026-04-02T16:40:24.414391Z","end":"2026-04-02T16:40:24.646677Z","steps":["trace[1347532662] 'process raft request' (duration: 232.076594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:25.396591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003268836103530,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:40:25.897777Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003268836103530,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:40:26.398618Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003268836103530,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:40:26.898819Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003268836103530,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:40:27.214055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:25.583557Z","time spent":"1.630489241s","remote":"127.0.0.1:37144","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-02T16:40:27.214434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.433068604s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.69\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T16:40:27.214546Z","caller":"traceutil/trace.go:171","msg":"trace[831485530] range","detail":"{range_begin:/registry/masterleases/199.204.45.69; range_end:; response_count:1; response_revision:622; }","duration":"2.433337359s","start":"2026-04-02T16:40:24.781178Z","end":"2026-04-02T16:40:27.214515Z","steps":["trace[831485530] 'range keys from in-memory index tree' (duration: 2.43280291s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:27.214611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:24.781153Z","time spent":"2.433443403s","remote":"127.0.0.1:60876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.69\" "} {"level":"warn","ts":"2026-04-02T16:40:27.224926Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.387096092s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T16:40:27.233152Z","caller":"traceutil/trace.go:171","msg":"trace[700737654] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"2.397816339s","start":"2026-04-02T16:40:24.835311Z","end":"2026-04-02T16:40:27.233127Z","steps":["trace[700737654] 'process raft request' (duration: 2.390931911s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:27.233316Z","caller":"traceutil/trace.go:171","msg":"trace[1832844991] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"2.336764513s","start":"2026-04-02T16:40:24.896201Z","end":"2026-04-02T16:40:27.232965Z","steps":["trace[1832844991] 'read index received' (duration: 2.328888575s)","trace[1832844991] 'applied index is now lower than readState.Index' (duration: 7.874158ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:27.233419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:24.835271Z","time spent":"2.398055743s","remote":"127.0.0.1:60932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:40:27.233619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.33707651s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-02T16:40:27.233606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19322466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:40:27.233701Z","caller":"traceutil/trace.go:171","msg":"trace[1814951712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:623; }","duration":"2.337199882s","start":"2026-04-02T16:40:24.896462Z","end":"2026-04-02T16:40:27.233662Z","steps":["trace[1814951712] 'agreement among raft nodes before linearized reading' (duration: 2.33703035s)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:27.233723Z","caller":"traceutil/trace.go:171","msg":"trace[1079151656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"1.193330013s","start":"2026-04-02T16:40:26.040347Z","end":"2026-04-02T16:40:27.233677Z","steps":["trace[1079151656] 'agreement among raft nodes before linearized reading' (duration: 1.19315957s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:27.233751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:24.896435Z","time spent":"2.337303984s","remote":"127.0.0.1:32884","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-04-02T16:40:27.23377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:26.040287Z","time spent":"1.193472225s","remote":"127.0.0.1:60834","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-04-02T16:40:27.23372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.335280643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-02T16:40:27.233942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.337394726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-02T16:40:27.233669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.337507108s","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-04-02T16:40:27.233995Z","caller":"traceutil/trace.go:171","msg":"trace[1840463908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:623; }","duration":"2.337438877s","start":"2026-04-02T16:40:24.896537Z","end":"2026-04-02T16:40:27.233976Z","steps":["trace[1840463908] 'agreement among raft nodes before linearized reading' (duration: 2.337272854s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:27.234028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:24.896435Z","time spent":"2.33758647s","remote":"127.0.0.1:32884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T16:40:27.234044Z","caller":"traceutil/trace.go:171","msg":"trace[2109072100] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"2.337895296s","start":"2026-04-02T16:40:24.896124Z","end":"2026-04-02T16:40:27.234019Z","steps":["trace[2109072100] 'agreement among raft nodes before linearized reading' (duration: 2.337357235s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:27.234113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:24.895937Z","time spent":"2.338152011s","remote":"127.0.0.1:32884","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-04-02T16:40:27.234381Z","caller":"traceutil/trace.go:171","msg":"trace[459627758] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:623; }","duration":"2.335530109s","start":"2026-04-02T16:40:24.898327Z","end":"2026-04-02T16:40:27.233857Z","steps":["trace[459627758] 'agreement among raft nodes before linearized reading' (duration: 2.335211692s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:27.234442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:24.898319Z","time spent":"2.33611321s","remote":"127.0.0.1:32884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T16:40:27.984174Z","caller":"traceutil/trace.go:171","msg":"trace[517896172] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"207.228427ms","start":"2026-04-02T16:40:27.77692Z","end":"2026-04-02T16:40:27.984148Z","steps":["trace[517896172] 'process raft request' (duration: 206.636665ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:28.656836Z","caller":"traceutil/trace.go:171","msg":"trace[1480592650] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"171.011212ms","start":"2026-04-02T16:40:28.485795Z","end":"2026-04-02T16:40:28.656806Z","steps":["trace[1480592650] 'process raft request' (duration: 170.331709ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:29.798933Z","caller":"traceutil/trace.go:171","msg":"trace[494314491] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"487.66434ms","start":"2026-04-02T16:40:29.311232Z","end":"2026-04-02T16:40:29.798897Z","steps":["trace[494314491] 'process raft request' (duration: 487.449776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:29.799156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:29.311202Z","time spent":"487.833024ms","remote":"127.0.0.1:32884","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-04-02T16:40:29.828946Z","caller":"traceutil/trace.go:171","msg":"trace[232614668] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"498.550068ms","start":"2026-04-02T16:40:29.330351Z","end":"2026-04-02T16:40:29.828901Z","steps":["trace[232614668] 'process raft request' (duration: 498.266202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:29.829166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:29.33033Z","time spent":"498.714811ms","remote":"127.0.0.1:32884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:40:29.829281Z","caller":"traceutil/trace.go:171","msg":"trace[2124539409] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"498.374514ms","start":"2026-04-02T16:40:29.330865Z","end":"2026-04-02T16:40:29.82924Z","steps":["trace[2124539409] 'process raft request' (duration: 497.930125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:29.829524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:29.330836Z","time spent":"498.597568ms","remote":"127.0.0.1:32858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T16:40:29.829337Z","caller":"traceutil/trace.go:171","msg":"trace[2068332706] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"497.980356ms","start":"2026-04-02T16:40:29.331185Z","end":"2026-04-02T16:40:29.829165Z","steps":["trace[2068332706] 'process raft request' (duration: 497.890734ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:29.829861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:29.331175Z","time spent":"498.519407ms","remote":"127.0.0.1:32884","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-04-02T16:40:33.162191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.337577ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T16:40:33.162338Z","caller":"traceutil/trace.go:171","msg":"trace[696339976] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:732; }","duration":"680.471153ms","start":"2026-04-02T16:40:32.48184Z","end":"2026-04-02T16:40:33.162311Z","steps":["trace[696339976] 'read index received' (duration: 32.87458ms)","trace[696339976] 'applied index is now lower than readState.Index' (duration: 647.594723ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:33.162405Z","caller":"traceutil/trace.go:171","msg":"trace[636872736] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"704.708983ms","start":"2026-04-02T16:40:32.457654Z","end":"2026-04-02T16:40:33.162363Z","steps":["trace[636872736] 'process raft request' (duration: 57.09642ms)","trace[636872736] 'compare' (duration: 647.192895ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:33.162567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.883467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T16:40:33.162622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.295101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-04-02T16:40:33.162655Z","caller":"traceutil/trace.go:171","msg":"trace[1633268303] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:708; }","duration":"122.016439ms","start":"2026-04-02T16:40:33.040615Z","end":"2026-04-02T16:40:33.162631Z","steps":["trace[1633268303] 'agreement among raft nodes before linearized reading' (duration: 121.877267ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:33.162673Z","caller":"traceutil/trace.go:171","msg":"trace[180353346] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:708; }","duration":"479.395052ms","start":"2026-04-02T16:40:32.683263Z","end":"2026-04-02T16:40:33.162658Z","steps":["trace[180353346] 'agreement among raft nodes before linearized reading' (duration: 479.188388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:33.162722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:32.683234Z","time spent":"479.470764ms","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":9344,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-04-02T16:40:33.16271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:32.457616Z","time spent":"704.863656ms","remote":"127.0.0.1:60922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:40:33.163212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.340481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"warn","ts":"2026-04-02T16:40:33.163247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.159278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-04-02T16:40:33.163303Z","caller":"traceutil/trace.go:171","msg":"trace[701201512] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:708; }","duration":"681.495114ms","start":"2026-04-02T16:40:32.481774Z","end":"2026-04-02T16:40:33.163269Z","steps":["trace[701201512] 'agreement among raft nodes before linearized reading' (duration: 680.621127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:33.163349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:32.481736Z","time spent":"681.602985ms","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5610,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-04-02T16:40:33.163366Z","caller":"traceutil/trace.go:171","msg":"trace[723281172] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:708; }","duration":"281.483344ms","start":"2026-04-02T16:40:32.88184Z","end":"2026-04-02T16:40:33.163323Z","steps":["trace[723281172] 'agreement among raft nodes before linearized reading' (duration: 280.719558ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:33.426061Z","caller":"traceutil/trace.go:171","msg":"trace[67175784] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:733; }","duration":"144.725398ms","start":"2026-04-02T16:40:33.281307Z","end":"2026-04-02T16:40:33.426032Z","steps":["trace[67175784] 'read index received' (duration: 144.479123ms)","trace[67175784] 'applied index is now lower than readState.Index' (duration: 244.825µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:33.426317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.992503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-04-02T16:40:33.426397Z","caller":"traceutil/trace.go:171","msg":"trace[1003435860] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:709; }","duration":"145.161256ms","start":"2026-04-02T16:40:33.281223Z","end":"2026-04-02T16:40:33.426384Z","steps":["trace[1003435860] 'agreement among raft nodes before linearized reading' (duration: 144.913812ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:33.426341Z","caller":"traceutil/trace.go:171","msg":"trace[901823657] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"248.1101ms","start":"2026-04-02T16:40:33.178138Z","end":"2026-04-02T16:40:33.426248Z","steps":["trace[901823657] 'process raft request' (duration: 247.686982ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:34.021159Z","caller":"traceutil/trace.go:171","msg":"trace[704693954] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"136.464933ms","start":"2026-04-02T16:40:33.88466Z","end":"2026-04-02T16:40:34.021125Z","steps":["trace[704693954] 'process raft request' (duration: 61.917751ms)","trace[704693954] 'compare' (duration: 74.383869ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:34.062864Z","caller":"traceutil/trace.go:171","msg":"trace[2009579138] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"149.866266ms","start":"2026-04-02T16:40:33.91296Z","end":"2026-04-02T16:40:34.062826Z","steps":["trace[2009579138] 'process raft request' (duration: 149.55797ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:34.481652Z","caller":"traceutil/trace.go:171","msg":"trace[1003913354] linearizableReadLoop","detail":"{readStateIndex:740; appliedIndex:739; }","duration":"317.097277ms","start":"2026-04-02T16:40:34.164523Z","end":"2026-04-02T16:40:34.48162Z","steps":["trace[1003913354] 'read index received' (duration: 316.613787ms)","trace[1003913354] 'applied index is now lower than readState.Index' (duration: 481.9µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:34.481854Z","caller":"traceutil/trace.go:171","msg":"trace[241709749] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"340.225752ms","start":"2026-04-02T16:40:34.141561Z","end":"2026-04-02T16:40:34.481787Z","steps":["trace[241709749] 'process raft request' (duration: 339.694191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:34.481972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.431483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-qvbss\" ","response":"range_response_count:1 size:18517"} {"level":"info","ts":"2026-04-02T16:40:34.482075Z","caller":"traceutil/trace.go:171","msg":"trace[2051358087] range","detail":"{range_begin:/registry/pods/kube-system/cilium-qvbss; range_end:; response_count:1; response_revision:715; }","duration":"317.541114ms","start":"2026-04-02T16:40:34.164471Z","end":"2026-04-02T16:40:34.482012Z","steps":["trace[2051358087] 'agreement among raft nodes before linearized reading' (duration: 317.288949ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:34.482113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:34.141517Z","time spent":"340.451416ms","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:40:34.482127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:34.164449Z","time spent":"317.666276ms","remote":"127.0.0.1:32816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18541,"request content":"key:\"/registry/pods/kube-system/cilium-qvbss\" "} {"level":"info","ts":"2026-04-02T16:40:34.651867Z","caller":"traceutil/trace.go:171","msg":"trace[2130181954] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"486.128405ms","start":"2026-04-02T16:40:34.16569Z","end":"2026-04-02T16:40:34.651819Z","steps":["trace[2130181954] 'process raft request' (duration: 485.728257ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:34.652046Z","caller":"traceutil/trace.go:171","msg":"trace[1186012568] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"340.204931ms","start":"2026-04-02T16:40:34.311787Z","end":"2026-04-02T16:40:34.651992Z","steps":["trace[1186012568] 'process raft request' (duration: 339.977177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:34.652263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:34.311755Z","time spent":"340.386976ms","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:40:34.652305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:34.165668Z","time spent":"486.33893ms","remote":"127.0.0.1:60922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":761,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:40:34.652571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.890335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"warn","ts":"2026-04-02T16:40:34.65271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.189844ms","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-04-02T16:40:34.652764Z","caller":"traceutil/trace.go:171","msg":"trace[1790203543] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:717; }","duration":"266.303706ms","start":"2026-04-02T16:40:34.386447Z","end":"2026-04-02T16:40:34.652751Z","steps":["trace[1790203543] 'agreement among raft nodes before linearized reading' (duration: 266.05393ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:34.652725Z","caller":"traceutil/trace.go:171","msg":"trace[1594118686] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:717; }","duration":"171.147561ms","start":"2026-04-02T16:40:34.481552Z","end":"2026-04-02T16:40:34.652699Z","steps":["trace[1594118686] 'agreement among raft nodes before linearized reading' (duration: 170.55289ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:34.651952Z","caller":"traceutil/trace.go:171","msg":"trace[2146440576] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:740; }","duration":"170.192602ms","start":"2026-04-02T16:40:34.481734Z","end":"2026-04-02T16:40:34.651926Z","steps":["trace[2146440576] 'read index received' (duration: 169.750044ms)","trace[2146440576] 'applied index is now lower than readState.Index' (duration: 441.378µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:34.720975Z","caller":"traceutil/trace.go:171","msg":"trace[2094137588] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"223.066995ms","start":"2026-04-02T16:40:34.497847Z","end":"2026-04-02T16:40:34.720914Z","steps":["trace[2094137588] 'process raft request' (duration: 222.718627ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:34.721109Z","caller":"traceutil/trace.go:171","msg":"trace[386281093] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"128.137184ms","start":"2026-04-02T16:40:34.592941Z","end":"2026-04-02T16:40:34.721078Z","steps":["trace[386281093] 'process raft request' (duration: 127.860249ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:35.028038Z","caller":"traceutil/trace.go:171","msg":"trace[932500317] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"246.212179ms","start":"2026-04-02T16:40:34.781781Z","end":"2026-04-02T16:40:35.027993Z","steps":["trace[932500317] 'read index received' (duration: 169.340076ms)","trace[932500317] 'applied index is now lower than readState.Index' (duration: 76.869673ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:35.028086Z","caller":"traceutil/trace.go:171","msg":"trace[507172778] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"288.393309ms","start":"2026-04-02T16:40:34.739642Z","end":"2026-04-02T16:40:35.028035Z","steps":["trace[507172778] 'process raft request' (duration: 211.70795ms)","trace[507172778] 'compare' (duration: 76.431214ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:35.02861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.781959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.69\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T16:40:35.028755Z","caller":"traceutil/trace.go:171","msg":"trace[159217207] range","detail":"{range_begin:/registry/masterleases/199.204.45.69; range_end:; response_count:1; response_revision:722; }","duration":"247.013624ms","start":"2026-04-02T16:40:34.781716Z","end":"2026-04-02T16:40:35.02873Z","steps":["trace[159217207] 'agreement among raft nodes before linearized reading' (duration: 246.543445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:35.664148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.269593ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-02T16:40:35.664644Z","caller":"traceutil/trace.go:171","msg":"trace[1092128090] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"561.103375ms","start":"2026-04-02T16:40:35.103518Z","end":"2026-04-02T16:40:35.664621Z","steps":["trace[1092128090] 'process raft request' (duration: 560.81468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:35.664773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:35.103481Z","time spent":"561.216357ms","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:40:35.664401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:35.030467Z","time spent":"633.922796ms","remote":"127.0.0.1:60876","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-02T16:40:35.665062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.094543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:40:35.665166Z","caller":"traceutil/trace.go:171","msg":"trace[1933391397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:723; }","duration":"625.263436ms","start":"2026-04-02T16:40:35.039875Z","end":"2026-04-02T16:40:35.665139Z","steps":["trace[1933391397] 'agreement among raft nodes before linearized reading' (duration: 625.054052ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:35.665219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:35.039856Z","time spent":"625.351388ms","remote":"127.0.0.1:60830","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-04-02T16:40:35.664484Z","caller":"traceutil/trace.go:171","msg":"trace[1590609531] linearizableReadLoop","detail":"{readStateIndex:748; appliedIndex:747; }","duration":"624.526902ms","start":"2026-04-02T16:40:35.039927Z","end":"2026-04-02T16:40:35.664454Z","steps":["trace[1590609531] 'read index received' (duration: 103.099906ms)","trace[1590609531] 'applied index is now lower than readState.Index' (duration: 521.423866ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:35.911018Z","caller":"traceutil/trace.go:171","msg":"trace[1524207726] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"244.28354ms","start":"2026-04-02T16:40:35.666708Z","end":"2026-04-02T16:40:35.910992Z","steps":["trace[1524207726] 'process raft request' (duration: 244.191458ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:35.911299Z","caller":"traceutil/trace.go:171","msg":"trace[2056663005] transaction","detail":"{read_only:false; response_revision:724; number_of_response:1; }","duration":"754.179389ms","start":"2026-04-02T16:40:35.15711Z","end":"2026-04-02T16:40:35.911289Z","steps":["trace[2056663005] 'process raft request' (duration: 720.388224ms)","trace[2056663005] 'compare' (duration: 33.143472ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:35.911424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:35.157089Z","time spent":"754.241349ms","remote":"127.0.0.1:60922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":696,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T16:40:35.91187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.724484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T16:40:35.911917Z","caller":"traceutil/trace.go:171","msg":"trace[534407611] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:725; }","duration":"457.779745ms","start":"2026-04-02T16:40:35.454124Z","end":"2026-04-02T16:40:35.911903Z","steps":["trace[534407611] 'agreement among raft nodes before linearized reading' (duration: 457.507849ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:35.911937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:35.454026Z","time spent":"457.907127ms","remote":"127.0.0.1:32884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T16:40:35.91157Z","caller":"traceutil/trace.go:171","msg":"trace[1141686124] linearizableReadLoop","detail":"{readStateIndex:750; appliedIndex:749; }","duration":"245.867221ms","start":"2026-04-02T16:40:35.665674Z","end":"2026-04-02T16:40:35.911541Z","steps":["trace[1141686124] 'read index received' (duration: 211.930734ms)","trace[1141686124] 'applied index is now lower than readState.Index' (duration: 33.934417ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:35.912416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.101235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-04-02T16:40:35.91247Z","caller":"traceutil/trace.go:171","msg":"trace[1320761857] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:725; }","duration":"732.219227ms","start":"2026-04-02T16:40:35.180234Z","end":"2026-04-02T16:40:35.912454Z","steps":["trace[1320761857] 'agreement among raft nodes before linearized reading' (duration: 731.919051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:35.912512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:35.180208Z","time spent":"732.292649ms","remote":"127.0.0.1:60894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188519,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-04-02T16:40:36.862763Z","caller":"traceutil/trace.go:171","msg":"trace[228408690] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"112.878924ms","start":"2026-04-02T16:40:36.749852Z","end":"2026-04-02T16:40:36.862731Z","steps":["trace[228408690] 'process raft request' (duration: 112.68839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:46.545476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"503.761932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:40:46.545564Z","caller":"traceutil/trace.go:171","msg":"trace[1960094317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"503.914515ms","start":"2026-04-02T16:40:46.041629Z","end":"2026-04-02T16:40:46.545543Z","steps":["trace[1960094317] 'range keys from in-memory index tree' (duration: 503.612939ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:46.545607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:46.041602Z","time spent":"503.995707ms","remote":"127.0.0.1:60834","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-04-02T16:40:46.546194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.610353ms","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-04-02T16:40:46.546232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.390168ms","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-04-02T16:40:46.546324Z","caller":"traceutil/trace.go:171","msg":"trace[1303478362] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"440.52823ms","start":"2026-04-02T16:40:46.105776Z","end":"2026-04-02T16:40:46.546304Z","steps":["trace[1303478362] 'range keys from in-memory index tree' (duration: 440.198015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:46.546353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.635925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-02T16:40:46.546213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.995456ms","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-04-02T16:40:46.546453Z","caller":"traceutil/trace.go:171","msg":"trace[1420890606] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"449.800048ms","start":"2026-04-02T16:40:46.09662Z","end":"2026-04-02T16:40:46.54642Z","steps":["trace[1420890606] 'range keys from in-memory index tree' (duration: 449.382721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:46.546513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:46.096588Z","time spent":"449.915381ms","remote":"127.0.0.1:32884","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-04-02T16:40:46.546493Z","caller":"traceutil/trace.go:171","msg":"trace[1583074900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:801; }","duration":"421.265021ms","start":"2026-04-02T16:40:46.12518Z","end":"2026-04-02T16:40:46.546445Z","steps":["trace[1583074900] 'range keys from in-memory index tree' (duration: 420.831343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:46.546672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:46.125168Z","time spent":"421.436145ms","remote":"127.0.0.1:32884","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-04-02T16:40:46.546383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:46.105764Z","time spent":"440.608153ms","remote":"127.0.0.1:32884","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-04-02T16:40:46.546289Z","caller":"traceutil/trace.go:171","msg":"trace[1632394003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:801; }","duration":"452.751574ms","start":"2026-04-02T16:40:46.093521Z","end":"2026-04-02T16:40:46.546273Z","steps":["trace[1632394003] 'range keys from in-memory index tree' (duration: 452.429909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:46.547308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:46.093495Z","time spent":"453.796845ms","remote":"127.0.0.1:32884","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-04-02T16:40:47.048308Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003268836103868,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T16:40:47.415119Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.151336654s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T16:40:47.415827Z","caller":"traceutil/trace.go:171","msg":"trace[1307232716] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"1.1521855s","start":"2026-04-02T16:40:46.26361Z","end":"2026-04-02T16:40:47.415795Z","steps":["trace[1307232716] 'process raft request' (duration: 1.151850784s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:47.416268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:46.263588Z","time spent":"1.152322772s","remote":"127.0.0.1:32858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:40:47.41683Z","caller":"traceutil/trace.go:171","msg":"trace[380086209] linearizableReadLoop","detail":"{readStateIndex:836; appliedIndex:831; }","duration":"869.183387ms","start":"2026-04-02T16:40:46.54762Z","end":"2026-04-02T16:40:47.416804Z","steps":["trace[380086209] 'read index received' (duration: 867.837792ms)","trace[380086209] 'applied index is now lower than readState.Index' (duration: 1.344595ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:40:47.416901Z","caller":"traceutil/trace.go:171","msg":"trace[175129118] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"864.886245ms","start":"2026-04-02T16:40:46.552Z","end":"2026-04-02T16:40:47.416886Z","steps":["trace[175129118] 'process raft request' (duration: 864.681511ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:47.416943Z","caller":"traceutil/trace.go:171","msg":"trace[1410060676] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"864.880975ms","start":"2026-04-02T16:40:46.552048Z","end":"2026-04-02T16:40:47.416929Z","steps":["trace[1410060676] 'process raft request' (duration: 864.681862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:47.416991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:46.551958Z","time spent":"864.989237ms","remote":"127.0.0.1:32884","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-04-02T16:40:47.41707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:46.551998Z","time spent":"864.976217ms","remote":"127.0.0.1:32884","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-04-02T16:40:47.41726Z","caller":"traceutil/trace.go:171","msg":"trace[939493336] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"865.298902ms","start":"2026-04-02T16:40:46.551949Z","end":"2026-04-02T16:40:47.417248Z","steps":["trace[939493336] 'process raft request' (duration: 864.581719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:47.417317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:46.551926Z","time spent":"865.361495ms","remote":"127.0.0.1:32884","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-04-02T16:40:47.417338Z","caller":"traceutil/trace.go:171","msg":"trace[880905043] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"865.058757ms","start":"2026-04-02T16:40:46.552267Z","end":"2026-04-02T16:40:47.417326Z","steps":["trace[880905043] 'process raft request' (duration: 864.498847ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:47.417398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:46.552256Z","time spent":"865.109249ms","remote":"127.0.0.1:32884","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-04-02T16:40:47.417527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.86184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:40:47.417614Z","caller":"traceutil/trace.go:171","msg":"trace[269569020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"869.999944ms","start":"2026-04-02T16:40:46.547591Z","end":"2026-04-02T16:40:47.417591Z","steps":["trace[269569020] 'agreement among raft nodes before linearized reading' (duration: 869.8086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:47.417666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:46.547571Z","time spent":"870.086105ms","remote":"127.0.0.1:60830","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-04-02T16:40:47.417844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.150141ms","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-04-02T16:40:47.417925Z","caller":"traceutil/trace.go:171","msg":"trace[512287860] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:806; }","duration":"482.312134ms","start":"2026-04-02T16:40:46.935595Z","end":"2026-04-02T16:40:47.417907Z","steps":["trace[512287860] 'agreement among raft nodes before linearized reading' (duration: 482.07921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:40:47.417968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:40:46.935562Z","time spent":"482.397886ms","remote":"127.0.0.1:32884","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-04-02T16:40:49.565517Z","caller":"traceutil/trace.go:171","msg":"trace[1016273743] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"137.550098ms","start":"2026-04-02T16:40:49.427936Z","end":"2026-04-02T16:40:49.565486Z","steps":["trace[1016273743] 'process raft request' (duration: 137.422216ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:49.565587Z","caller":"traceutil/trace.go:171","msg":"trace[2053661452] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"137.987627ms","start":"2026-04-02T16:40:49.427526Z","end":"2026-04-02T16:40:49.565514Z","steps":["trace[2053661452] 'process raft request' (duration: 137.63921ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:49.565608Z","caller":"traceutil/trace.go:171","msg":"trace[1645070905] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"137.488288ms","start":"2026-04-02T16:40:49.428085Z","end":"2026-04-02T16:40:49.565573Z","steps":["trace[1645070905] 'process raft request' (duration: 137.318885ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:40:49.565833Z","caller":"traceutil/trace.go:171","msg":"trace[211021027] linearizableReadLoop","detail":"{readStateIndex:845; appliedIndex:841; }","duration":"132.431592ms","start":"2026-04-02T16:40:49.433013Z","end":"2026-04-02T16:40:49.565445Z","steps":["trace[211021027] 'read index received' (duration: 81.101524ms)","trace[211021027] 'applied index is now lower than readState.Index' (duration: 51.329258ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:40:49.565976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.935911ms","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-04-02T16:40:49.566084Z","caller":"traceutil/trace.go:171","msg":"trace[322949288] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:815; }","duration":"133.065793ms","start":"2026-04-02T16:40:49.432992Z","end":"2026-04-02T16:40:49.566058Z","steps":["trace[322949288] 'agreement among raft nodes before linearized reading' (duration: 132.795928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:36.680922Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003268836105106,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-02T16:42:36.87802Z","caller":"traceutil/trace.go:171","msg":"trace[1956307003] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1374; }","duration":"697.593667ms","start":"2026-04-02T16:42:36.180398Z","end":"2026-04-02T16:42:36.877991Z","steps":["trace[1956307003] 'read index received' (duration: 697.416121ms)","trace[1956307003] 'applied index is now lower than readState.Index' (duration: 175.976µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:36.878164Z","caller":"traceutil/trace.go:171","msg":"trace[1697304163] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"798.923863ms","start":"2026-04-02T16:42:36.079192Z","end":"2026-04-02T16:42:36.878116Z","steps":["trace[1697304163] 'process raft request' (duration: 798.603303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:36.878346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"697.925488ms","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-04-02T16:42:36.878377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:36.079167Z","time spent":"799.09102ms","remote":"127.0.0.1:32884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:42:36.878414Z","caller":"traceutil/trace.go:171","msg":"trace[653629722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1321; }","duration":"698.056812ms","start":"2026-04-02T16:42:36.180342Z","end":"2026-04-02T16:42:36.878399Z","steps":["trace[653629722] 'agreement among raft nodes before linearized reading' (duration: 697.830275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:36.87846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:36.180321Z","time spent":"698.129734ms","remote":"127.0.0.1:32884","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-04-02T16:42:36.880042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.344448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2475"} {"level":"info","ts":"2026-04-02T16:42:36.880097Z","caller":"traceutil/trace.go:171","msg":"trace[868080620] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1322; }","duration":"393.45356ms","start":"2026-04-02T16:42:36.486628Z","end":"2026-04-02T16:42:36.880081Z","steps":["trace[868080620] 'agreement among raft nodes before linearized reading' (duration: 393.283975ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:36.880086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.851728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-02T16:42:36.880132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:36.486603Z","time spent":"393.520852ms","remote":"127.0.0.1:32816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2499,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-04-02T16:42:36.8802Z","caller":"traceutil/trace.go:171","msg":"trace[490260058] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1322; }","duration":"699.019094ms","start":"2026-04-02T16:42:36.181161Z","end":"2026-04-02T16:42:36.88018Z","steps":["trace[490260058] 'agreement among raft nodes before linearized reading' (duration: 698.700123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:36.88021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.400652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-02T16:42:36.880294Z","caller":"traceutil/trace.go:171","msg":"trace[1477976453] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1322; }","duration":"652.501726ms","start":"2026-04-02T16:42:36.227765Z","end":"2026-04-02T16:42:36.880267Z","steps":["trace[1477976453] 'agreement among raft nodes before linearized reading' (duration: 652.192345ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:36.88025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:36.181145Z","time spent":"699.095207ms","remote":"127.0.0.1:32884","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-04-02T16:42:36.880344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:36.227754Z","time spent":"652.581208ms","remote":"127.0.0.1:32884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-02T16:42:36.880347Z","caller":"traceutil/trace.go:171","msg":"trace[2117723010] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"173.952596ms","start":"2026-04-02T16:42:36.706352Z","end":"2026-04-02T16:42:36.880304Z","steps":["trace[2117723010] 'process raft request' (duration: 173.108789ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:36.880508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.715243ms","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-04-02T16:42:36.880543Z","caller":"traceutil/trace.go:171","msg":"trace[1496679691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1322; }","duration":"694.802505ms","start":"2026-04-02T16:42:36.185729Z","end":"2026-04-02T16:42:36.880532Z","steps":["trace[1496679691] 'agreement among raft nodes before linearized reading' (duration: 694.708763ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:36.880583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:36.185699Z","time spent":"694.877018ms","remote":"127.0.0.1:32884","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-04-02T16:42:36.880671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.434879ms","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-04-02T16:42:36.8808Z","caller":"traceutil/trace.go:171","msg":"trace[227361785] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1322; }","duration":"647.573474ms","start":"2026-04-02T16:42:36.23318Z","end":"2026-04-02T16:42:36.880754Z","steps":["trace[227361785] 'agreement among raft nodes before linearized reading' (duration: 647.001256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:36.880887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:36.233167Z","time spent":"647.668918ms","remote":"127.0.0.1:32884","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-04-02T16:42:39.055829Z","caller":"traceutil/trace.go:171","msg":"trace[733236710] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1381; }","duration":"159.9593ms","start":"2026-04-02T16:42:38.895843Z","end":"2026-04-02T16:42:39.055803Z","steps":["trace[733236710] 'read index received' (duration: 159.732173ms)","trace[733236710] 'applied index is now lower than readState.Index' (duration: 225.157µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:39.055963Z","caller":"traceutil/trace.go:171","msg":"trace[256781538] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"170.48584ms","start":"2026-04-02T16:42:38.885468Z","end":"2026-04-02T16:42:39.055954Z","steps":["trace[256781538] 'process raft request' (duration: 170.123029ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:39.056285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.416864ms","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-04-02T16:42:39.056327Z","caller":"traceutil/trace.go:171","msg":"trace[1827901617] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1328; }","duration":"160.479237ms","start":"2026-04-02T16:42:38.895838Z","end":"2026-04-02T16:42:39.056317Z","steps":["trace[1827901617] 'agreement among raft nodes before linearized reading' (duration: 160.245408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:39.056348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.483397ms","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-04-02T16:42:39.056418Z","caller":"traceutil/trace.go:171","msg":"trace[116014238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1328; }","duration":"160.59755ms","start":"2026-04-02T16:42:38.895803Z","end":"2026-04-02T16:42:39.056401Z","steps":["trace[116014238] 'agreement among raft nodes before linearized reading' (duration: 160.376252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:39.05664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.011478ms","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-04-02T16:42:39.056673Z","caller":"traceutil/trace.go:171","msg":"trace[1206229119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1328; }","duration":"159.093351ms","start":"2026-04-02T16:42:38.89757Z","end":"2026-04-02T16:42:39.056663Z","steps":["trace[1206229119] 'agreement among raft nodes before linearized reading' (duration: 158.979067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:39.056771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.894239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-02T16:42:39.056867Z","caller":"traceutil/trace.go:171","msg":"trace[1243099979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1328; }","duration":"161.039644ms","start":"2026-04-02T16:42:38.895804Z","end":"2026-04-02T16:42:39.056844Z","steps":["trace[1243099979] 'agreement among raft nodes before linearized reading' (duration: 160.821657ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:39.056369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.973667ms","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-04-02T16:42:39.057104Z","caller":"traceutil/trace.go:171","msg":"trace[2008635919] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1328; }","duration":"159.746583ms","start":"2026-04-02T16:42:38.897338Z","end":"2026-04-02T16:42:39.057084Z","steps":["trace[2008635919] 'agreement among raft nodes before linearized reading' (duration: 158.914576ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:39.183929Z","caller":"traceutil/trace.go:171","msg":"trace[1169838794] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"123.069816ms","start":"2026-04-02T16:42:39.060832Z","end":"2026-04-02T16:42:39.183902Z","steps":["trace[1169838794] 'process raft request' (duration: 122.768586ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:39.184279Z","caller":"traceutil/trace.go:171","msg":"trace[1717345900] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"123.19517ms","start":"2026-04-02T16:42:39.061042Z","end":"2026-04-02T16:42:39.184238Z","steps":["trace[1717345900] 'process raft request' (duration: 122.809148ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:39.184323Z","caller":"traceutil/trace.go:171","msg":"trace[1678054371] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"122.845699ms","start":"2026-04-02T16:42:39.061456Z","end":"2026-04-02T16:42:39.184301Z","steps":["trace[1678054371] 'process raft request' (duration: 122.780786ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:39.184403Z","caller":"traceutil/trace.go:171","msg":"trace[924291263] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"123.044556ms","start":"2026-04-02T16:42:39.061341Z","end":"2026-04-02T16:42:39.184385Z","steps":["trace[924291263] 'process raft request' (duration: 122.794417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:42.1295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.527474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2568"} {"level":"info","ts":"2026-04-02T16:42:42.129585Z","caller":"traceutil/trace.go:171","msg":"trace[552466169] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1344; }","duration":"240.659659ms","start":"2026-04-02T16:42:41.888906Z","end":"2026-04-02T16:42:42.129566Z","steps":["trace[552466169] 'range keys from in-memory index tree' (duration: 240.345148ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:43.331892Z","caller":"traceutil/trace.go:171","msg":"trace[303689218] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"125.306644ms","start":"2026-04-02T16:42:43.206547Z","end":"2026-04-02T16:42:43.331853Z","steps":["trace[303689218] 'process raft request' (duration: 125.204021ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:43.332148Z","caller":"traceutil/trace.go:171","msg":"trace[1375798500] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"125.796821ms","start":"2026-04-02T16:42:43.206343Z","end":"2026-04-02T16:42:43.332139Z","steps":["trace[1375798500] 'process raft request' (duration: 125.294295ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:43.331913Z","caller":"traceutil/trace.go:171","msg":"trace[1679513326] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"127.697821ms","start":"2026-04-02T16:42:43.204177Z","end":"2026-04-02T16:42:43.331875Z","steps":["trace[1679513326] 'process raft request' (duration: 69.830687ms)","trace[1679513326] 'compare' (duration: 57.331867ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:43.332478Z","caller":"traceutil/trace.go:171","msg":"trace[1217351861] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"127.273027ms","start":"2026-04-02T16:42:43.205135Z","end":"2026-04-02T16:42:43.332408Z","steps":["trace[1217351861] 'process raft request' (duration: 126.44176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:44.70381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.872464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2580"} {"level":"info","ts":"2026-04-02T16:42:44.703905Z","caller":"traceutil/trace.go:171","msg":"trace[1224001796] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1353; }","duration":"282.099973ms","start":"2026-04-02T16:42:44.421783Z","end":"2026-04-02T16:42:44.703883Z","steps":["trace[1224001796] 'range keys from in-memory index tree' (duration: 281.283596ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:45.259996Z","caller":"traceutil/trace.go:171","msg":"trace[1515392443] linearizableReadLoop","detail":"{readStateIndex:1409; appliedIndex:1408; }","duration":"470.433259ms","start":"2026-04-02T16:42:44.789534Z","end":"2026-04-02T16:42:45.259967Z","steps":["trace[1515392443] 'read index received' (duration: 470.310516ms)","trace[1515392443] 'applied index is now lower than readState.Index' (duration: 120.933µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:45.260106Z","caller":"traceutil/trace.go:171","msg":"trace[1786414979] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"548.934783ms","start":"2026-04-02T16:42:44.71116Z","end":"2026-04-02T16:42:45.260094Z","steps":["trace[1786414979] 'process raft request' (duration: 548.630175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:45.260225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:44.711144Z","time spent":"548.987566ms","remote":"127.0.0.1:32816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2753,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T16:42:45.260625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.055049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.69\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T16:42:45.260733Z","caller":"traceutil/trace.go:171","msg":"trace[1316686030] range","detail":"{range_begin:/registry/masterleases/199.204.45.69; range_end:; response_count:1; response_revision:1354; }","duration":"471.212234ms","start":"2026-04-02T16:42:44.789496Z","end":"2026-04-02T16:42:45.260708Z","steps":["trace[1316686030] 'agreement among raft nodes before linearized reading' (duration: 470.933436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:45.260785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:44.78948Z","time spent":"471.293736ms","remote":"127.0.0.1:60876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.69\" "} {"level":"info","ts":"2026-04-02T16:42:45.262074Z","caller":"traceutil/trace.go:171","msg":"trace[1403430865] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"177.410939ms","start":"2026-04-02T16:42:45.084648Z","end":"2026-04-02T16:42:45.262059Z","steps":["trace[1403430865] 'process raft request' (duration: 176.685676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:45.840418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.817697ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-04-02T16:42:45.840638Z","caller":"traceutil/trace.go:171","msg":"trace[339563441] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"573.46616ms","start":"2026-04-02T16:42:45.267153Z","end":"2026-04-02T16:42:45.84062Z","steps":["trace[339563441] 'process raft request' (duration: 573.363567ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:45.840741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:45.267136Z","time spent":"573.541982ms","remote":"127.0.0.1:32884","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-04-02T16:42:45.84077Z","caller":"traceutil/trace.go:171","msg":"trace[927314026] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1410; }","duration":"575.533955ms","start":"2026-04-02T16:42:45.265168Z","end":"2026-04-02T16:42:45.840702Z","steps":["trace[927314026] 'read index received' (duration: 466.562314ms)","trace[927314026] 'applied index is now lower than readState.Index' (duration: 108.968581ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:42:45.840897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:45.262904Z","time spent":"577.988892ms","remote":"127.0.0.1:60876","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-02T16:42:45.841143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.026551ms","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-04-02T16:42:45.841185Z","caller":"traceutil/trace.go:171","msg":"trace[161790380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1356; }","duration":"504.135205ms","start":"2026-04-02T16:42:45.337039Z","end":"2026-04-02T16:42:45.841175Z","steps":["trace[161790380] 'agreement among raft nodes before linearized reading' (duration: 504.009771ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:45.841218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:45.337017Z","time spent":"504.191445ms","remote":"127.0.0.1:32884","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-04-02T16:42:45.841107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.965475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:42:45.841264Z","caller":"traceutil/trace.go:171","msg":"trace[253586505] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1356; }","duration":"532.184093ms","start":"2026-04-02T16:42:45.309064Z","end":"2026-04-02T16:42:45.841248Z","steps":["trace[253586505] 'agreement among raft nodes before linearized reading' (duration: 531.958696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:45.841254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.052562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2768"} {"level":"warn","ts":"2026-04-02T16:42:45.841318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:45.309043Z","time spent":"532.256934ms","remote":"127.0.0.1:32894","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-02T16:42:45.841347Z","caller":"traceutil/trace.go:171","msg":"trace[2116748060] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1356; }","duration":"576.187716ms","start":"2026-04-02T16:42:45.265141Z","end":"2026-04-02T16:42:45.841329Z","steps":["trace[2116748060] 'agreement among raft nodes before linearized reading' (duration: 575.905388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:45.8414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:45.265129Z","time spent":"576.261708ms","remote":"127.0.0.1:32816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2792,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-04-02T16:42:45.841288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.518407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-02T16:42:45.841513Z","caller":"traceutil/trace.go:171","msg":"trace[2088204792] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1356; }","duration":"504.773015ms","start":"2026-04-02T16:42:45.336721Z","end":"2026-04-02T16:42:45.841494Z","steps":["trace[2088204792] 'agreement among raft nodes before linearized reading' (duration: 504.448514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:45.841548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:45.336699Z","time spent":"504.839406ms","remote":"127.0.0.1:32884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T16:42:45.841693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.694632ms","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-04-02T16:42:45.841168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.211097ms","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-04-02T16:42:45.842292Z","caller":"traceutil/trace.go:171","msg":"trace[976768187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1356; }","duration":"505.362182ms","start":"2026-04-02T16:42:45.336914Z","end":"2026-04-02T16:42:45.842276Z","steps":["trace[976768187] 'agreement among raft nodes before linearized reading' (duration: 504.107123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:45.842394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:45.336909Z","time spent":"505.469955ms","remote":"127.0.0.1:32884","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-04-02T16:42:45.842503Z","caller":"traceutil/trace.go:171","msg":"trace[1543704342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1356; }","duration":"504.97131ms","start":"2026-04-02T16:42:45.336784Z","end":"2026-04-02T16:42:45.841755Z","steps":["trace[1543704342] 'agreement among raft nodes before linearized reading' (duration: 504.308369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:45.842634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:45.336779Z","time spent":"505.837598ms","remote":"127.0.0.1:32884","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-04-02T16:42:46.056114Z","caller":"traceutil/trace.go:171","msg":"trace[810304191] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"210.236223ms","start":"2026-04-02T16:42:45.845855Z","end":"2026-04-02T16:42:46.056091Z","steps":["trace[810304191] 'process raft request' (duration: 208.761516ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:46.056421Z","caller":"traceutil/trace.go:171","msg":"trace[783558685] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"209.609612ms","start":"2026-04-02T16:42:45.846787Z","end":"2026-04-02T16:42:46.056396Z","steps":["trace[783558685] 'process raft request' (duration: 209.263311ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:46.056447Z","caller":"traceutil/trace.go:171","msg":"trace[219478313] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"209.004723ms","start":"2026-04-02T16:42:45.847416Z","end":"2026-04-02T16:42:46.056421Z","steps":["trace[219478313] 'process raft request' (duration: 208.958851ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:46.056506Z","caller":"traceutil/trace.go:171","msg":"trace[1460625247] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"209.25139ms","start":"2026-04-02T16:42:45.847211Z","end":"2026-04-02T16:42:46.056462Z","steps":["trace[1460625247] 'process raft request' (duration: 209.062144ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:46.12132Z","caller":"traceutil/trace.go:171","msg":"trace[1108396688] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1413; }","duration":"262.347186ms","start":"2026-04-02T16:42:45.858952Z","end":"2026-04-02T16:42:46.121299Z","steps":["trace[1108396688] 'read index received' (duration: 195.728984ms)","trace[1108396688] 'applied index is now lower than readState.Index' (duration: 66.616902ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:46.121452Z","caller":"traceutil/trace.go:171","msg":"trace[480255564] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"262.639074ms","start":"2026-04-02T16:42:45.858788Z","end":"2026-04-02T16:42:46.121427Z","steps":["trace[480255564] 'process raft request' (duration: 262.408976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:46.121549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.592723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-04-02T16:42:46.121612Z","caller":"traceutil/trace.go:171","msg":"trace[1691027141] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1362; }","duration":"262.701596ms","start":"2026-04-02T16:42:45.858895Z","end":"2026-04-02T16:42:46.121597Z","steps":["trace[1691027141] 'agreement among raft nodes before linearized reading' (duration: 262.568032ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:47.316887Z","caller":"traceutil/trace.go:171","msg":"trace[1189476514] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"331.290605ms","start":"2026-04-02T16:42:46.985577Z","end":"2026-04-02T16:42:47.316867Z","steps":["trace[1189476514] 'process raft request' (duration: 331.15607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:47.31702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T16:42:46.985556Z","time spent":"331.394418ms","remote":"127.0.0.1:32884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T16:42:48.209102Z","caller":"traceutil/trace.go:171","msg":"trace[844928051] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"116.559465ms","start":"2026-04-02T16:42:48.092515Z","end":"2026-04-02T16:42:48.209075Z","steps":["trace[844928051] 'process raft request' (duration: 107.725228ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:48.209376Z","caller":"traceutil/trace.go:171","msg":"trace[1930189334] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"116.745461ms","start":"2026-04-02T16:42:48.092614Z","end":"2026-04-02T16:42:48.209359Z","steps":["trace[1930189334] 'process raft request' (duration: 116.043419ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:48.209604Z","caller":"traceutil/trace.go:171","msg":"trace[602587412] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"116.922987ms","start":"2026-04-02T16:42:48.092664Z","end":"2026-04-02T16:42:48.209587Z","steps":["trace[602587412] 'process raft request' (duration: 116.028ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:52.208912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.032772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:42:52.208995Z","caller":"traceutil/trace.go:171","msg":"trace[1663815045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1380; }","duration":"168.162506ms","start":"2026-04-02T16:42:52.040812Z","end":"2026-04-02T16:42:52.208975Z","steps":["trace[1663815045] 'range keys from in-memory index tree' (duration: 167.926569ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:42:58.164605Z","caller":"traceutil/trace.go:171","msg":"trace[483015055] linearizableReadLoop","detail":"{readStateIndex:1458; appliedIndex:1457; }","duration":"175.264197ms","start":"2026-04-02T16:42:57.989318Z","end":"2026-04-02T16:42:58.164582Z","steps":["trace[483015055] 'read index received' (duration: 175.094972ms)","trace[483015055] 'applied index is now lower than readState.Index' (duration: 168.185µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:42:58.164717Z","caller":"traceutil/trace.go:171","msg":"trace[1976459133] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"190.413598ms","start":"2026-04-02T16:42:57.97425Z","end":"2026-04-02T16:42:58.164663Z","steps":["trace[1976459133] 'process raft request' (duration: 190.209132ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:58.164794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.696554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-04-02T16:42:58.164865Z","caller":"traceutil/trace.go:171","msg":"trace[1651269862] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-autoapprove-certificate-rotation; range_end:; response_count:1; response_revision:1400; }","duration":"162.768216ms","start":"2026-04-02T16:42:58.002058Z","end":"2026-04-02T16:42:58.164826Z","steps":["trace[1651269862] 'agreement among raft nodes before linearized reading' (duration: 162.666863ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:58.164861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.40724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T16:42:58.164935Z","caller":"traceutil/trace.go:171","msg":"trace[985920896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1400; }","duration":"124.530484ms","start":"2026-04-02T16:42:58.040385Z","end":"2026-04-02T16:42:58.164915Z","steps":["trace[985920896] 'agreement among raft nodes before linearized reading' (duration: 124.422151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:42:58.165038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.71238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replication-controller\" ","response":"range_response_count:1 size:830"} {"level":"info","ts":"2026-04-02T16:42:58.165111Z","caller":"traceutil/trace.go:171","msg":"trace[1657673067] range","detail":"{range_begin:/registry/clusterroles/system:controller:replication-controller; range_end:; response_count:1; response_revision:1400; }","duration":"175.799782ms","start":"2026-04-02T16:42:57.989295Z","end":"2026-04-02T16:42:58.165094Z","steps":["trace[1657673067] 'agreement among raft nodes before linearized reading' (duration: 175.415021ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:12.397899Z","caller":"traceutil/trace.go:171","msg":"trace[417238734] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"137.465535ms","start":"2026-04-02T16:43:12.260406Z","end":"2026-04-02T16:43:12.397871Z","steps":["trace[417238734] 'process raft request' (duration: 137.134045ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T16:43:22.773725Z","caller":"traceutil/trace.go:171","msg":"trace[928589698] linearizableReadLoop","detail":"{readStateIndex:1544; appliedIndex:1543; }","duration":"221.033601ms","start":"2026-04-02T16:43:22.552653Z","end":"2026-04-02T16:43:22.773686Z","steps":["trace[928589698] 'read index received' (duration: 143.69211ms)","trace[928589698] 'applied index is now lower than readState.Index' (duration: 77.340271ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T16:43:22.773765Z","caller":"traceutil/trace.go:171","msg":"trace[563476338] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"275.482694ms","start":"2026-04-02T16:43:22.498247Z","end":"2026-04-02T16:43:22.77373Z","steps":["trace[563476338] 'process raft request' (duration: 198.196875ms)","trace[563476338] 'compare' (duration: 77.042042ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T16:43:22.774009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.336131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T16:43:22.77409Z","caller":"traceutil/trace.go:171","msg":"trace[951924336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1481; }","duration":"221.452124ms","start":"2026-04-02T16:43:22.55262Z","end":"2026-04-02T16:43:22.774072Z","steps":["trace[951924336] 'agreement among raft nodes before linearized reading' (duration: 221.257519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:22.774196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.511326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5154"} {"level":"info","ts":"2026-04-02T16:43:22.774287Z","caller":"traceutil/trace.go:171","msg":"trace[689264668] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:1481; }","duration":"202.64798ms","start":"2026-04-02T16:43:22.571615Z","end":"2026-04-02T16:43:22.774263Z","steps":["trace[689264668] 'agreement among raft nodes before linearized reading' (duration: 202.464656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T16:43:22.774312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.495215ms","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-04-02T16:43:22.774481Z","caller":"traceutil/trace.go:171","msg":"trace[790693347] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1481; }","duration":"221.760862ms","start":"2026-04-02T16:43:22.552707Z","end":"2026-04-02T16:43:22.774468Z","steps":["trace[790693347] 'agreement among raft nodes before linearized reading' (duration: 221.33603ms)"],"step_count":1}