{"level":"warn","ts":"2026-04-30T14:28:34.962228Z","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-30T14:28:34.962359Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-30T14:28:34.962451Z","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-30T14:28:34.962481Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-30T14:28:34.962509Z","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-30T14:28:34.963154Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-04-30T14:28:34.963245Z","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.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-30T14:28:34.967264Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.799998ms"} {"level":"info","ts":"2026-04-30T14:28:34.97395Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-04-30T14:28:34.974058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-04-30T14:28:34.974121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-04-30T14:28:34.974223Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-30T14:28:34.974741Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-04-30T14:28:34.974849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-04-30T14:28:34.980742Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-30T14:28:34.982222Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-30T14:28:34.983454Z","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-30T14:28:34.985388Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-30T14:28:34.985618Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-30T14:28:34.98583Z","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-30T14:28:34.985887Z","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-30T14:28:34.985898Z","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-30T14:28:34.986649Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-04-30T14:28:34.986878Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-04-30T14:28:34.992736Z","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-30T14:28:34.993021Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-30T14:28:34.99307Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-30T14:28:34.993213Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-30T14:28:34.993242Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-04-30T14:28:35.975195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-04-30T14:28:35.975262Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-30T14:28:35.975369Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-04-30T14:28:35.97541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-04-30T14:28:35.975431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-30T14:28:35.975458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-04-30T14:28:35.97548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-04-30T14:28:35.976475Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-30T14:28:35.977506Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-04-30T14:28:35.977513Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-30T14:28:35.977929Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-30T14:28:35.978076Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-30T14:28:35.978073Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-30T14:28:35.978075Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-30T14:28:35.978138Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-30T14:28:35.978149Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-30T14:28:35.983027Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-30T14:28:35.989144Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-04-30T14:28:40.765719Z","caller":"traceutil/trace.go:171","msg":"trace[1790843463] linearizableReadLoop","detail":"{readStateIndex:246; appliedIndex:245; }","duration":"105.246803ms","start":"2026-04-30T14:28:40.660453Z","end":"2026-04-30T14:28:40.7657Z","steps":["trace[1790843463] 'read index received' (duration: 46.082658ms)","trace[1790843463] 'applied index is now lower than readState.Index' (duration: 59.163425ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:28:40.765827Z","caller":"traceutil/trace.go:171","msg":"trace[2146919002] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"110.713244ms","start":"2026-04-30T14:28:40.655105Z","end":"2026-04-30T14:28:40.765818Z","steps":["trace[2146919002] 'process raft request' (duration: 51.419226ms)","trace[2146919002] 'compare' (duration: 59.072572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:28:40.766003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.5404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:115"} {"level":"info","ts":"2026-04-30T14:28:40.766107Z","caller":"traceutil/trace.go:171","msg":"trace[733021472] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:240; }","duration":"105.674504ms","start":"2026-04-30T14:28:40.660411Z","end":"2026-04-30T14:28:40.766085Z","steps":["trace[733021472] 'agreement among raft nodes before linearized reading' (duration: 105.503999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:28:41.310001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.392766ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-30T14:28:41.310356Z","caller":"traceutil/trace.go:171","msg":"trace[743201081] transaction","detail":"{read_only:false; number_of_response:0; response_revision:241; }","duration":"544.146294ms","start":"2026-04-30T14:28:40.766157Z","end":"2026-04-30T14:28:41.310303Z","steps":["trace[743201081] 'process raft request' (duration: 543.977709ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:28:41.310388Z","caller":"traceutil/trace.go:171","msg":"trace[1322958272] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"532.633817ms","start":"2026-04-30T14:28:40.777737Z","end":"2026-04-30T14:28:41.310371Z","steps":["trace[1322958272] 'process raft request' (duration: 532.590566ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:28:41.310389Z","caller":"traceutil/trace.go:171","msg":"trace[1450093906] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"537.03828ms","start":"2026-04-30T14:28:40.773319Z","end":"2026-04-30T14:28:41.310357Z","steps":["trace[1450093906] 'process raft request' (duration: 536.903897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:28:41.310486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:40.766139Z","time spent":"544.282648ms","remote":"127.0.0.1:51122","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T14:28:41.310526Z","caller":"traceutil/trace.go:171","msg":"trace[2123589107] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"535.299796ms","start":"2026-04-30T14:28:40.775218Z","end":"2026-04-30T14:28:41.310517Z","steps":["trace[2123589107] 'process raft request' (duration: 535.035439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:28:41.310528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:40.773301Z","time spent":"537.152623ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3959,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T14:28:41.310568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:40.775198Z","time spent":"535.353057ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5877,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T14:28:41.310697Z","caller":"traceutil/trace.go:171","msg":"trace[1150857556] transaction","detail":"{read_only:false; response_revision:241; number_of_response:1; }","duration":"644.846069ms","start":"2026-04-30T14:28:40.665832Z","end":"2026-04-30T14:28:41.310678Z","steps":["trace[1150857556] 'process raft request' (duration: 261.223702ms)","trace[1150857556] 'compare' (duration: 382.238652ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:28:41.310708Z","caller":"traceutil/trace.go:171","msg":"trace[1687545212] transaction","detail":"{read_only:false; number_of_response:0; response_revision:244; }","duration":"535.414369ms","start":"2026-04-30T14:28:40.775283Z","end":"2026-04-30T14:28:41.310698Z","steps":["trace[1687545212] 'process raft request' (duration: 534.997078ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:28:41.310751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:40.665822Z","time spent":"644.90242ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T14:28:41.310787Z","caller":"traceutil/trace.go:171","msg":"trace[402238893] transaction","detail":"{read_only:false; number_of_response:0; response_revision:244; }","duration":"533.331565ms","start":"2026-04-30T14:28:40.777449Z","end":"2026-04-30T14:28:41.310781Z","steps":["trace[402238893] 'process raft request' (duration: 532.856153ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:28:41.310788Z","caller":"traceutil/trace.go:171","msg":"trace[661292292] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"543.97175ms","start":"2026-04-30T14:28:40.766803Z","end":"2026-04-30T14:28:41.310775Z","steps":["trace[661292292] 'process raft request' (duration: 543.374354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:28:41.310823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:40.777433Z","time spent":"533.375156ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T14:28:41.310759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:40.775267Z","time spent":"535.470531ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":29,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T14:28:41.310901Z","caller":"traceutil/trace.go:171","msg":"trace[477712906] linearizableReadLoop","detail":"{readStateIndex:247; appliedIndex:246; }","duration":"545.105119ms","start":"2026-04-30T14:28:40.765784Z","end":"2026-04-30T14:28:41.310889Z","steps":["trace[477712906] 'read index received' (duration: 161.361668ms)","trace[477712906] 'applied index is now lower than readState.Index' (duration: 383.74152ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:28:41.310461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:40.777725Z","time spent":"532.705359ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T14:28:41.311065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:40.766795Z","time spent":"544.030841ms","remote":"127.0.0.1:51006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T14:28:41.311111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.300617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T14:28:41.311129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.713042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-30T14:28:41.311186Z","caller":"traceutil/trace.go:171","msg":"trace[254185408] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:245; }","duration":"645.776894ms","start":"2026-04-30T14:28:40.665392Z","end":"2026-04-30T14:28:41.311169Z","steps":["trace[254185408] 'agreement among raft nodes before linearized reading' (duration: 645.61701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:28:41.311228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:40.665387Z","time spent":"645.833405ms","remote":"127.0.0.1:51334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-30T14:28:41.311201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.809644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T14:28:41.311146Z","caller":"traceutil/trace.go:171","msg":"trace[484061159] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:245; }","duration":"542.359019ms","start":"2026-04-30T14:28:40.768777Z","end":"2026-04-30T14:28:41.311136Z","steps":["trace[484061159] 'agreement among raft nodes before linearized reading' (duration: 542.236166ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:28:41.311304Z","caller":"traceutil/trace.go:171","msg":"trace[542684193] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:245; }","duration":"645.962319ms","start":"2026-04-30T14:28:40.665319Z","end":"2026-04-30T14:28:41.311281Z","steps":["trace[542684193] 'agreement among raft nodes before linearized reading' (duration: 645.67541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:28:41.312152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:40.768769Z","time spent":"543.21164ms","remote":"127.0.0.1:51146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":29,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-04-30T14:28:41.311352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:40.665302Z","time spent":"646.041181ms","remote":"127.0.0.1:51224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"warn","ts":"2026-04-30T14:28:41.726584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.985284ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-30T14:28:41.726757Z","caller":"traceutil/trace.go:171","msg":"trace[1197636063] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"345.435153ms","start":"2026-04-30T14:28:41.381305Z","end":"2026-04-30T14:28:41.72674Z","steps":["trace[1197636063] 'process raft request' (duration: 345.382411ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:28:41.726819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:41.381289Z","time spent":"345.505754ms","remote":"127.0.0.1:51060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1933,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T14:28:41.726969Z","caller":"traceutil/trace.go:171","msg":"trace[862058028] linearizableReadLoop","detail":"{readStateIndex:256; appliedIndex:255; }","duration":"351.216261ms","start":"2026-04-30T14:28:41.375542Z","end":"2026-04-30T14:28:41.726758Z","steps":["trace[862058028] 'read index received' (duration: 221.930099ms)","trace[862058028] 'applied index is now lower than readState.Index' (duration: 129.281392ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:28:41.727392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.537495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"warn","ts":"2026-04-30T14:28:41.727404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.14982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T14:28:41.727443Z","caller":"traceutil/trace.go:171","msg":"trace[551438567] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:248; }","duration":"409.593946ms","start":"2026-04-30T14:28:41.317835Z","end":"2026-04-30T14:28:41.727429Z","steps":["trace[551438567] 'agreement among raft nodes before linearized reading' (duration: 409.439072ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:28:41.727468Z","caller":"traceutil/trace.go:171","msg":"trace[197341326] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:248; }","duration":"370.238352ms","start":"2026-04-30T14:28:41.357212Z","end":"2026-04-30T14:28:41.72745Z","steps":["trace[197341326] 'agreement among raft nodes before linearized reading' (duration: 370.131719ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:28:41.727403Z","caller":"traceutil/trace.go:171","msg":"trace[2009383473] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"410.333975ms","start":"2026-04-30T14:28:41.317018Z","end":"2026-04-30T14:28:41.727352Z","steps":["trace[2009383473] 'process raft request' (duration: 280.492079ms)","trace[2009383473] 'compare' (duration: 128.783669ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:28:41.727481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.683308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-04-30T14:28:41.727523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:41.357185Z","time spent":"370.327534ms","remote":"127.0.0.1:51224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-30T14:28:41.727537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.171563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-04-30T14:28:41.727563Z","caller":"traceutil/trace.go:171","msg":"trace[213023883] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:248; }","duration":"412.232084ms","start":"2026-04-30T14:28:41.315323Z","end":"2026-04-30T14:28:41.727555Z","steps":["trace[213023883] 'agreement among raft nodes before linearized reading' (duration: 412.185053ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:28:41.727476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:41.31779Z","time spent":"409.679108ms","remote":"127.0.0.1:51122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-30T14:28:41.727596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:41.315307Z","time spent":"412.283225ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3490,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-04-30T14:28:41.727489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.285984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-30T14:28:41.727562Z","caller":"traceutil/trace.go:171","msg":"trace[1030275877] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:248; }","duration":"409.76311ms","start":"2026-04-30T14:28:41.317781Z","end":"2026-04-30T14:28:41.727544Z","steps":["trace[1030275877] 'agreement among raft nodes before linearized reading' (duration: 409.640217ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:28:41.727743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:41.317773Z","time spent":"409.962126ms","remote":"127.0.0.1:51070","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":375,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-30T14:28:41.727781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:41.317002Z","time spent":"410.53312ms","remote":"127.0.0.1:51038","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":625,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-30T14:28:41.727752Z","caller":"traceutil/trace.go:171","msg":"trace[21421780] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:248; }","duration":"410.5439ms","start":"2026-04-30T14:28:41.317122Z","end":"2026-04-30T14:28:41.727666Z","steps":["trace[21421780] 'agreement among raft nodes before linearized reading' (duration: 410.252353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:28:41.727998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:41.317116Z","time spent":"410.863438ms","remote":"127.0.0.1:51334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":688,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-04-30T14:28:41.728244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.807859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:2920"} {"level":"info","ts":"2026-04-30T14:28:41.728421Z","caller":"traceutil/trace.go:171","msg":"trace[2132546142] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:248; }","duration":"413.087007ms","start":"2026-04-30T14:28:41.315317Z","end":"2026-04-30T14:28:41.728404Z","steps":["trace[2132546142] 'agreement among raft nodes before linearized reading' (duration: 412.06716ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:28:41.728543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:41.315297Z","time spent":"413.23213ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":2944,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-04-30T14:28:41.731507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.212083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-04-30T14:28:41.731542Z","caller":"traceutil/trace.go:171","msg":"trace[1521678508] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:248; }","duration":"353.273084ms","start":"2026-04-30T14:28:41.378261Z","end":"2026-04-30T14:28:41.731534Z","steps":["trace[1521678508] 'agreement among raft nodes before linearized reading' (duration: 353.140091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:28:41.731565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:28:41.378248Z","time spent":"353.311816ms","remote":"127.0.0.1:51122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4040,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-30T14:28:41.731911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.194778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T14:28:41.731933Z","caller":"traceutil/trace.go:171","msg":"trace[699237518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:248; }","duration":"177.252709ms","start":"2026-04-30T14:28:41.554674Z","end":"2026-04-30T14:28:41.731926Z","steps":["trace[699237518] 'agreement among raft nodes before linearized reading' (duration: 177.222988ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:29:06.440566Z","caller":"traceutil/trace.go:171","msg":"trace[2122486752] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"169.791527ms","start":"2026-04-30T14:29:06.270755Z","end":"2026-04-30T14:29:06.440547Z","steps":["trace[2122486752] 'process raft request' (duration: 169.663653ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:29:06.522653Z","caller":"traceutil/trace.go:171","msg":"trace[888605026] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"243.782515ms","start":"2026-04-30T14:29:06.278839Z","end":"2026-04-30T14:29:06.522621Z","steps":["trace[888605026] 'process raft request' (duration: 243.561638ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:29:06.522727Z","caller":"traceutil/trace.go:171","msg":"trace[220737469] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"243.784485ms","start":"2026-04-30T14:29:06.278891Z","end":"2026-04-30T14:29:06.522675Z","steps":["trace[220737469] 'process raft request' (duration: 243.645521ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:29:06.522788Z","caller":"traceutil/trace.go:171","msg":"trace[465888232] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"240.639606ms","start":"2026-04-30T14:29:06.28213Z","end":"2026-04-30T14:29:06.522769Z","steps":["trace[465888232] 'process raft request' (duration: 240.524833ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:29:06.522801Z","caller":"traceutil/trace.go:171","msg":"trace[2048789935] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"240.709457ms","start":"2026-04-30T14:29:06.282084Z","end":"2026-04-30T14:29:06.522793Z","steps":["trace[2048789935] 'process raft request' (duration: 240.531252ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:29:06.522808Z","caller":"traceutil/trace.go:171","msg":"trace[1688762879] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"237.653151ms","start":"2026-04-30T14:29:06.285126Z","end":"2026-04-30T14:29:06.522779Z","steps":["trace[1688762879] 'process raft request' (duration: 237.566289ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:29:06.522946Z","caller":"traceutil/trace.go:171","msg":"trace[2126046625] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"231.163426ms","start":"2026-04-30T14:29:06.291771Z","end":"2026-04-30T14:29:06.522935Z","steps":["trace[2126046625] 'process raft request' (duration: 230.953431ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:29:06.522996Z","caller":"traceutil/trace.go:171","msg":"trace[681682624] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"240.777579ms","start":"2026-04-30T14:29:06.281947Z","end":"2026-04-30T14:29:06.522725Z","steps":["trace[681682624] 'process raft request' (duration: 240.628925ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:29:06.523126Z","caller":"traceutil/trace.go:171","msg":"trace[468891868] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"216.012297ms","start":"2026-04-30T14:29:06.307105Z","end":"2026-04-30T14:29:06.523117Z","steps":["trace[468891868] 'process raft request' (duration: 215.865373ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:29:06.52302Z","caller":"traceutil/trace.go:171","msg":"trace[681158007] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"225.26382ms","start":"2026-04-30T14:29:06.297747Z","end":"2026-04-30T14:29:06.523011Z","steps":["trace[681158007] 'process raft request' (duration: 225.101925ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:29:06.523208Z","caller":"traceutil/trace.go:171","msg":"trace[841317160] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"197.210954ms","start":"2026-04-30T14:29:06.325982Z","end":"2026-04-30T14:29:06.523193Z","steps":["trace[841317160] 'process raft request' (duration: 197.044189ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:29:12.397457Z","caller":"traceutil/trace.go:171","msg":"trace[940737060] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"105.109051ms","start":"2026-04-30T14:29:12.292323Z","end":"2026-04-30T14:29:12.397432Z","steps":["trace[940737060] 'process raft request' (duration: 104.603286ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:29:12.91496Z","caller":"traceutil/trace.go:171","msg":"trace[811053209] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"300.673869ms","start":"2026-04-30T14:29:12.614258Z","end":"2026-04-30T14:29:12.914931Z","steps":["trace[811053209] 'read index received' (duration: 300.510694ms)","trace[811053209] 'applied index is now lower than readState.Index' (duration: 162.175µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:29:12.915311Z","caller":"traceutil/trace.go:171","msg":"trace[1380100150] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"467.522042ms","start":"2026-04-30T14:29:12.447773Z","end":"2026-04-30T14:29:12.915295Z","steps":["trace[1380100150] 'process raft request' (duration: 467.046449ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:29:12.915449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:29:12.447745Z","time spent":"467.612114ms","remote":"127.0.0.1:51432","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-30T14:29:12.915468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.195344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-6fwdx\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-30T14:29:12.915692Z","caller":"traceutil/trace.go:171","msg":"trace[1323410104] range","detail":"{range_begin:/registry/pods/kube-system/cilium-6fwdx; range_end:; response_count:1; response_revision:579; }","duration":"147.454991ms","start":"2026-04-30T14:29:12.768217Z","end":"2026-04-30T14:29:12.915672Z","steps":["trace[1323410104] 'agreement among raft nodes before linearized reading' (duration: 146.977298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:29:12.915708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.43009ms","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-30T14:29:12.915851Z","caller":"traceutil/trace.go:171","msg":"trace[755042449] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:579; }","duration":"301.602494ms","start":"2026-04-30T14:29:12.614206Z","end":"2026-04-30T14:29:12.915809Z","steps":["trace[755042449] 'agreement among raft nodes before linearized reading' (duration: 301.180402ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:29:12.915906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:29:12.614186Z","time spent":"301.707648ms","remote":"127.0.0.1:51224","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-30T14:30:33.312219Z","caller":"traceutil/trace.go:171","msg":"trace[2140826123] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"162.951966ms","start":"2026-04-30T14:30:33.149235Z","end":"2026-04-30T14:30:33.312187Z","steps":["trace[2140826123] 'process raft request' (duration: 162.72166ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:30:45.880853Z","caller":"traceutil/trace.go:171","msg":"trace[1019061648] linearizableReadLoop","detail":"{readStateIndex:1191; appliedIndex:1190; }","duration":"355.902533ms","start":"2026-04-30T14:30:45.524934Z","end":"2026-04-30T14:30:45.880836Z","steps":["trace[1019061648] 'read index received' (duration: 355.80626ms)","trace[1019061648] 'applied index is now lower than readState.Index' (duration: 95.523µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:30:45.880939Z","caller":"traceutil/trace.go:171","msg":"trace[285487105] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"529.164262ms","start":"2026-04-30T14:30:45.351769Z","end":"2026-04-30T14:30:45.880934Z","steps":["trace[285487105] 'process raft request' (duration: 528.966016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:45.881011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:45.35174Z","time spent":"529.215702ms","remote":"127.0.0.1:51224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T14:30:45.88115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.217651ms","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-30T14:30:45.881173Z","caller":"traceutil/trace.go:171","msg":"trace[515534638] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1147; }","duration":"356.276043ms","start":"2026-04-30T14:30:45.524891Z","end":"2026-04-30T14:30:45.881167Z","steps":["trace[515534638] 'agreement among raft nodes before linearized reading' (duration: 356.197631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:45.881188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:45.524871Z","time spent":"356.313194ms","remote":"127.0.0.1:51224","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-30T14:30:50.81762Z","caller":"traceutil/trace.go:171","msg":"trace[762941891] linearizableReadLoop","detail":"{readStateIndex:1208; appliedIndex:1207; }","duration":"340.074615ms","start":"2026-04-30T14:30:50.477529Z","end":"2026-04-30T14:30:50.817603Z","steps":["trace[762941891] 'read index received' (duration: 339.643123ms)","trace[762941891] 'applied index is now lower than readState.Index' (duration: 430.932µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:30:50.81769Z","caller":"traceutil/trace.go:171","msg":"trace[1819731443] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"363.346953ms","start":"2026-04-30T14:30:50.454338Z","end":"2026-04-30T14:30:50.817685Z","steps":["trace[1819731443] 'process raft request' (duration: 362.87557ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:50.81797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.428499ms","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-30T14:30:50.818011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.465575ms","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-30T14:30:50.818043Z","caller":"traceutil/trace.go:171","msg":"trace[1030436635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1163; }","duration":"338.510191ms","start":"2026-04-30T14:30:50.479515Z","end":"2026-04-30T14:30:50.818025Z","steps":["trace[1030436635] 'agreement among raft nodes before linearized reading' (duration: 338.260694ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:30:50.81806Z","caller":"traceutil/trace.go:171","msg":"trace[658902874] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1163; }","duration":"340.545528ms","start":"2026-04-30T14:30:50.477496Z","end":"2026-04-30T14:30:50.818049Z","steps":["trace[658902874] 'agreement among raft nodes before linearized reading' (duration: 340.381223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:50.818088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:50.477489Z","time spent":"340.594469ms","remote":"127.0.0.1:51224","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-30T14:30:50.818086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:50.479511Z","time spent":"338.566683ms","remote":"127.0.0.1:51224","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-30T14:30:50.818235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.317763ms","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-30T14:30:50.818253Z","caller":"traceutil/trace.go:171","msg":"trace[391674975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1163; }","duration":"131.334564ms","start":"2026-04-30T14:30:50.686912Z","end":"2026-04-30T14:30:50.818247Z","steps":["trace[391674975] 'agreement among raft nodes before linearized reading' (duration: 131.264112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:50.818451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:50.454322Z","time spent":"363.380704ms","remote":"127.0.0.1:51224","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-30T14:30:52.400218Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278502431402269,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T14:30:52.900613Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278502431402269,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T14:30:53.401127Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278502431402269,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T14:30:53.902078Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278502431402269,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T14:30:54.402769Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.506062774s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-30T14:30:54.402848Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278502431402269,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T14:30:54.404731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.954415749s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T14:30:54.404818Z","caller":"traceutil/trace.go:171","msg":"trace[1013849436] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1166; }","duration":"2.954564173s","start":"2026-04-30T14:30:51.450234Z","end":"2026-04-30T14:30:54.404799Z","steps":["trace[1013849436] 'count revisions from in-memory index tree' (duration: 2.954333527s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:54.404848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:51.450216Z","time spent":"2.954625385s","remote":"127.0.0.1:35832","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-30T14:30:54.405199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.575003223s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T14:30:54.405274Z","caller":"traceutil/trace.go:171","msg":"trace[1692297604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1166; }","duration":"2.575116387s","start":"2026-04-30T14:30:51.830137Z","end":"2026-04-30T14:30:54.405253Z","steps":["trace[1692297604] 'range keys from in-memory index tree' (duration: 2.574946662s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:54.405316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:51.830122Z","time spent":"2.575186108s","remote":"127.0.0.1:41516","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-30T14:30:54.405333Z","caller":"traceutil/trace.go:171","msg":"trace[1755823738] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"2.505377165s","start":"2026-04-30T14:30:51.899916Z","end":"2026-04-30T14:30:54.405293Z","steps":["trace[1755823738] 'read index received' (duration: 2.502990407s)","trace[1755823738] 'applied index is now lower than readState.Index' (duration: 2.384758ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:30:54.405484Z","caller":"traceutil/trace.go:171","msg":"trace[1116841581] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"2.508877333s","start":"2026-04-30T14:30:51.896588Z","end":"2026-04-30T14:30:54.405466Z","steps":["trace[1116841581] 'process raft request' (duration: 2.506355602s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:54.40581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:51.89656Z","time spent":"2.508954046s","remote":"127.0.0.1:51224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T14:30:54.405863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.50594794s","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-30T14:30:54.405896Z","caller":"traceutil/trace.go:171","msg":"trace[518054691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1167; }","duration":"2.506005262s","start":"2026-04-30T14:30:51.899883Z","end":"2026-04-30T14:30:54.405888Z","steps":["trace[518054691] 'agreement among raft nodes before linearized reading' (duration: 2.505891829s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:54.405911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:51.899869Z","time spent":"2.506039633s","remote":"127.0.0.1:51224","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-30T14:30:54.780736Z","caller":"traceutil/trace.go:171","msg":"trace[1894209471] transaction","detail":"{read_only:false; response_revision:1168; number_of_response:1; }","duration":"715.234553ms","start":"2026-04-30T14:30:54.065475Z","end":"2026-04-30T14:30:54.780709Z","steps":["trace[1894209471] 'process raft request' (duration: 677.274699ms)","trace[1894209471] 'compare' (duration: 37.773918ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:30:54.780864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:54.065435Z","time spent":"715.360826ms","remote":"127.0.0.1:51224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-30T14:30:54.826772Z","caller":"traceutil/trace.go:171","msg":"trace[1806548022] linearizableReadLoop","detail":"{readStateIndex:1214; appliedIndex:1212; }","duration":"421.324742ms","start":"2026-04-30T14:30:54.405421Z","end":"2026-04-30T14:30:54.826746Z","steps":["trace[1806548022] 'read index received' (duration: 337.359288ms)","trace[1806548022] 'applied index is now lower than readState.Index' (duration: 83.963764ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:30:54.826961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.563726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T14:30:54.827031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.995047885s","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-30T14:30:54.827062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.995970341s","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":"warn","ts":"2026-04-30T14:30:54.827042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.001804096s","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-30T14:30:54.827112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.66684133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T14:30:54.827127Z","caller":"traceutil/trace.go:171","msg":"trace[135432686] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1168; }","duration":"1.996062984s","start":"2026-04-30T14:30:52.831045Z","end":"2026-04-30T14:30:54.827108Z","steps":["trace[135432686] 'agreement among raft nodes before linearized reading' (duration: 1.995895239s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:54.82715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"996.155804ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T14:30:54.827166Z","caller":"traceutil/trace.go:171","msg":"trace[1473104565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1168; }","duration":"2.001990961s","start":"2026-04-30T14:30:52.825155Z","end":"2026-04-30T14:30:54.827146Z","steps":["trace[1473104565] 'agreement among raft nodes before linearized reading' (duration: 2.001650221s)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:30:54.827172Z","caller":"traceutil/trace.go:171","msg":"trace[1447301153] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1168; }","duration":"2.666904051s","start":"2026-04-30T14:30:52.160252Z","end":"2026-04-30T14:30:54.827156Z","steps":["trace[1447301153] 'agreement among raft nodes before linearized reading' (duration: 2.666821259s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:54.827181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:52.831026Z","time spent":"1.996142416s","remote":"127.0.0.1:51224","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-30T14:30:54.827193Z","caller":"traceutil/trace.go:171","msg":"trace[1678797598] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1168; }","duration":"996.250356ms","start":"2026-04-30T14:30:53.830932Z","end":"2026-04-30T14:30:54.827183Z","steps":["trace[1678797598] 'agreement among raft nodes before linearized reading' (duration: 996.191294ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:54.827217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:52.825136Z","time spent":"2.002072414s","remote":"127.0.0.1:51224","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-30T14:30:54.827218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:52.160236Z","time spent":"2.666968493s","remote":"127.0.0.1:35818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-30T14:30:54.827245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:53.8309Z","time spent":"996.336008ms","remote":"127.0.0.1:41512","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-30T14:30:54.827093Z","caller":"traceutil/trace.go:171","msg":"trace[1687444286] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1168; }","duration":"1.995133628s","start":"2026-04-30T14:30:52.831943Z","end":"2026-04-30T14:30:54.827077Z","steps":["trace[1687444286] 'agreement among raft nodes before linearized reading' (duration: 1.99484227s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:54.827367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:52.831934Z","time spent":"1.995425366s","remote":"127.0.0.1:51224","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-30T14:30:54.827038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.38925063s","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-30T14:30:54.827454Z","caller":"traceutil/trace.go:171","msg":"trace[2129551977] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1168; }","duration":"2.389703603s","start":"2026-04-30T14:30:52.437742Z","end":"2026-04-30T14:30:54.827446Z","steps":["trace[2129551977] 'agreement among raft nodes before linearized reading' (duration: 2.389130787s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:54.82748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:52.437715Z","time spent":"2.389759625s","remote":"127.0.0.1:51224","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-30T14:30:54.827079Z","caller":"traceutil/trace.go:171","msg":"trace[1237789717] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1168; }","duration":"747.755421ms","start":"2026-04-30T14:30:54.079297Z","end":"2026-04-30T14:30:54.827052Z","steps":["trace[1237789717] 'agreement among raft nodes before linearized reading' (duration: 747.548676ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:54.827553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:54.079274Z","time spent":"748.269406ms","remote":"127.0.0.1:35878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-04-30T14:30:54.839279Z","caller":"traceutil/trace.go:171","msg":"trace[880653096] transaction","detail":"{read_only:false; response_revision:1169; number_of_response:1; }","duration":"428.724062ms","start":"2026-04-30T14:30:54.410531Z","end":"2026-04-30T14:30:54.839255Z","steps":["trace[880653096] 'process raft request' (duration: 428.478044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:54.839326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.307301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T14:30:54.839367Z","caller":"traceutil/trace.go:171","msg":"trace[344432855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1169; }","duration":"213.377183ms","start":"2026-04-30T14:30:54.625978Z","end":"2026-04-30T14:30:54.839355Z","steps":["trace[344432855] 'agreement among raft nodes before linearized reading' (duration: 213.293561ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:30:54.839389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:30:54.410511Z","time spent":"428.815805ms","remote":"127.0.0.1:51224","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-30T14:31:01.311317Z","caller":"traceutil/trace.go:171","msg":"trace[1991986903] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"199.005458ms","start":"2026-04-30T14:31:01.112282Z","end":"2026-04-30T14:31:01.311287Z","steps":["trace[1991986903] 'process raft request' (duration: 198.819593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:31:02.247374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.085273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-30T14:31:02.247466Z","caller":"traceutil/trace.go:171","msg":"trace[604459533] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"260.533009ms","start":"2026-04-30T14:31:01.986919Z","end":"2026-04-30T14:31:02.247452Z","steps":["trace[604459533] 'process raft request' (duration: 109.315962ms)","trace[604459533] 'compare' (duration: 150.97115ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:31:02.474388Z","caller":"traceutil/trace.go:171","msg":"trace[1796200322] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"221.67157ms","start":"2026-04-30T14:31:02.25268Z","end":"2026-04-30T14:31:02.474352Z","steps":["trace[1796200322] 'process raft request' (duration: 157.463614ms)","trace[1796200322] 'compare' (duration: 64.021281ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:31:02.500932Z","caller":"traceutil/trace.go:171","msg":"trace[297991800] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"245.195404ms","start":"2026-04-30T14:31:02.255713Z","end":"2026-04-30T14:31:02.500908Z","steps":["trace[297991800] 'process raft request' (duration: 245.138683ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:02.501017Z","caller":"traceutil/trace.go:171","msg":"trace[1901624510] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"245.817403ms","start":"2026-04-30T14:31:02.255172Z","end":"2026-04-30T14:31:02.50099Z","steps":["trace[1901624510] 'process raft request' (duration: 245.573445ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:03.087603Z","caller":"traceutil/trace.go:171","msg":"trace[1202302044] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1270; }","duration":"117.979757ms","start":"2026-04-30T14:31:02.969599Z","end":"2026-04-30T14:31:03.087579Z","steps":["trace[1202302044] 'read index received' (duration: 117.795732ms)","trace[1202302044] 'applied index is now lower than readState.Index' (duration: 183.175µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:31:03.087651Z","caller":"traceutil/trace.go:171","msg":"trace[1519027798] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"132.882379ms","start":"2026-04-30T14:31:02.954737Z","end":"2026-04-30T14:31:03.087619Z","steps":["trace[1519027798] 'process raft request' (duration: 132.707774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:31:03.08782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.200093ms","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-30T14:31:03.087862Z","caller":"traceutil/trace.go:171","msg":"trace[63454971] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1224; }","duration":"118.274226ms","start":"2026-04-30T14:31:02.969576Z","end":"2026-04-30T14:31:03.08785Z","steps":["trace[63454971] 'agreement among raft nodes before linearized reading' (duration: 118.127962ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:31:03.087917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.227714ms","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-30T14:31:03.087973Z","caller":"traceutil/trace.go:171","msg":"trace[936886094] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1224; }","duration":"118.324387ms","start":"2026-04-30T14:31:02.969631Z","end":"2026-04-30T14:31:03.087955Z","steps":["trace[936886094] 'agreement among raft nodes before linearized reading' (duration: 118.152602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:31:03.087961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.587886ms","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":"warn","ts":"2026-04-30T14:31:03.088006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.592346ms","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-30T14:31:03.088039Z","caller":"traceutil/trace.go:171","msg":"trace[670358684] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1224; }","duration":"117.674958ms","start":"2026-04-30T14:31:02.970342Z","end":"2026-04-30T14:31:03.088017Z","steps":["trace[670358684] 'agreement among raft nodes before linearized reading' (duration: 117.468143ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:03.088086Z","caller":"traceutil/trace.go:171","msg":"trace[1483461574] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1224; }","duration":"117.692119ms","start":"2026-04-30T14:31:02.970374Z","end":"2026-04-30T14:31:03.088066Z","steps":["trace[1483461574] 'agreement among raft nodes before linearized reading' (duration: 117.427101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:31:27.753943Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278502431402674,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T14:31:28.254838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278502431402674,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T14:31:28.75583Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278502431402674,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T14:31:29.256761Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278502431402674,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-30T14:31:29.542324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.060067ms","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-30T14:31:29.542437Z","caller":"traceutil/trace.go:171","msg":"trace[1551267582] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1362; }","duration":"993.18158ms","start":"2026-04-30T14:31:28.549239Z","end":"2026-04-30T14:31:29.54242Z","steps":["trace[1551267582] 'range keys from in-memory index tree' (duration: 993.045876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:31:29.586687Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.335407182s","expected-duration":"1s"} {"level":"info","ts":"2026-04-30T14:31:29.587017Z","caller":"traceutil/trace.go:171","msg":"trace[950077324] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"2.335842516s","start":"2026-04-30T14:31:27.251158Z","end":"2026-04-30T14:31:29.587Z","steps":["trace[950077324] 'process raft request' (duration: 2.335716023s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:31:29.587127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:31:27.251131Z","time spent":"2.335935198s","remote":"127.0.0.1:51224","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-30T14:31:29.613064Z","caller":"traceutil/trace.go:171","msg":"trace[1864759468] linearizableReadLoop","detail":"{readStateIndex:1416; appliedIndex:1414; }","duration":"2.359943378s","start":"2026-04-30T14:31:27.253098Z","end":"2026-04-30T14:31:29.613041Z","steps":["trace[1864759468] 'read index received' (duration: 2.333721655s)","trace[1864759468] 'applied index is now lower than readState.Index' (duration: 26.220703ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:31:29.613181Z","caller":"traceutil/trace.go:171","msg":"trace[689035982] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"1.018085775s","start":"2026-04-30T14:31:28.595084Z","end":"2026-04-30T14:31:29.61317Z","steps":["trace[689035982] 'process raft request' (duration: 1.017840088s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:31:29.613288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:31:28.59506Z","time spent":"1.018146047s","remote":"127.0.0.1:51224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T14:31:29.613406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.360329779s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-04-30T14:31:29.613411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.698025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T14:31:29.613457Z","caller":"traceutil/trace.go:171","msg":"trace[1031695208] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1364; }","duration":"2.360405071s","start":"2026-04-30T14:31:27.253028Z","end":"2026-04-30T14:31:29.613433Z","steps":["trace[1031695208] 'agreement among raft nodes before linearized reading' (duration: 2.360194265s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:31:29.613486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.345809998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-30T14:31:29.613715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.069442711s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-04-30T14:31:29.61374Z","caller":"traceutil/trace.go:171","msg":"trace[703729258] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1364; }","duration":"2.069502623s","start":"2026-04-30T14:31:27.544231Z","end":"2026-04-30T14:31:29.613733Z","steps":["trace[703729258] 'agreement among raft nodes before linearized reading' (duration: 2.069434181s)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:29.613781Z","caller":"traceutil/trace.go:171","msg":"trace[2123801654] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1364; }","duration":"2.346113017s","start":"2026-04-30T14:31:27.267613Z","end":"2026-04-30T14:31:29.613726Z","steps":["trace[2123801654] 'agreement among raft nodes before linearized reading' (duration: 2.345660964s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:31:29.613831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:31:27.267598Z","time spent":"2.34622328s","remote":"127.0.0.1:51224","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-30T14:31:29.613829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:31:27.544211Z","time spent":"2.069546565s","remote":"127.0.0.1:51224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-30T14:31:29.613916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.874061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-30T14:31:29.613977Z","caller":"traceutil/trace.go:171","msg":"trace[1843490673] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1364; }","duration":"212.964373ms","start":"2026-04-30T14:31:29.400999Z","end":"2026-04-30T14:31:29.613963Z","steps":["trace[1843490673] 'agreement among raft nodes before linearized reading' (duration: 212.692766ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:29.613618Z","caller":"traceutil/trace.go:171","msg":"trace[1056966209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1364; }","duration":"783.873991ms","start":"2026-04-30T14:31:28.829666Z","end":"2026-04-30T14:31:29.61354Z","steps":["trace[1056966209] 'agreement among raft nodes before linearized reading' (duration: 783.625494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:31:29.613591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:31:27.253006Z","time spent":"2.360556975s","remote":"127.0.0.1:51224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-30T14:31:29.614049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:31:28.829599Z","time spent":"784.424976ms","remote":"127.0.0.1:41516","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-30T14:31:29.613941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.217297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-30T14:31:29.614205Z","caller":"traceutil/trace.go:171","msg":"trace[1249191675] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:1364; }","duration":"687.526936ms","start":"2026-04-30T14:31:28.926662Z","end":"2026-04-30T14:31:29.614189Z","steps":["trace[1249191675] 'agreement among raft nodes before linearized reading' (duration: 686.988471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:31:29.613667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.068427ms","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-30T14:31:29.614222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.343169973s","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-30T14:31:29.614311Z","caller":"traceutil/trace.go:171","msg":"trace[855634813] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1364; }","duration":"370.710125ms","start":"2026-04-30T14:31:29.243589Z","end":"2026-04-30T14:31:29.614299Z","steps":["trace[855634813] 'agreement among raft nodes before linearized reading' (duration: 369.999905ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:31:29.614354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:31:29.243528Z","time spent":"370.814908ms","remote":"127.0.0.1:51224","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-30T14:31:29.613664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.34585988s","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-30T14:31:29.61444Z","caller":"traceutil/trace.go:171","msg":"trace[1399278131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1364; }","duration":"2.346644641s","start":"2026-04-30T14:31:27.267786Z","end":"2026-04-30T14:31:29.61443Z","steps":["trace[1399278131] 'agreement among raft nodes before linearized reading' (duration: 2.345812168s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:31:29.61447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:31:27.267781Z","time spent":"2.346680042s","remote":"127.0.0.1:51224","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-30T14:31:29.614419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:31:28.926649Z","time spent":"687.639578ms","remote":"127.0.0.1:50984","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.49\" "} {"level":"info","ts":"2026-04-30T14:31:29.614714Z","caller":"traceutil/trace.go:171","msg":"trace[388571403] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1364; }","duration":"2.343365718s","start":"2026-04-30T14:31:27.270953Z","end":"2026-04-30T14:31:29.614319Z","steps":["trace[388571403] 'agreement among raft nodes before linearized reading' (duration: 2.342623508s)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:31:29.614783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:31:27.270947Z","time spent":"2.343818781s","remote":"127.0.0.1:51224","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-30T14:31:29.781048Z","caller":"traceutil/trace.go:171","msg":"trace[381861328] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"162.848737ms","start":"2026-04-30T14:31:29.618179Z","end":"2026-04-30T14:31:29.781028Z","steps":["trace[381861328] 'process raft request' (duration: 162.582178ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:29.781309Z","caller":"traceutil/trace.go:171","msg":"trace[1683565618] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"162.766483ms","start":"2026-04-30T14:31:29.618534Z","end":"2026-04-30T14:31:29.781301Z","steps":["trace[1683565618] 'process raft request' (duration: 162.458315ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:29.781302Z","caller":"traceutil/trace.go:171","msg":"trace[1167013907] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"162.26137ms","start":"2026-04-30T14:31:29.619023Z","end":"2026-04-30T14:31:29.781284Z","steps":["trace[1167013907] 'process raft request' (duration: 162.208608ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:29.781354Z","caller":"traceutil/trace.go:171","msg":"trace[231794153] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"162.809655ms","start":"2026-04-30T14:31:29.618533Z","end":"2026-04-30T14:31:29.781343Z","steps":["trace[231794153] 'process raft request' (duration: 162.606429ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:29.78144Z","caller":"traceutil/trace.go:171","msg":"trace[1811724855] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"162.332611ms","start":"2026-04-30T14:31:29.619101Z","end":"2026-04-30T14:31:29.781433Z","steps":["trace[1811724855] 'process raft request' (duration: 162.156826ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:29.781541Z","caller":"traceutil/trace.go:171","msg":"trace[1832952709] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"161.700464ms","start":"2026-04-30T14:31:29.619832Z","end":"2026-04-30T14:31:29.781533Z","steps":["trace[1832952709] 'process raft request' (duration: 161.56927ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:29.78154Z","caller":"traceutil/trace.go:171","msg":"trace[1996091961] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"160.305124ms","start":"2026-04-30T14:31:29.621226Z","end":"2026-04-30T14:31:29.781531Z","steps":["trace[1996091961] 'process raft request' (duration: 160.259903ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:30.938014Z","caller":"traceutil/trace.go:171","msg":"trace[509566944] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"103.406395ms","start":"2026-04-30T14:31:30.834578Z","end":"2026-04-30T14:31:30.937984Z","steps":["trace[509566944] 'process raft request' (duration: 103.27028ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:31.753856Z","caller":"traceutil/trace.go:171","msg":"trace[126431338] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"133.842216ms","start":"2026-04-30T14:31:31.61999Z","end":"2026-04-30T14:31:31.753833Z","steps":["trace[126431338] 'process raft request' (duration: 101.899652ms)","trace[126431338] 'compare' (duration: 31.840191ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:31:34.051222Z","caller":"traceutil/trace.go:171","msg":"trace[141743386] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"205.386699ms","start":"2026-04-30T14:31:33.845793Z","end":"2026-04-30T14:31:34.05118Z","steps":["trace[141743386] 'process raft request' (duration: 107.944412ms)","trace[141743386] 'compare' (duration: 97.229411ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:31:34.052232Z","caller":"traceutil/trace.go:171","msg":"trace[1321478061] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"203.971699ms","start":"2026-04-30T14:31:33.847235Z","end":"2026-04-30T14:31:34.051207Z","steps":["trace[1321478061] 'process raft request' (duration: 203.928838ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:34.052666Z","caller":"traceutil/trace.go:171","msg":"trace[1589909417] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"206.076568ms","start":"2026-04-30T14:31:33.846529Z","end":"2026-04-30T14:31:34.052606Z","steps":["trace[1589909417] 'process raft request' (duration: 204.555846ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:34.052797Z","caller":"traceutil/trace.go:171","msg":"trace[1893168634] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"206.202302ms","start":"2026-04-30T14:31:33.846553Z","end":"2026-04-30T14:31:34.052755Z","steps":["trace[1893168634] 'process raft request' (duration: 204.576546ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:31:34.442433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.857427ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-30T14:31:34.93383Z","caller":"traceutil/trace.go:171","msg":"trace[2064154221] linearizableReadLoop","detail":"{readStateIndex:1445; appliedIndex:1443; }","duration":"103.668942ms","start":"2026-04-30T14:31:34.830136Z","end":"2026-04-30T14:31:34.933805Z","steps":["trace[2064154221] 'read index received' (duration: 8.84022ms)","trace[2064154221] 'applied index is now lower than readState.Index' (duration: 94.819402ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:31:34.933945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.790406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T14:31:34.933935Z","caller":"traceutil/trace.go:171","msg":"trace[998921117] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"144.100315ms","start":"2026-04-30T14:31:34.789805Z","end":"2026-04-30T14:31:34.933905Z","steps":["trace[998921117] 'process raft request' (duration: 98.29725ms)","trace[998921117] 'compare' (duration: 44.997583ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:31:34.933977Z","caller":"traceutil/trace.go:171","msg":"trace[1665643330] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1391; }","duration":"103.868298ms","start":"2026-04-30T14:31:34.830098Z","end":"2026-04-30T14:31:34.933966Z","steps":["trace[1665643330] 'agreement among raft nodes before linearized reading' (duration: 103.796136ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:35.649016Z","caller":"traceutil/trace.go:171","msg":"trace[1049218396] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"132.947159ms","start":"2026-04-30T14:31:35.516045Z","end":"2026-04-30T14:31:35.648992Z","steps":["trace[1049218396] 'process raft request' (duration: 132.782384ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:35.855561Z","caller":"traceutil/trace.go:171","msg":"trace[463370992] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"158.901024ms","start":"2026-04-30T14:31:35.696599Z","end":"2026-04-30T14:31:35.8555Z","steps":["trace[463370992] 'process raft request' (duration: 70.3876ms)","trace[463370992] 'compare' (duration: 88.257677ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:31:36.03481Z","caller":"traceutil/trace.go:171","msg":"trace[178980661] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"110.733402ms","start":"2026-04-30T14:31:35.924018Z","end":"2026-04-30T14:31:36.034752Z","steps":["trace[178980661] 'process raft request' (duration: 64.627178ms)","trace[178980661] 'compare' (duration: 45.883368ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:31:36.345867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.319033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T14:31:36.346204Z","caller":"traceutil/trace.go:171","msg":"trace[772238648] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"227.874094ms","start":"2026-04-30T14:31:36.118308Z","end":"2026-04-30T14:31:36.346183Z","steps":["trace[772238648] 'process raft request' (duration: 227.830283ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:36.346351Z","caller":"traceutil/trace.go:171","msg":"trace[1194918526] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"228.203484ms","start":"2026-04-30T14:31:36.118136Z","end":"2026-04-30T14:31:36.346339Z","steps":["trace[1194918526] 'process raft request' (duration: 227.871264ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:36.346432Z","caller":"traceutil/trace.go:171","msg":"trace[2031740609] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"228.209034ms","start":"2026-04-30T14:31:36.118214Z","end":"2026-04-30T14:31:36.346423Z","steps":["trace[2031740609] 'process raft request' (duration: 227.887645ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:31:36.346553Z","caller":"traceutil/trace.go:171","msg":"trace[637283761] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"228.589874ms","start":"2026-04-30T14:31:36.11795Z","end":"2026-04-30T14:31:36.34654Z","steps":["trace[637283761] 'process raft request' (duration: 60.5004ms)","trace[637283761] 'compare' (duration: 167.055825ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:33:45.128403Z","caller":"traceutil/trace.go:171","msg":"trace[1577922240] linearizableReadLoop","detail":"{readStateIndex:2065; appliedIndex:2064; }","duration":"110.851443ms","start":"2026-04-30T14:33:45.017524Z","end":"2026-04-30T14:33:45.128375Z","steps":["trace[1577922240] 'read index received' (duration: 110.649298ms)","trace[1577922240] 'applied index is now lower than readState.Index' (duration: 201.135µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:33:45.128494Z","caller":"traceutil/trace.go:171","msg":"trace[1703926334] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"112.641773ms","start":"2026-04-30T14:33:45.01583Z","end":"2026-04-30T14:33:45.128472Z","steps":["trace[1703926334] 'process raft request' (duration: 112.376896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:33:45.128688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.147421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-30T14:33:45.128724Z","caller":"traceutil/trace.go:171","msg":"trace[1536241056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1985; }","duration":"111.204593ms","start":"2026-04-30T14:33:45.017509Z","end":"2026-04-30T14:33:45.128713Z","steps":["trace[1536241056] 'agreement among raft nodes before linearized reading' (duration: 111.019398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:33:45.128788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.151142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-30T14:33:45.128908Z","caller":"traceutil/trace.go:171","msg":"trace[1443965748] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1985; }","duration":"111.334286ms","start":"2026-04-30T14:33:45.017546Z","end":"2026-04-30T14:33:45.12888Z","steps":["trace[1443965748] 'agreement among raft nodes before linearized reading' (duration: 111.10641ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:33:45.129027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.504474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-04-30T14:33:45.129095Z","caller":"traceutil/trace.go:171","msg":"trace[1794398449] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1985; }","duration":"109.592307ms","start":"2026-04-30T14:33:45.019489Z","end":"2026-04-30T14:33:45.129081Z","steps":["trace[1794398449] 'agreement among raft nodes before linearized reading' (duration: 109.448923ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:34:12.775228Z","caller":"traceutil/trace.go:171","msg":"trace[1752885973] linearizableReadLoop","detail":"{readStateIndex:2196; appliedIndex:2195; }","duration":"252.903089ms","start":"2026-04-30T14:34:12.522305Z","end":"2026-04-30T14:34:12.775208Z","steps":["trace[1752885973] 'read index received' (duration: 252.733364ms)","trace[1752885973] 'applied index is now lower than readState.Index' (duration: 168.905µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:34:12.775332Z","caller":"traceutil/trace.go:171","msg":"trace[1209589581] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"301.60763ms","start":"2026-04-30T14:34:12.473714Z","end":"2026-04-30T14:34:12.775322Z","steps":["trace[1209589581] 'process raft request' (duration: 301.369154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:34:12.77542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:34:12.473685Z","time spent":"301.669682ms","remote":"127.0.0.1:51224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T14:34:12.775517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.05426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-30T14:34:12.775614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.30615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-04-30T14:34:12.775625Z","caller":"traceutil/trace.go:171","msg":"trace[1964811837] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2111; }","duration":"249.200764ms","start":"2026-04-30T14:34:12.526401Z","end":"2026-04-30T14:34:12.775602Z","steps":["trace[1964811837] 'agreement among raft nodes before linearized reading' (duration: 249.000198ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:34:12.775666Z","caller":"traceutil/trace.go:171","msg":"trace[789083389] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2111; }","duration":"253.380532ms","start":"2026-04-30T14:34:12.522261Z","end":"2026-04-30T14:34:12.775641Z","steps":["trace[789083389] 'agreement among raft nodes before linearized reading' (duration: 253.262689ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:34:12.775896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.842981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-30T14:34:12.775982Z","caller":"traceutil/trace.go:171","msg":"trace[1831586343] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2111; }","duration":"165.968944ms","start":"2026-04-30T14:34:12.609993Z","end":"2026-04-30T14:34:12.775962Z","steps":["trace[1831586343] 'agreement among raft nodes before linearized reading' (duration: 165.692756ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:34:14.887629Z","caller":"traceutil/trace.go:171","msg":"trace[751378646] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"107.548403ms","start":"2026-04-30T14:34:14.780061Z","end":"2026-04-30T14:34:14.887609Z","steps":["trace[751378646] 'process raft request' (duration: 107.402969ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:34:37.367742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.8025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:128451"} {"level":"warn","ts":"2026-04-30T14:34:37.367872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.959726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:5 size:6181"} {"level":"info","ts":"2026-04-30T14:34:37.367898Z","caller":"traceutil/trace.go:171","msg":"trace[1306946205] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:2248; }","duration":"126.013146ms","start":"2026-04-30T14:34:37.241862Z","end":"2026-04-30T14:34:37.367875Z","steps":["trace[1306946205] 'range keys from in-memory index tree' (duration: 125.579774ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:34:37.367941Z","caller":"traceutil/trace.go:171","msg":"trace[390186657] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:5; response_revision:2248; }","duration":"125.054888ms","start":"2026-04-30T14:34:37.24287Z","end":"2026-04-30T14:34:37.367925Z","steps":["trace[390186657] 'range keys from in-memory index tree' (duration: 124.785101ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:34:51.726676Z","caller":"traceutil/trace.go:171","msg":"trace[1611033628] linearizableReadLoop","detail":"{readStateIndex:2421; appliedIndex:2420; }","duration":"127.651981ms","start":"2026-04-30T14:34:51.599002Z","end":"2026-04-30T14:34:51.726654Z","steps":["trace[1611033628] 'read index received' (duration: 127.499157ms)","trace[1611033628] 'applied index is now lower than readState.Index' (duration: 151.774µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:34:51.726768Z","caller":"traceutil/trace.go:171","msg":"trace[46821844] transaction","detail":"{read_only:false; response_revision:2327; number_of_response:1; }","duration":"139.191848ms","start":"2026-04-30T14:34:51.587514Z","end":"2026-04-30T14:34:51.726706Z","steps":["trace[46821844] 'process raft request' (duration: 138.987162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:34:51.726944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.914848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role\" ","response":"range_response_count:1 size:1944"} {"level":"info","ts":"2026-04-30T14:34:51.727023Z","caller":"traceutil/trace.go:171","msg":"trace[1769577141] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-envoy-gateway-role; range_end:; response_count:1; response_revision:2327; }","duration":"128.032212ms","start":"2026-04-30T14:34:51.598969Z","end":"2026-04-30T14:34:51.727002Z","steps":["trace[1769577141] 'agreement among raft nodes before linearized reading' (duration: 127.778805ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:34:53.061787Z","caller":"traceutil/trace.go:171","msg":"trace[943994475] transaction","detail":"{read_only:false; response_revision:2332; number_of_response:1; }","duration":"104.072734ms","start":"2026-04-30T14:34:52.957676Z","end":"2026-04-30T14:34:53.061748Z","steps":["trace[943994475] 'process raft request' (duration: 103.904639ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:34:55.532488Z","caller":"traceutil/trace.go:171","msg":"trace[1110529170] linearizableReadLoop","detail":"{readStateIndex:2437; appliedIndex:2436; }","duration":"113.891973ms","start":"2026-04-30T14:34:55.418559Z","end":"2026-04-30T14:34:55.532451Z","steps":["trace[1110529170] 'read index received' (duration: 28.719763ms)","trace[1110529170] 'applied index is now lower than readState.Index' (duration: 85.17084ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:34:55.532595Z","caller":"traceutil/trace.go:171","msg":"trace[1848353177] transaction","detail":"{read_only:false; response_revision:2342; number_of_response:1; }","duration":"122.760944ms","start":"2026-04-30T14:34:55.409789Z","end":"2026-04-30T14:34:55.53255Z","steps":["trace[1848353177] 'process raft request' (duration: 37.481751ms)","trace[1848353177] 'compare' (duration: 84.707117ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:34:55.53286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.273444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" ","response":"range_response_count:1 size:841"} {"level":"info","ts":"2026-04-30T14:34:55.532934Z","caller":"traceutil/trace.go:171","msg":"trace[1592241888] range","detail":"{range_begin:/registry/clusterroles/system:controller:replicaset-controller; range_end:; response_count:1; response_revision:2342; }","duration":"114.371476ms","start":"2026-04-30T14:34:55.418542Z","end":"2026-04-30T14:34:55.532914Z","steps":["trace[1592241888] 'agreement among raft nodes before linearized reading' (duration: 114.039997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:34:55.53298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.525563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:kubelet-bootstrap\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-30T14:34:55.533031Z","caller":"traceutil/trace.go:171","msg":"trace[1867741415] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:kubelet-bootstrap; range_end:; response_count:1; response_revision:2342; }","duration":"112.599196ms","start":"2026-04-30T14:34:55.420419Z","end":"2026-04-30T14:34:55.533018Z","steps":["trace[1867741415] 'agreement among raft nodes before linearized reading' (duration: 112.455282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:34:55.752145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.649504ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T14:34:55.752301Z","caller":"traceutil/trace.go:171","msg":"trace[1870199807] linearizableReadLoop","detail":"{readStateIndex:2439; appliedIndex:2437; }","duration":"142.568684ms","start":"2026-04-30T14:34:55.609717Z","end":"2026-04-30T14:34:55.752286Z","steps":["trace[1870199807] 'read index received' (duration: 39.651553ms)","trace[1870199807] 'applied index is now lower than readState.Index' (duration: 102.916331ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:34:55.752393Z","caller":"traceutil/trace.go:171","msg":"trace[1764323714] transaction","detail":"{read_only:false; response_revision:2344; number_of_response:1; }","duration":"214.478268ms","start":"2026-04-30T14:34:55.537905Z","end":"2026-04-30T14:34:55.752384Z","steps":["trace[1764323714] 'process raft request' (duration: 214.334604ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:34:55.75244Z","caller":"traceutil/trace.go:171","msg":"trace[895087905] transaction","detail":"{read_only:false; response_revision:2343; number_of_response:1; }","duration":"215.826927ms","start":"2026-04-30T14:34:55.536592Z","end":"2026-04-30T14:34:55.752419Z","steps":["trace[895087905] 'process raft request' (duration: 112.796963ms)","trace[895087905] 'compare' (duration: 102.53654ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:34:55.752485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.057333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:replicaset-controller\" ","response":"range_response_count:1 size:841"} {"level":"info","ts":"2026-04-30T14:34:55.752546Z","caller":"traceutil/trace.go:171","msg":"trace[109273386] range","detail":"{range_begin:/registry/clusterroles/system:controller:replicaset-controller; range_end:; response_count:1; response_revision:2344; }","duration":"140.130545ms","start":"2026-04-30T14:34:55.612399Z","end":"2026-04-30T14:34:55.75253Z","steps":["trace[109273386] 'agreement among raft nodes before linearized reading' (duration: 140.014302ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:34:55.752569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.805921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:kubelet-bootstrap\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-30T14:34:55.752716Z","caller":"traceutil/trace.go:171","msg":"trace[98493408] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:kubelet-bootstrap; range_end:; response_count:1; response_revision:2344; }","duration":"142.974866ms","start":"2026-04-30T14:34:55.609684Z","end":"2026-04-30T14:34:55.752658Z","steps":["trace[98493408] 'agreement among raft nodes before linearized reading' (duration: 142.742399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:34:56.274391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.836665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-30T14:34:56.274472Z","caller":"traceutil/trace.go:171","msg":"trace[2022915999] transaction","detail":"{read_only:false; response_revision:2348; number_of_response:1; }","duration":"180.820057ms","start":"2026-04-30T14:34:56.093641Z","end":"2026-04-30T14:34:56.274461Z","steps":["trace[2022915999] 'process raft request' (duration: 62.86158ms)","trace[2022915999] 'compare' (duration: 117.741391ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:34:56.332039Z","caller":"traceutil/trace.go:171","msg":"trace[2035249381] linearizableReadLoop","detail":"{readStateIndex:2444; appliedIndex:2442; }","duration":"181.066222ms","start":"2026-04-30T14:34:56.150953Z","end":"2026-04-30T14:34:56.332019Z","steps":["trace[2035249381] 'read index received' (duration: 5.539856ms)","trace[2035249381] 'applied index is now lower than readState.Index' (duration: 175.525646ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:34:56.332152Z","caller":"traceutil/trace.go:171","msg":"trace[453512349] transaction","detail":"{read_only:false; response_revision:2349; number_of_response:1; }","duration":"202.441248ms","start":"2026-04-30T14:34:56.129703Z","end":"2026-04-30T14:34:56.332144Z","steps":["trace[453512349] 'process raft request' (duration: 202.190011ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:34:56.332363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.298583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:1 size:672"} {"level":"warn","ts":"2026-04-30T14:34:56.332368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.402062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/kubeadm:node-proxier\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-30T14:34:56.332449Z","caller":"traceutil/trace.go:171","msg":"trace[915652848] range","detail":"{range_begin:/registry/clusterroles/system:controller:root-ca-cert-publisher; range_end:; response_count:1; response_revision:2349; }","duration":"173.422467ms","start":"2026-04-30T14:34:56.159005Z","end":"2026-04-30T14:34:56.332427Z","steps":["trace[915652848] 'agreement among raft nodes before linearized reading' (duration: 173.265062ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:34:56.332483Z","caller":"traceutil/trace.go:171","msg":"trace[764748477] range","detail":"{range_begin:/registry/clusterrolebindings/kubeadm:node-proxier; range_end:; response_count:1; response_revision:2349; }","duration":"181.554517ms","start":"2026-04-30T14:34:56.150908Z","end":"2026-04-30T14:34:56.332463Z","steps":["trace[764748477] 'agreement among raft nodes before linearized reading' (duration: 181.374592ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:34:57.929312Z","caller":"traceutil/trace.go:171","msg":"trace[527169711] linearizableReadLoop","detail":"{readStateIndex:2449; appliedIndex:2448; }","duration":"102.449748ms","start":"2026-04-30T14:34:57.826838Z","end":"2026-04-30T14:34:57.929288Z","steps":["trace[527169711] 'read index received' (duration: 102.227642ms)","trace[527169711] 'applied index is now lower than readState.Index' (duration: 221.076µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:34:57.92937Z","caller":"traceutil/trace.go:171","msg":"trace[2142191419] transaction","detail":"{read_only:false; response_revision:2354; number_of_response:1; }","duration":"127.104466ms","start":"2026-04-30T14:34:57.802236Z","end":"2026-04-30T14:34:57.929341Z","steps":["trace[2142191419] 'process raft request' (duration: 126.862489ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:34:57.929531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.665794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T14:34:57.929528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.662174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-04-30T14:34:57.929613Z","caller":"traceutil/trace.go:171","msg":"trace[453227868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2354; }","duration":"102.790678ms","start":"2026-04-30T14:34:57.826802Z","end":"2026-04-30T14:34:57.929593Z","steps":["trace[453227868] 'agreement among raft nodes before linearized reading' (duration: 102.643334ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:34:57.929624Z","caller":"traceutil/trace.go:171","msg":"trace[828171370] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:2354; }","duration":"102.788278ms","start":"2026-04-30T14:34:57.826809Z","end":"2026-04-30T14:34:57.929597Z","steps":["trace[828171370] 'agreement among raft nodes before linearized reading' (duration: 102.656934ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:34:58.295227Z","caller":"traceutil/trace.go:171","msg":"trace[759533918] transaction","detail":"{read_only:false; response_revision:2357; number_of_response:1; }","duration":"219.16557ms","start":"2026-04-30T14:34:58.076027Z","end":"2026-04-30T14:34:58.295193Z","steps":["trace[759533918] 'process raft request' (duration: 137.118509ms)","trace[759533918] 'compare' (duration: 81.903957ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:34:58.295418Z","caller":"traceutil/trace.go:171","msg":"trace[1445080592] linearizableReadLoop","detail":"{readStateIndex:2452; appliedIndex:2450; }","duration":"218.174983ms","start":"2026-04-30T14:34:58.077228Z","end":"2026-04-30T14:34:58.295403Z","steps":["trace[1445080592] 'read index received' (duration: 64.612938ms)","trace[1445080592] 'applied index is now lower than readState.Index' (duration: 153.560675ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:34:58.295684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.43268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:endpoint-controller\" ","response":"range_response_count:1 size:751"} {"level":"info","ts":"2026-04-30T14:34:58.295766Z","caller":"traceutil/trace.go:171","msg":"trace[793057219] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:endpoint-controller; range_end:; response_count:1; response_revision:2357; }","duration":"218.522393ms","start":"2026-04-30T14:34:58.077223Z","end":"2026-04-30T14:34:58.295745Z","steps":["trace[793057219] 'agreement among raft nodes before linearized reading' (duration: 218.278086ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:34:58.295803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.802044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:1 size:647"} {"level":"info","ts":"2026-04-30T14:34:58.295867Z","caller":"traceutil/trace.go:171","msg":"trace[1149542803] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:1; response_revision:2357; }","duration":"158.052332ms","start":"2026-04-30T14:34:58.137798Z","end":"2026-04-30T14:34:58.295851Z","steps":["trace[1149542803] 'agreement among raft nodes before linearized reading' (duration: 157.771844ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:34:59.421997Z","caller":"traceutil/trace.go:171","msg":"trace[2141054500] linearizableReadLoop","detail":"{readStateIndex:2456; appliedIndex:2455; }","duration":"129.184675ms","start":"2026-04-30T14:34:59.292753Z","end":"2026-04-30T14:34:59.421938Z","steps":["trace[2141054500] 'read index received' (duration: 129.038891ms)","trace[2141054500] 'applied index is now lower than readState.Index' (duration: 144.874µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:34:59.422069Z","caller":"traceutil/trace.go:171","msg":"trace[823774532] transaction","detail":"{read_only:false; response_revision:2360; number_of_response:1; }","duration":"172.83978ms","start":"2026-04-30T14:34:59.249194Z","end":"2026-04-30T14:34:59.422034Z","steps":["trace[823774532] 'process raft request' (duration: 172.599883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:34:59.422194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.116437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:monitoring\" ","response":"range_response_count:1 size:634"} {"level":"warn","ts":"2026-04-30T14:34:59.422218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.454692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:generic-garbage-collector\" ","response":"range_response_count:1 size:775"} {"level":"info","ts":"2026-04-30T14:34:59.422294Z","caller":"traceutil/trace.go:171","msg":"trace[657565546] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:generic-garbage-collector; range_end:; response_count:1; response_revision:2360; }","duration":"129.583877ms","start":"2026-04-30T14:34:59.292694Z","end":"2026-04-30T14:34:59.422278Z","steps":["trace[657565546] 'agreement among raft nodes before linearized reading' (duration: 129.395762ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:34:59.422237Z","caller":"traceutil/trace.go:171","msg":"trace[2102061972] range","detail":"{range_begin:/registry/clusterroles/system:monitoring; range_end:; response_count:1; response_revision:2360; }","duration":"127.172068ms","start":"2026-04-30T14:34:59.295054Z","end":"2026-04-30T14:34:59.422226Z","steps":["trace[2102061972] 'agreement among raft nodes before linearized reading' (duration: 127.046515ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:00.324345Z","caller":"traceutil/trace.go:171","msg":"trace[283801459] linearizableReadLoop","detail":"{readStateIndex:2462; appliedIndex:2461; }","duration":"136.527663ms","start":"2026-04-30T14:35:00.187793Z","end":"2026-04-30T14:35:00.32432Z","steps":["trace[283801459] 'read index received' (duration: 71.150503ms)","trace[283801459] 'applied index is now lower than readState.Index' (duration: 65.37557ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:35:00.324552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.746159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-bootstrapper\" ","response":"range_response_count:1 size:633"} {"level":"info","ts":"2026-04-30T14:35:00.324628Z","caller":"traceutil/trace.go:171","msg":"trace[1467400676] range","detail":"{range_begin:/registry/clusterroles/system:node-bootstrapper; range_end:; response_count:1; response_revision:2366; }","duration":"136.831031ms","start":"2026-04-30T14:35:00.187781Z","end":"2026-04-30T14:35:00.324612Z","steps":["trace[1467400676] 'agreement among raft nodes before linearized reading' (duration: 136.676317ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:00.324811Z","caller":"traceutil/trace.go:171","msg":"trace[913025430] transaction","detail":"{read_only:false; response_revision:2366; number_of_response:1; }","duration":"176.163684ms","start":"2026-04-30T14:35:00.148614Z","end":"2026-04-30T14:35:00.324778Z","steps":["trace[913025430] 'process raft request' (duration: 110.381323ms)","trace[913025430] 'compare' (duration: 65.109362ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:35:00.324933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.371677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:pod-garbage-collector\" ","response":"range_response_count:1 size:759"} {"level":"info","ts":"2026-04-30T14:35:00.325001Z","caller":"traceutil/trace.go:171","msg":"trace[75981329] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:pod-garbage-collector; range_end:; response_count:1; response_revision:2366; }","duration":"131.46748ms","start":"2026-04-30T14:35:00.193517Z","end":"2026-04-30T14:35:00.324985Z","steps":["trace[75981329] 'agreement among raft nodes before linearized reading' (duration: 131.348097ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:01.707299Z","caller":"traceutil/trace.go:171","msg":"trace[156221143] transaction","detail":"{read_only:false; response_revision:2372; number_of_response:1; }","duration":"100.400991ms","start":"2026-04-30T14:35:01.606871Z","end":"2026-04-30T14:35:01.707272Z","steps":["trace[156221143] 'process raft request' (duration: 100.232587ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:03.451709Z","caller":"traceutil/trace.go:171","msg":"trace[976342216] linearizableReadLoop","detail":"{readStateIndex:2474; appliedIndex:2473; }","duration":"123.297748ms","start":"2026-04-30T14:35:03.328389Z","end":"2026-04-30T14:35:03.451687Z","steps":["trace[976342216] 'read index received' (duration: 123.105263ms)","trace[976342216] 'applied index is now lower than readState.Index' (duration: 191.605µs)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:35:03.451877Z","caller":"traceutil/trace.go:171","msg":"trace[231332122] transaction","detail":"{read_only:false; response_revision:2378; number_of_response:1; }","duration":"148.169522ms","start":"2026-04-30T14:35:03.303635Z","end":"2026-04-30T14:35:03.451805Z","steps":["trace[231332122] 'process raft request' (duration: 147.902445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:35:03.452167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.507333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" ","response":"range_response_count:1 size:363"} {"level":"warn","ts":"2026-04-30T14:35:03.452167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.71768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/envoy-gateway-system\" ","response":"range_response_count:1 size:412"} {"level":"info","ts":"2026-04-30T14:35:03.452266Z","caller":"traceutil/trace.go:171","msg":"trace[1952815959] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:1; response_revision:2378; }","duration":"106.690009ms","start":"2026-04-30T14:35:03.345563Z","end":"2026-04-30T14:35:03.452253Z","steps":["trace[1952815959] 'agreement among raft nodes before linearized reading' (duration: 106.482093ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:03.452297Z","caller":"traceutil/trace.go:171","msg":"trace[1801084492] range","detail":"{range_begin:/registry/namespaces/envoy-gateway-system; range_end:; response_count:1; response_revision:2378; }","duration":"123.932566ms","start":"2026-04-30T14:35:03.328347Z","end":"2026-04-30T14:35:03.452279Z","steps":["trace[1801084492] 'agreement among raft nodes before linearized reading' (duration: 123.495014ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:03.621785Z","caller":"traceutil/trace.go:171","msg":"trace[992020438] transaction","detail":"{read_only:false; response_revision:2379; number_of_response:1; }","duration":"156.23975ms","start":"2026-04-30T14:35:03.465525Z","end":"2026-04-30T14:35:03.621765Z","steps":["trace[992020438] 'process raft request' (duration: 57.021133ms)","trace[992020438] 'compare' (duration: 99.082833ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:35:04.274308Z","caller":"traceutil/trace.go:171","msg":"trace[165064523] transaction","detail":"{read_only:false; response_revision:2383; number_of_response:1; }","duration":"129.394421ms","start":"2026-04-30T14:35:04.144877Z","end":"2026-04-30T14:35:04.274272Z","steps":["trace[165064523] 'process raft request' (duration: 97.330234ms)","trace[165064523] 'compare' (duration: 31.885892ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:35:05.968479Z","caller":"traceutil/trace.go:171","msg":"trace[88721527] transaction","detail":"{read_only:false; response_revision:2389; number_of_response:1; }","duration":"164.620578ms","start":"2026-04-30T14:35:05.80383Z","end":"2026-04-30T14:35:05.968451Z","steps":["trace[88721527] 'process raft request' (duration: 131.236663ms)","trace[88721527] 'compare' (duration: 33.21739ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:35:05.968679Z","caller":"traceutil/trace.go:171","msg":"trace[2072211418] transaction","detail":"{read_only:false; response_revision:2390; number_of_response:1; }","duration":"164.798793ms","start":"2026-04-30T14:35:05.803859Z","end":"2026-04-30T14:35:05.968658Z","steps":["trace[2072211418] 'process raft request' (duration: 164.545356ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:05.968713Z","caller":"traceutil/trace.go:171","msg":"trace[1604776980] linearizableReadLoop","detail":"{readStateIndex:2487; appliedIndex:2485; }","duration":"164.813694ms","start":"2026-04-30T14:35:05.803878Z","end":"2026-04-30T14:35:05.968692Z","steps":["trace[1604776980] 'read index received' (duration: 34.573009ms)","trace[1604776980] 'applied index is now lower than readState.Index' (duration: 130.189263ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:35:05.968891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.37373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-30T14:35:05.968902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.985788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager\" ","response":"range_response_count:1 size:1111"} {"level":"info","ts":"2026-04-30T14:35:05.968968Z","caller":"traceutil/trace.go:171","msg":"trace[67436458] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-infra-manager; range_end:; response_count:1; response_revision:2390; }","duration":"165.112552ms","start":"2026-04-30T14:35:05.803836Z","end":"2026-04-30T14:35:05.968949Z","steps":["trace[67436458] 'agreement among raft nodes before linearized reading' (duration: 164.887646ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:05.968922Z","caller":"traceutil/trace.go:171","msg":"trace[1431266182] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2390; }","duration":"141.456142ms","start":"2026-04-30T14:35:05.827457Z","end":"2026-04-30T14:35:05.968913Z","steps":["trace[1431266182] 'agreement among raft nodes before linearized reading' (duration: 141.3825ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:06.679909Z","caller":"traceutil/trace.go:171","msg":"trace[1304020556] transaction","detail":"{read_only:false; response_revision:2394; number_of_response:1; }","duration":"126.042116ms","start":"2026-04-30T14:35:06.55384Z","end":"2026-04-30T14:35:06.679882Z","steps":["trace[1304020556] 'process raft request' (duration: 125.856851ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:06.715942Z","caller":"traceutil/trace.go:171","msg":"trace[1804168781] linearizableReadLoop","detail":"{readStateIndex:2492; appliedIndex:2490; }","duration":"142.550433ms","start":"2026-04-30T14:35:06.573369Z","end":"2026-04-30T14:35:06.715919Z","steps":["trace[1804168781] 'read index received' (duration: 106.457112ms)","trace[1804168781] 'applied index is now lower than readState.Index' (duration: 36.090631ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:35:06.716056Z","caller":"traceutil/trace.go:171","msg":"trace[1134504472] transaction","detail":"{read_only:false; response_revision:2395; number_of_response:1; }","duration":"156.666753ms","start":"2026-04-30T14:35:06.559378Z","end":"2026-04-30T14:35:06.716045Z","steps":["trace[1134504472] 'process raft request' (duration: 156.401455ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:35:06.716225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.826802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/kube-node-lease/\" range_end:\"/registry/ingress/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T14:35:06.716338Z","caller":"traceutil/trace.go:171","msg":"trace[1868001551] range","detail":"{range_begin:/registry/ingress/kube-node-lease/; range_end:/registry/ingress/kube-node-lease0; response_count:0; response_revision:2395; }","duration":"142.976775ms","start":"2026-04-30T14:35:06.573344Z","end":"2026-04-30T14:35:06.716321Z","steps":["trace[1868001551] 'agreement among raft nodes before linearized reading' (duration: 142.814461ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:35:06.716659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.934129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/kube-node-lease/\" range_end:\"/registry/services/endpoints/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T14:35:06.716758Z","caller":"traceutil/trace.go:171","msg":"trace[1574044194] range","detail":"{range_begin:/registry/services/endpoints/kube-node-lease/; range_end:/registry/services/endpoints/kube-node-lease0; response_count:0; response_revision:2395; }","duration":"134.040623ms","start":"2026-04-30T14:35:06.582702Z","end":"2026-04-30T14:35:06.716742Z","steps":["trace[1574044194] 'agreement among raft nodes before linearized reading' (duration: 133.92129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:35:06.716989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.927471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:4641"} {"level":"info","ts":"2026-04-30T14:35:06.717074Z","caller":"traceutil/trace.go:171","msg":"trace[1123391001] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:2395; }","duration":"133.078265ms","start":"2026-04-30T14:35:06.583974Z","end":"2026-04-30T14:35:06.717052Z","steps":["trace[1123391001] 'agreement among raft nodes before linearized reading' (duration: 132.883189ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:08.071486Z","caller":"traceutil/trace.go:171","msg":"trace[32184246] linearizableReadLoop","detail":"{readStateIndex:2496; appliedIndex:2495; }","duration":"118.491143ms","start":"2026-04-30T14:35:07.952972Z","end":"2026-04-30T14:35:08.071463Z","steps":["trace[32184246] 'read index received' (duration: 35.941367ms)","trace[32184246] 'applied index is now lower than readState.Index' (duration: 82.548736ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:35:08.071748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.759581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"info","ts":"2026-04-30T14:35:08.071829Z","caller":"traceutil/trace.go:171","msg":"trace[1545165426] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:2399; }","duration":"118.888545ms","start":"2026-04-30T14:35:07.952924Z","end":"2026-04-30T14:35:08.071812Z","steps":["trace[1545165426] 'agreement among raft nodes before linearized reading' (duration: 118.70904ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:35:08.071872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.689059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/kubernetes\" ","response":"range_response_count:1 size:704"} {"level":"warn","ts":"2026-04-30T14:35:08.071876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.763418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-public/\" range_end:\"/registry/persistentvolumeclaims/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T14:35:08.071918Z","caller":"traceutil/trace.go:171","msg":"trace[187355726] range","detail":"{range_begin:/registry/services/specs/default/kubernetes; range_end:; response_count:1; response_revision:2399; }","duration":"100.750441ms","start":"2026-04-30T14:35:07.971158Z","end":"2026-04-30T14:35:08.071908Z","steps":["trace[187355726] 'agreement among raft nodes before linearized reading' (duration: 100.664918ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:08.071924Z","caller":"traceutil/trace.go:171","msg":"trace[881332629] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-public/; range_end:/registry/persistentvolumeclaims/kube-public0; response_count:0; response_revision:2399; }","duration":"102.83119ms","start":"2026-04-30T14:35:07.969081Z","end":"2026-04-30T14:35:08.071913Z","steps":["trace[881332629] 'agreement among raft nodes before linearized reading' (duration: 102.776488ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:08.071763Z","caller":"traceutil/trace.go:171","msg":"trace[1631921207] transaction","detail":"{read_only:false; response_revision:2399; number_of_response:1; }","duration":"195.836731ms","start":"2026-04-30T14:35:07.875901Z","end":"2026-04-30T14:35:08.071738Z","steps":["trace[1631921207] 'process raft request' (duration: 113.057888ms)","trace[1631921207] 'compare' (duration: 82.389132ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:35:08.07183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.09956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/kube-system/\" range_end:\"/registry/cronjobs/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T14:35:08.072191Z","caller":"traceutil/trace.go:171","msg":"trace[1152050254] range","detail":"{range_begin:/registry/cronjobs/kube-system/; range_end:/registry/cronjobs/kube-system0; response_count:0; response_revision:2399; }","duration":"108.4839ms","start":"2026-04-30T14:35:07.963675Z","end":"2026-04-30T14:35:08.072159Z","steps":["trace[1152050254] 'agreement among raft nodes before linearized reading' (duration: 108.101879ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:08.442929Z","caller":"traceutil/trace.go:171","msg":"trace[1010580301] linearizableReadLoop","detail":"{readStateIndex:2498; appliedIndex:2497; }","duration":"126.684124ms","start":"2026-04-30T14:35:08.316219Z","end":"2026-04-30T14:35:08.442903Z","steps":["trace[1010580301] 'read index received' (duration: 47.671529ms)","trace[1010580301] 'applied index is now lower than readState.Index' (duration: 79.011195ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:35:08.443129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.500533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-public/\" range_end:\"/registry/roles/kube-public0\" limit:500 ","response":"range_response_count:2 size:1073"} {"level":"warn","ts":"2026-04-30T14:35:08.443107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.862969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-public/\" range_end:\"/registry/events/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T14:35:08.443169Z","caller":"traceutil/trace.go:171","msg":"trace[188559127] range","detail":"{range_begin:/registry/roles/kube-public/; range_end:/registry/roles/kube-public0; response_count:2; response_revision:2401; }","duration":"106.583475ms","start":"2026-04-30T14:35:08.336576Z","end":"2026-04-30T14:35:08.443159Z","steps":["trace[188559127] 'agreement among raft nodes before linearized reading' (duration: 106.472632ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:08.443185Z","caller":"traceutil/trace.go:171","msg":"trace[1395277746] range","detail":"{range_begin:/registry/events/kube-public/; range_end:/registry/events/kube-public0; response_count:0; response_revision:2401; }","duration":"126.974582ms","start":"2026-04-30T14:35:08.316198Z","end":"2026-04-30T14:35:08.443172Z","steps":["trace[1395277746] 'agreement among raft nodes before linearized reading' (duration: 126.834339ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:08.443554Z","caller":"traceutil/trace.go:171","msg":"trace[1216458984] transaction","detail":"{read_only:false; response_revision:2401; number_of_response:1; }","duration":"130.617225ms","start":"2026-04-30T14:35:08.312361Z","end":"2026-04-30T14:35:08.442978Z","steps":["trace[1216458984] 'process raft request' (duration: 51.510797ms)","trace[1216458984] 'compare' (duration: 78.880572ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:35:09.112815Z","caller":"traceutil/trace.go:171","msg":"trace[1804420991] linearizableReadLoop","detail":"{readStateIndex:2502; appliedIndex:2501; }","duration":"163.769154ms","start":"2026-04-30T14:35:08.949023Z","end":"2026-04-30T14:35:09.112792Z","steps":["trace[1804420991] 'read index received' (duration: 163.511705ms)","trace[1804420991] 'applied index is now lower than readState.Index' (duration: 255.919µs)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:35:09.113124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.064882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-csi-encryption-kms-config\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-04-30T14:35:09.11366Z","caller":"traceutil/trace.go:171","msg":"trace[954532564] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-csi-encryption-kms-config; range_end:; response_count:1; response_revision:2404; }","duration":"164.636708ms","start":"2026-04-30T14:35:08.949008Z","end":"2026-04-30T14:35:09.113645Z","steps":["trace[954532564] 'agreement among raft nodes before linearized reading' (duration: 163.909088ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:35:09.113547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.799733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/kube-system/\" range_end:\"/registry/persistentvolumeclaims/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-30T14:35:09.113869Z","caller":"traceutil/trace.go:171","msg":"trace[21328922] range","detail":"{range_begin:/registry/persistentvolumeclaims/kube-system/; range_end:/registry/persistentvolumeclaims/kube-system0; response_count:0; response_revision:2404; }","duration":"159.170804ms","start":"2026-04-30T14:35:08.954671Z","end":"2026-04-30T14:35:09.113842Z","steps":["trace[21328922] 'agreement among raft nodes before linearized reading' (duration: 158.813984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:35:09.113577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.674794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-30T14:35:09.114011Z","caller":"traceutil/trace.go:171","msg":"trace[1300598121] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:2404; }","duration":"139.138507ms","start":"2026-04-30T14:35:08.974855Z","end":"2026-04-30T14:35:09.113994Z","steps":["trace[1300598121] 'agreement among raft nodes before linearized reading' (duration: 138.645173ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:09.453362Z","caller":"traceutil/trace.go:171","msg":"trace[760904828] linearizableReadLoop","detail":"{readStateIndex:2503; appliedIndex:2502; }","duration":"334.820823ms","start":"2026-04-30T14:35:09.118507Z","end":"2026-04-30T14:35:09.453328Z","steps":["trace[760904828] 'read index received' (duration: 279.555709ms)","trace[760904828] 'applied index is now lower than readState.Index' (duration: 55.262114ms)"],"step_count":2} {"level":"info","ts":"2026-04-30T14:35:09.453394Z","caller":"traceutil/trace.go:171","msg":"trace[2135981418] transaction","detail":"{read_only:false; response_revision:2405; number_of_response:1; }","duration":"338.94218ms","start":"2026-04-30T14:35:09.114405Z","end":"2026-04-30T14:35:09.453347Z","steps":["trace[2135981418] 'process raft request' (duration: 283.729517ms)","trace[2135981418] 'compare' (duration: 55.046278ms)"],"step_count":2} {"level":"warn","ts":"2026-04-30T14:35:09.453574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:35:09.11437Z","time spent":"339.108064ms","remote":"127.0.0.1:50984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-30T14:35:09.453591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.154364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-04-30T14:35:09.453646Z","caller":"traceutil/trace.go:171","msg":"trace[1929390917] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:2405; }","duration":"252.237556ms","start":"2026-04-30T14:35:09.201393Z","end":"2026-04-30T14:35:09.45363Z","steps":["trace[1929390917] 'agreement among raft nodes before linearized reading' (duration: 252.113663ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:35:09.453702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.028413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/kube-system/\" range_end:\"/registry/roles/kube-system0\" limit:500 ","response":"range_response_count:12 size:6839"} {"level":"warn","ts":"2026-04-30T14:35:09.453696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.165873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/ceph-csi-encryption-kms-config\" ","response":"range_response_count:1 size:811"} {"level":"info","ts":"2026-04-30T14:35:09.453737Z","caller":"traceutil/trace.go:171","msg":"trace[1459108272] range","detail":"{range_begin:/registry/roles/kube-system/; range_end:/registry/roles/kube-system0; response_count:12; response_revision:2405; }","duration":"262.116355ms","start":"2026-04-30T14:35:09.191611Z","end":"2026-04-30T14:35:09.453728Z","steps":["trace[1459108272] 'agreement among raft nodes before linearized reading' (duration: 261.873858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:35:09.453751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.281863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" limit:500 ","response":"range_response_count:6 size:363678"} {"level":"info","ts":"2026-04-30T14:35:09.453775Z","caller":"traceutil/trace.go:171","msg":"trace[1631785252] range","detail":"{range_begin:/registry/configmaps/kube-system/ceph-csi-encryption-kms-config; range_end:; response_count:1; response_revision:2405; }","duration":"335.275295ms","start":"2026-04-30T14:35:09.118479Z","end":"2026-04-30T14:35:09.453754Z","steps":["trace[1631785252] 'agreement among raft nodes before linearized reading' (duration: 334.967996ms)"],"step_count":1} {"level":"info","ts":"2026-04-30T14:35:09.453807Z","caller":"traceutil/trace.go:171","msg":"trace[937029222] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:2405; }","duration":"225.384066ms","start":"2026-04-30T14:35:09.228409Z","end":"2026-04-30T14:35:09.453793Z","steps":["trace[937029222] 'agreement among raft nodes before linearized reading' (duration: 225.085518ms)"],"step_count":1} {"level":"warn","ts":"2026-04-30T14:35:09.453819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-30T14:35:09.118466Z","time spent":"335.342777ms","remote":"127.0.0.1:51060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":835,"request content":"key:\"/registry/configmaps/kube-system/ceph-csi-encryption-kms-config\" "}