{"level":"warn","ts":"2026-07-01T13:12:45.274161Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T13:12:45.274245Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-07-01T13:12:45.274306Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T13:12:45.274318Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-07-01T13:12:45.274378Z","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-07-01T13:12:45.275498Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-07-01T13:12:45.275627Z","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.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-07-01T13:12:45.278311Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.419517ms"} {"level":"info","ts":"2026-07-01T13:12:45.28318Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-07-01T13:12:45.283257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T13:12:45.283283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-07-01T13:12:45.283297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T13:12:45.283306Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-07-01T13:12:45.283349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-07-01T13:12:45.288568Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T13:12:45.290661Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T13:12:45.292894Z","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-07-01T13:12:45.296972Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T13:12:45.297128Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T13:12:45.297454Z","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-07-01T13:12:45.297542Z","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-07-01T13:12:45.297551Z","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-07-01T13:12:45.29792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-07-01T13:12:45.30079Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-07-01T13:12:45.303856Z","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-07-01T13:12:45.303932Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-07-01T13:12:45.303971Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-07-01T13:12:45.304144Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T13:12:45.304177Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T13:12:45.683875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T13:12:45.683958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T13:12:45.684005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-07-01T13:12:45.684034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-07-01T13:12:45.684051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-07-01T13:12:45.684072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-07-01T13:12:45.684091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-07-01T13:12:45.702878Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T13:12:45.703039Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T13:12:45.703019Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T13:12:45.703208Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T13:12:45.703291Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T13:12:45.706367Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T13:12:45.709254Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-07-01T13:12:45.702951Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T13:12:45.71004Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T13:12:45.727249Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T13:12:45.727352Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-01T13:12:48.639507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.532261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-01T13:12:48.639576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.788643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T13:12:48.639649Z","caller":"traceutil/trace.go:171","msg":"trace[1058625317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"581.693987ms","start":"2026-07-01T13:12:48.05792Z","end":"2026-07-01T13:12:48.639614Z","steps":["trace[1058625317] 'range keys from in-memory index tree' (duration: 581.426008ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:12:48.639665Z","caller":"traceutil/trace.go:171","msg":"trace[706124830] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:71; }","duration":"686.959599ms","start":"2026-07-01T13:12:47.952684Z","end":"2026-07-01T13:12:48.639644Z","steps":["trace[706124830] 'range keys from in-memory index tree' (duration: 686.68851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:12:48.63975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:12:47.952664Z","time spent":"687.068102ms","remote":"127.0.0.1:48722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-07-01T13:12:48.63972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:12:48.0579Z","time spent":"581.80811ms","remote":"127.0.0.1:48336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T13:12:48.639507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.115598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-01T13:12:48.639905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.461478ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:4"} {"level":"info","ts":"2026-07-01T13:12:48.639974Z","caller":"traceutil/trace.go:171","msg":"trace[473176221] transaction","detail":"{read_only:false; number_of_response:0; response_revision:71; }","duration":"525.662807ms","start":"2026-07-01T13:12:48.114298Z","end":"2026-07-01T13:12:48.639961Z","steps":["trace[473176221] 'process raft request' (duration: 197.578439ms)","trace[473176221] 'compare' (duration: 327.411667ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T13:12:48.640029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:12:48.114138Z","time spent":"525.870674ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T13:12:48.640121Z","caller":"traceutil/trace.go:171","msg":"trace[681977636] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:71; }","duration":"684.611814ms","start":"2026-07-01T13:12:47.95533Z","end":"2026-07-01T13:12:48.639942Z","steps":["trace[681977636] 'range keys from in-memory index tree' (duration: 684.025145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:12:48.640331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:12:47.955268Z","time spent":"685.020427ms","remote":"127.0.0.1:48698","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-07-01T13:12:48.661726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:12:48.341098Z","time spent":"320.62566ms","remote":"127.0.0.1:48458","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-01T13:12:49.161775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316921127494622,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T13:12:49.513969Z","caller":"traceutil/trace.go:171","msg":"trace[1404728410] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"870.084778ms","start":"2026-07-01T13:12:48.643861Z","end":"2026-07-01T13:12:49.513946Z","steps":["trace[1404728410] 'process raft request' (duration: 869.945763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:12:49.514104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:12:48.64384Z","time spent":"870.204612ms","remote":"127.0.0.1:48722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T13:12:49.523445Z","caller":"traceutil/trace.go:171","msg":"trace[607693225] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:78; }","duration":"861.720371ms","start":"2026-07-01T13:12:48.661698Z","end":"2026-07-01T13:12:49.523419Z","steps":["trace[607693225] 'read index received' (duration: 852.079523ms)","trace[607693225] 'applied index is now lower than readState.Index' (duration: 9.639708ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:12:49.523572Z","caller":"traceutil/trace.go:171","msg":"trace[1641423757] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"860.814622ms","start":"2026-07-01T13:12:48.662745Z","end":"2026-07-01T13:12:49.52356Z","steps":["trace[1641423757] 'process raft request' (duration: 859.82858ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:12:49.52366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:12:48.662726Z","time spent":"860.877744ms","remote":"127.0.0.1:48458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":592,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T13:12:49.523961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"880.596894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T13:12:49.524009Z","caller":"traceutil/trace.go:171","msg":"trace[892288510] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:73; }","duration":"880.680257ms","start":"2026-07-01T13:12:48.643311Z","end":"2026-07-01T13:12:49.523992Z","steps":["trace[892288510] 'agreement among raft nodes before linearized reading' (duration: 880.536382ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:12:49.52404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:12:48.643291Z","time spent":"880.741218ms","remote":"127.0.0.1:48710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-07-01T13:12:49.524206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.102414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3465"} {"level":"info","ts":"2026-07-01T13:12:49.524239Z","caller":"traceutil/trace.go:171","msg":"trace[1146728186] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:73; }","duration":"883.169626ms","start":"2026-07-01T13:12:48.641061Z","end":"2026-07-01T13:12:49.524231Z","steps":["trace[1146728186] 'agreement among raft nodes before linearized reading' (duration: 883.108294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:12:49.524266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:12:48.641044Z","time spent":"883.216207ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-07-01T13:12:49.524407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.116697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T13:12:49.524439Z","caller":"traceutil/trace.go:171","msg":"trace[280937256] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"466.183439ms","start":"2026-07-01T13:12:49.058247Z","end":"2026-07-01T13:12:49.524431Z","steps":["trace[280937256] 'agreement among raft nodes before linearized reading' (duration: 466.137017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:12:49.524502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:12:49.058228Z","time spent":"466.230481ms","remote":"127.0.0.1:48336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T13:12:50.205759Z","caller":"traceutil/trace.go:171","msg":"trace[1453285320] transaction","detail":"{read_only:false; response_revision:183; number_of_response:1; }","duration":"300.962622ms","start":"2026-07-01T13:12:49.90477Z","end":"2026-07-01T13:12:50.205732Z","steps":["trace[1453285320] 'process raft request' (duration: 291.562021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:12:50.205917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:12:49.904753Z","time spent":"301.111017ms","remote":"127.0.0.1:48710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":730,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T13:13:21.346432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.163616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:13:21.346532Z","caller":"traceutil/trace.go:171","msg":"trace[296733478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"193.233438ms","start":"2026-07-01T13:13:21.153249Z","end":"2026-07-01T13:13:21.346482Z","steps":["trace[296733478] 'range keys from in-memory index tree' (duration: 193.082854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:22.88851Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316921127495577,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T13:13:23.158043Z","caller":"traceutil/trace.go:171","msg":"trace[1521049618] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"853.889543ms","start":"2026-07-01T13:13:22.304124Z","end":"2026-07-01T13:13:23.158014Z","steps":["trace[1521049618] 'process raft request' (duration: 849.870464ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:23.158187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:22.304106Z","time spent":"854.014186ms","remote":"127.0.0.1:48424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T13:13:23.159511Z","caller":"traceutil/trace.go:171","msg":"trace[808697299] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"853.307524ms","start":"2026-07-01T13:13:22.306177Z","end":"2026-07-01T13:13:23.159485Z","steps":["trace[808697299] 'process raft request' (duration: 853.045256ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:13:23.159784Z","caller":"traceutil/trace.go:171","msg":"trace[1439146198] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:587; }","duration":"771.945933ms","start":"2026-07-01T13:13:22.38781Z","end":"2026-07-01T13:13:23.159756Z","steps":["trace[1439146198] 'read index received' (duration: 766.180108ms)","trace[1439146198] 'applied index is now lower than readState.Index' (duration: 5.764945ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:13:23.159804Z","caller":"traceutil/trace.go:171","msg":"trace[412048830] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"851.61017ms","start":"2026-07-01T13:13:22.308172Z","end":"2026-07-01T13:13:23.159782Z","steps":["trace[412048830] 'process raft request' (duration: 851.257878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:23.159894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:22.306155Z","time spent":"853.423868ms","remote":"127.0.0.1:48424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T13:13:23.159997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:22.308157Z","time spent":"851.754125ms","remote":"127.0.0.1:48424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29461,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T13:13:23.160027Z","caller":"traceutil/trace.go:171","msg":"trace[1476387814] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"503.891122ms","start":"2026-07-01T13:13:22.656122Z","end":"2026-07-01T13:13:23.160013Z","steps":["trace[1476387814] 'process raft request' (duration: 503.566502ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:23.160069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.754491ms","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-07-01T13:13:23.160109Z","caller":"traceutil/trace.go:171","msg":"trace[548474568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"393.822973ms","start":"2026-07-01T13:13:22.766275Z","end":"2026-07-01T13:13:23.160098Z","steps":["trace[548474568] 'agreement among raft nodes before linearized reading' (duration: 393.751831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:23.160114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:22.656098Z","time spent":"503.966425ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T13:13:23.160144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:22.766259Z","time spent":"393.878314ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T13:13:23.160323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.207541ms","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-07-01T13:13:23.160445Z","caller":"traceutil/trace.go:171","msg":"trace[1902371477] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"387.361006ms","start":"2026-07-01T13:13:22.773069Z","end":"2026-07-01T13:13:23.16043Z","steps":["trace[1902371477] 'agreement among raft nodes before linearized reading' (duration: 387.206191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:23.160518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:22.773046Z","time spent":"387.462109ms","remote":"127.0.0.1:48638","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-07-01T13:13:23.160579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.770809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-01T13:13:23.16063Z","caller":"traceutil/trace.go:171","msg":"trace[2025432048] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:568; }","duration":"772.860772ms","start":"2026-07-01T13:13:22.38776Z","end":"2026-07-01T13:13:23.160621Z","steps":["trace[2025432048] 'agreement among raft nodes before linearized reading' (duration: 772.653945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:23.160669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:22.387737Z","time spent":"772.925314ms","remote":"127.0.0.1:48638","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-07-01T13:13:28.892978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.220102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:13:28.893064Z","caller":"traceutil/trace.go:171","msg":"trace[115898592] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:630; }","duration":"109.336326ms","start":"2026-07-01T13:13:28.783711Z","end":"2026-07-01T13:13:28.893047Z","steps":["trace[115898592] 'range keys from in-memory index tree' (duration: 109.175391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:28.893114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.163921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:13:28.893195Z","caller":"traceutil/trace.go:171","msg":"trace[886820455] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:630; }","duration":"109.272534ms","start":"2026-07-01T13:13:28.783903Z","end":"2026-07-01T13:13:28.893176Z","steps":["trace[886820455] 'count revisions from in-memory index tree' (duration: 109.131819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:28.893387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.087858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T13:13:28.893375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.703997ms","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-07-01T13:13:28.893426Z","caller":"traceutil/trace.go:171","msg":"trace[1287275321] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:630; }","duration":"109.137619ms","start":"2026-07-01T13:13:28.784279Z","end":"2026-07-01T13:13:28.893416Z","steps":["trace[1287275321] 'range keys from in-memory index tree' (duration: 109.025656ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:13:28.893468Z","caller":"traceutil/trace.go:171","msg":"trace[1891568021] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:630; }","duration":"129.830901ms","start":"2026-07-01T13:13:28.763617Z","end":"2026-07-01T13:13:28.893448Z","steps":["trace[1891568021] 'count revisions from in-memory index tree' (duration: 129.196411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:28.8934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.941355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:13:28.893602Z","caller":"traceutil/trace.go:171","msg":"trace[1250390212] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:630; }","duration":"130.141121ms","start":"2026-07-01T13:13:28.763416Z","end":"2026-07-01T13:13:28.893557Z","steps":["trace[1250390212] 'range keys from in-memory index tree' (duration: 129.902344ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:13:29.014277Z","caller":"traceutil/trace.go:171","msg":"trace[2073980846] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"173.979083ms","start":"2026-07-01T13:13:28.840277Z","end":"2026-07-01T13:13:29.014257Z","steps":["trace[2073980846] 'read index received' (duration: 173.375794ms)","trace[2073980846] 'applied index is now lower than readState.Index' (duration: 602.759µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:13:29.015624Z","caller":"traceutil/trace.go:171","msg":"trace[164239321] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"218.077103ms","start":"2026-07-01T13:13:28.797535Z","end":"2026-07-01T13:13:29.015613Z","steps":["trace[164239321] 'process raft request' (duration: 216.344997ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:29.015877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.582514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:13:29.015914Z","caller":"traceutil/trace.go:171","msg":"trace[1227312508] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:631; }","duration":"175.662667ms","start":"2026-07-01T13:13:28.840246Z","end":"2026-07-01T13:13:29.015908Z","steps":["trace[1227312508] 'agreement among raft nodes before linearized reading' (duration: 175.589485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:29.016126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.787521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:13:29.016147Z","caller":"traceutil/trace.go:171","msg":"trace[1118074159] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:631; }","duration":"175.832233ms","start":"2026-07-01T13:13:28.84031Z","end":"2026-07-01T13:13:29.016142Z","steps":["trace[1118074159] 'agreement among raft nodes before linearized reading' (duration: 175.798681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:29.017763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.832948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:13:29.017789Z","caller":"traceutil/trace.go:171","msg":"trace[682969185] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:631; }","duration":"102.88194ms","start":"2026-07-01T13:13:28.914902Z","end":"2026-07-01T13:13:29.017784Z","steps":["trace[682969185] 'agreement among raft nodes before linearized reading' (duration: 102.840239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:29.017956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.643354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:13:29.017978Z","caller":"traceutil/trace.go:171","msg":"trace[362352833] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:631; }","duration":"103.713446ms","start":"2026-07-01T13:13:28.914259Z","end":"2026-07-01T13:13:29.017973Z","steps":["trace[362352833] 'agreement among raft nodes before linearized reading' (duration: 103.668065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:29.018071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.84489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:13:29.018089Z","caller":"traceutil/trace.go:171","msg":"trace[682707446] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:631; }","duration":"103.894372ms","start":"2026-07-01T13:13:28.914191Z","end":"2026-07-01T13:13:29.018085Z","steps":["trace[682707446] 'agreement among raft nodes before linearized reading' (duration: 103.862471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:29.019137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.997981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:13:29.019221Z","caller":"traceutil/trace.go:171","msg":"trace[612313571] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:631; }","duration":"178.097304ms","start":"2026-07-01T13:13:28.8411Z","end":"2026-07-01T13:13:29.019197Z","steps":["trace[612313571] 'agreement among raft nodes before linearized reading' (duration: 177.986061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:29.019363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.409547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:13:29.019415Z","caller":"traceutil/trace.go:171","msg":"trace[1888022333] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:631; }","duration":"154.466649ms","start":"2026-07-01T13:13:28.86493Z","end":"2026-07-01T13:13:29.019396Z","steps":["trace[1888022333] 'agreement among raft nodes before linearized reading' (duration: 154.417448ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:29.019531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.163552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:13:29.019582Z","caller":"traceutil/trace.go:171","msg":"trace[2139847432] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:631; }","duration":"155.201853ms","start":"2026-07-01T13:13:28.86436Z","end":"2026-07-01T13:13:29.019562Z","steps":["trace[2139847432] 'agreement among raft nodes before linearized reading' (duration: 155.154611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:29.019692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.41417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:13:29.019716Z","caller":"traceutil/trace.go:171","msg":"trace[930650857] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:631; }","duration":"155.45708ms","start":"2026-07-01T13:13:28.864253Z","end":"2026-07-01T13:13:29.01971Z","steps":["trace[930650857] 'agreement among raft nodes before linearized reading' (duration: 155.419949ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:30.132809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.901355ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T13:13:30.133033Z","caller":"traceutil/trace.go:171","msg":"trace[1609462469] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"936.67454ms","start":"2026-07-01T13:13:29.196307Z","end":"2026-07-01T13:13:30.132981Z","steps":["trace[1609462469] 'process raft request' (duration: 360.520448ms)","trace[1609462469] 'compare' (duration: 575.252843ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:13:30.133148Z","caller":"traceutil/trace.go:171","msg":"trace[856548671] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"936.418192ms","start":"2026-07-01T13:13:29.196701Z","end":"2026-07-01T13:13:30.133119Z","steps":["trace[856548671] 'read index received' (duration: 360.067613ms)","trace[856548671] 'applied index is now lower than readState.Index' (duration: 576.348698ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T13:13:30.133205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:29.196284Z","time spent":"936.831984ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T13:13:30.133408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.69103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-01T13:13:30.13349Z","caller":"traceutil/trace.go:171","msg":"trace[1461476416] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"936.801714ms","start":"2026-07-01T13:13:29.196666Z","end":"2026-07-01T13:13:30.133467Z","steps":["trace[1461476416] 'agreement among raft nodes before linearized reading' (duration: 936.565856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:30.13355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:29.19665Z","time spent":"936.886007ms","remote":"127.0.0.1:48638","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-07-01T13:13:30.134058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.927794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T13:13:30.134191Z","caller":"traceutil/trace.go:171","msg":"trace[602359229] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"935.078839ms","start":"2026-07-01T13:13:29.199093Z","end":"2026-07-01T13:13:30.134172Z","steps":["trace[602359229] 'agreement among raft nodes before linearized reading' (duration: 934.840142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:30.134285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:29.199082Z","time spent":"935.186782ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-01T13:13:30.134437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.218346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q82hm\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-01T13:13:30.134496Z","caller":"traceutil/trace.go:171","msg":"trace[885902464] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q82hm; range_end:; response_count:1; response_revision:634; }","duration":"820.292078ms","start":"2026-07-01T13:13:29.31419Z","end":"2026-07-01T13:13:30.134482Z","steps":["trace[885902464] 'agreement among raft nodes before linearized reading' (duration: 820.085461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:30.134457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.833104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-01T13:13:30.13453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:29.314134Z","time spent":"820.388451ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-q82hm\" "} {"level":"info","ts":"2026-07-01T13:13:30.134094Z","caller":"traceutil/trace.go:171","msg":"trace[477654118] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"549.454349ms","start":"2026-07-01T13:13:29.584578Z","end":"2026-07-01T13:13:30.134032Z","steps":["trace[477654118] 'process raft request' (duration: 548.683614ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:13:30.134576Z","caller":"traceutil/trace.go:171","msg":"trace[1813327537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:634; }","duration":"419.991699ms","start":"2026-07-01T13:13:29.714565Z","end":"2026-07-01T13:13:30.134557Z","steps":["trace[1813327537] 'agreement among raft nodes before linearized reading' (duration: 419.68661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:30.134791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:29.584546Z","time spent":"550.085729ms","remote":"127.0.0.1:48484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T13:13:30.134751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:29.714538Z","time spent":"420.197445ms","remote":"127.0.0.1:48638","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-07-01T13:13:30.73818Z","caller":"traceutil/trace.go:171","msg":"trace[1262447707] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"599.54021ms","start":"2026-07-01T13:13:30.138608Z","end":"2026-07-01T13:13:30.738148Z","steps":["trace[1262447707] 'process raft request' (duration: 511.632719ms)","trace[1262447707] 'compare' (duration: 87.664543ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T13:13:30.738387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:30.13859Z","time spent":"599.675785ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T13:13:30.738484Z","caller":"traceutil/trace.go:171","msg":"trace[1952760091] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"599.85216ms","start":"2026-07-01T13:13:30.138608Z","end":"2026-07-01T13:13:30.73846Z","steps":["trace[1952760091] 'process raft request' (duration: 599.478148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:30.738632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:30.138597Z","time spent":"599.965753ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T13:13:30.738796Z","caller":"traceutil/trace.go:171","msg":"trace[2124117831] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"545.390749ms","start":"2026-07-01T13:13:30.193395Z","end":"2026-07-01T13:13:30.738785Z","steps":["trace[2124117831] 'process raft request' (duration: 545.12862ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:13:30.738795Z","caller":"traceutil/trace.go:171","msg":"trace[938987981] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"598.760745ms","start":"2026-07-01T13:13:30.140011Z","end":"2026-07-01T13:13:30.738771Z","steps":["trace[938987981] 'process raft request' (duration: 598.304351ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:30.738852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:30.193375Z","time spent":"545.453481ms","remote":"127.0.0.1:48610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T13:13:30.7388Z","caller":"traceutil/trace.go:171","msg":"trace[1225727458] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:659; }","duration":"578.045763ms","start":"2026-07-01T13:13:30.160732Z","end":"2026-07-01T13:13:30.738778Z","steps":["trace[1225727458] 'read index received' (duration: 489.530243ms)","trace[1225727458] 'applied index is now lower than readState.Index' (duration: 88.51404ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T13:13:30.738926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:30.139986Z","time spent":"598.870079ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T13:13:30.739053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.311232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:13:30.739096Z","caller":"traceutil/trace.go:171","msg":"trace[728545439] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"588.407084ms","start":"2026-07-01T13:13:30.150679Z","end":"2026-07-01T13:13:30.739086Z","steps":["trace[728545439] 'process raft request' (duration: 587.767934ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:13:30.739126Z","caller":"traceutil/trace.go:171","msg":"trace[1018419749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"578.407935ms","start":"2026-07-01T13:13:30.1607Z","end":"2026-07-01T13:13:30.739108Z","steps":["trace[1018419749] 'agreement among raft nodes before linearized reading' (duration: 578.306602ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:30.739152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:30.150669Z","time spent":"588.454485ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T13:13:30.73918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:30.160685Z","time spent":"578.482637ms","remote":"127.0.0.1:48336","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-07-01T13:13:30.739233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.106643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-01T13:13:30.739283Z","caller":"traceutil/trace.go:171","msg":"trace[1138621454] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:639; }","duration":"177.213966ms","start":"2026-07-01T13:13:30.562057Z","end":"2026-07-01T13:13:30.739271Z","steps":["trace[1138621454] 'agreement among raft nodes before linearized reading' (duration: 177.136994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:32.461024Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.640999509s","expected-duration":"1s"} {"level":"info","ts":"2026-07-01T13:13:32.475909Z","caller":"traceutil/trace.go:171","msg":"trace[1156341999] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"338.70481ms","start":"2026-07-01T13:13:32.137181Z","end":"2026-07-01T13:13:32.475886Z","steps":["trace[1156341999] 'read index received' (duration: 324.126403ms)","trace[1156341999] 'applied index is now lower than readState.Index' (duration: 14.577346ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T13:13:32.476107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.364811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:13:32.47614Z","caller":"traceutil/trace.go:171","msg":"trace[1501390243] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"324.453104ms","start":"2026-07-01T13:13:32.151676Z","end":"2026-07-01T13:13:32.476129Z","steps":["trace[1501390243] 'agreement among raft nodes before linearized reading' (duration: 324.347251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:32.476169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:32.151652Z","time spent":"324.510565ms","remote":"127.0.0.1:48336","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-07-01T13:13:32.476209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.005799ms","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-07-01T13:13:32.476267Z","caller":"traceutil/trace.go:171","msg":"trace[1132682360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"339.121893ms","start":"2026-07-01T13:13:32.13713Z","end":"2026-07-01T13:13:32.476252Z","steps":["trace[1132682360] 'agreement among raft nodes before linearized reading' (duration: 338.872765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:13:32.476305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:32.137111Z","time spent":"339.185015ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T13:13:32.475969Z","caller":"traceutil/trace.go:171","msg":"trace[633122251] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"1.693974203s","start":"2026-07-01T13:13:30.781938Z","end":"2026-07-01T13:13:32.475912Z","steps":["trace[633122251] 'process raft request' (duration: 1.679399147s)","trace[633122251] 'compare' (duration: 14.174194ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T13:13:32.476631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:13:30.781926Z","time spent":"1.694632374s","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T13:13:32.633965Z","caller":"traceutil/trace.go:171","msg":"trace[122426505] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"154.173549ms","start":"2026-07-01T13:13:32.479756Z","end":"2026-07-01T13:13:32.633929Z","steps":["trace[122426505] 'process raft request' (duration: 118.855399ms)","trace[122426505] 'compare' (duration: 34.735841ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:13:32.63407Z","caller":"traceutil/trace.go:171","msg":"trace[2055568974] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"153.303682ms","start":"2026-07-01T13:13:32.480743Z","end":"2026-07-01T13:13:32.634047Z","steps":["trace[2055568974] 'process raft request' (duration: 153.146987ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:13:32.634164Z","caller":"traceutil/trace.go:171","msg":"trace[1925856829] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"152.844107ms","start":"2026-07-01T13:13:32.481297Z","end":"2026-07-01T13:13:32.634142Z","steps":["trace[1925856829] 'process raft request' (duration: 152.690132ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:13:32.634918Z","caller":"traceutil/trace.go:171","msg":"trace[1211455837] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"152.478095ms","start":"2026-07-01T13:13:32.482423Z","end":"2026-07-01T13:13:32.634901Z","steps":["trace[1211455837] 'process raft request' (duration: 152.162605ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:13:32.634875Z","caller":"traceutil/trace.go:171","msg":"trace[378102018] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"152.042562ms","start":"2026-07-01T13:13:32.482819Z","end":"2026-07-01T13:13:32.634862Z","steps":["trace[378102018] 'process raft request' (duration: 151.99563ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:13:34.547809Z","caller":"traceutil/trace.go:171","msg":"trace[371946795] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"104.063048ms","start":"2026-07-01T13:13:34.443716Z","end":"2026-07-01T13:13:34.547779Z","steps":["trace[371946795] 'process raft request' (duration: 103.922133ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:13:37.661153Z","caller":"traceutil/trace.go:171","msg":"trace[554812478] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"129.226802ms","start":"2026-07-01T13:13:37.53188Z","end":"2026-07-01T13:13:37.661107Z","steps":["trace[554812478] 'process raft request' (duration: 128.950493ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:13:52.37609Z","caller":"traceutil/trace.go:171","msg":"trace[2067397290] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"245.570062ms","start":"2026-07-01T13:13:52.130495Z","end":"2026-07-01T13:13:52.376065Z","steps":["trace[2067397290] 'process raft request' (duration: 245.384096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:14:27.672668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.036079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T13:14:27.672762Z","caller":"traceutil/trace.go:171","msg":"trace[312214271] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"672.882659ms","start":"2026-07-01T13:14:26.999864Z","end":"2026-07-01T13:14:27.672747Z","steps":["trace[312214271] 'process raft request' (duration: 248.690566ms)","trace[312214271] 'compare' (duration: 423.709128ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T13:14:27.672807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:14:26.999853Z","time spent":"672.93268ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T13:14:28.090273Z","caller":"traceutil/trace.go:171","msg":"trace[2011368329] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"429.183614ms","start":"2026-07-01T13:14:27.661049Z","end":"2026-07-01T13:14:28.090232Z","steps":["trace[2011368329] 'process raft request' (duration: 429.145973ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:14:28.090277Z","caller":"traceutil/trace.go:171","msg":"trace[559266494] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"1.089084737s","start":"2026-07-01T13:14:27.001153Z","end":"2026-07-01T13:14:28.090237Z","steps":["trace[559266494] 'process raft request' (duration: 1.059663064s)","trace[559266494] 'compare' (duration: 29.193356ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:14:28.090331Z","caller":"traceutil/trace.go:171","msg":"trace[221017291] linearizableReadLoop","detail":"{readStateIndex:1069; appliedIndex:1066; }","duration":"935.699579ms","start":"2026-07-01T13:14:27.154603Z","end":"2026-07-01T13:14:28.090303Z","steps":["trace[221017291] 'read index received' (duration: 94.045647ms)","trace[221017291] 'applied index is now lower than readState.Index' (duration: 841.649081ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T13:14:28.090431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:14:27.66102Z","time spent":"429.329329ms","remote":"127.0.0.1:48638","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-07-01T13:14:28.090463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.850463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T13:14:28.090492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.130858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T13:14:28.090499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:14:27.001137Z","time spent":"1.089249192s","remote":"127.0.0.1:48638","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-07-01T13:14:28.090521Z","caller":"traceutil/trace.go:171","msg":"trace[2134745117] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1029; }","duration":"441.207309ms","start":"2026-07-01T13:14:27.649305Z","end":"2026-07-01T13:14:28.090512Z","steps":["trace[2134745117] 'agreement among raft nodes before linearized reading' (duration: 441.130097ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:14:28.090526Z","caller":"traceutil/trace.go:171","msg":"trace[727809639] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1029; }","duration":"935.954396ms","start":"2026-07-01T13:14:27.154555Z","end":"2026-07-01T13:14:28.090509Z","steps":["trace[727809639] 'agreement among raft nodes before linearized reading' (duration: 935.856523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:14:28.090548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:14:27.649288Z","time spent":"441.253661ms","remote":"127.0.0.1:48522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-07-01T13:14:28.090567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:14:27.154536Z","time spent":"936.023989ms","remote":"127.0.0.1:48336","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-07-01T13:14:28.090293Z","caller":"traceutil/trace.go:171","msg":"trace[150629992] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"1.089064756s","start":"2026-07-01T13:14:27.001199Z","end":"2026-07-01T13:14:28.090264Z","steps":["trace[150629992] 'process raft request' (duration: 1.088946033s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:14:28.090665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:14:27.001182Z","time spent":"1.089455309s","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T13:14:49.652076Z","caller":"traceutil/trace.go:171","msg":"trace[749224182] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"584.678101ms","start":"2026-07-01T13:14:49.067356Z","end":"2026-07-01T13:14:49.652034Z","steps":["trace[749224182] 'process raft request' (duration: 584.466794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:14:49.652293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:14:49.067347Z","time spent":"584.858927ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T13:14:50.17814Z","caller":"traceutil/trace.go:171","msg":"trace[202806265] linearizableReadLoop","detail":"{readStateIndex:1143; appliedIndex:1141; }","duration":"852.871161ms","start":"2026-07-01T13:14:49.325254Z","end":"2026-07-01T13:14:50.178125Z","steps":["trace[202806265] 'read index received' (duration: 326.519902ms)","trace[202806265] 'applied index is now lower than readState.Index' (duration: 526.349119ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:14:50.178217Z","caller":"traceutil/trace.go:171","msg":"trace[675264745] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"927.429341ms","start":"2026-07-01T13:14:49.250738Z","end":"2026-07-01T13:14:50.178168Z","steps":["trace[675264745] 'process raft request' (duration: 927.293957ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:14:50.178258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.996094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T13:14:50.178388Z","caller":"traceutil/trace.go:171","msg":"trace[1519651777] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1098; }","duration":"853.176471ms","start":"2026-07-01T13:14:49.325203Z","end":"2026-07-01T13:14:50.17838Z","steps":["trace[1519651777] 'agreement among raft nodes before linearized reading' (duration: 852.996005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:14:50.17842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:14:49.325185Z","time spent":"853.226842ms","remote":"127.0.0.1:48688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-01T13:14:50.178431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:14:49.250713Z","time spent":"927.594687ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T13:14:50.196643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.880188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T13:14:50.196678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.60582ms","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-07-01T13:14:50.196734Z","caller":"traceutil/trace.go:171","msg":"trace[452620781] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1099; }","duration":"392.694283ms","start":"2026-07-01T13:14:49.804026Z","end":"2026-07-01T13:14:50.19672Z","steps":["trace[452620781] 'agreement among raft nodes before linearized reading' (duration: 392.534518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:14:50.196783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:14:49.804013Z","time spent":"392.762055ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T13:14:50.19674Z","caller":"traceutil/trace.go:171","msg":"trace[828063565] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1099; }","duration":"301.031373ms","start":"2026-07-01T13:14:49.89568Z","end":"2026-07-01T13:14:50.196712Z","steps":["trace[828063565] 'agreement among raft nodes before linearized reading' (duration: 300.888468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:14:50.196896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:14:49.895651Z","time spent":"301.234559ms","remote":"127.0.0.1:33446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-07-01T13:14:51.873038Z","caller":"traceutil/trace.go:171","msg":"trace[610362565] transaction","detail":"{read_only:false; response_revision:1105; number_of_response:1; }","duration":"211.695269ms","start":"2026-07-01T13:14:51.661316Z","end":"2026-07-01T13:14:51.873011Z","steps":["trace[610362565] 'process raft request' (duration: 211.535564ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:14:52.392552Z","caller":"traceutil/trace.go:171","msg":"trace[214821450] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"170.660053ms","start":"2026-07-01T13:14:52.22186Z","end":"2026-07-01T13:14:52.39252Z","steps":["trace[214821450] 'process raft request' (duration: 170.454156ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:14:52.462255Z","caller":"traceutil/trace.go:171","msg":"trace[184960668] linearizableReadLoop","detail":"{readStateIndex:1153; appliedIndex:1151; }","duration":"223.212779ms","start":"2026-07-01T13:14:52.239015Z","end":"2026-07-01T13:14:52.462227Z","steps":["trace[184960668] 'read index received' (duration: 153.294596ms)","trace[184960668] 'applied index is now lower than readState.Index' (duration: 69.917133ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:14:52.462371Z","caller":"traceutil/trace.go:171","msg":"trace[1848365576] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"237.507277ms","start":"2026-07-01T13:14:52.22483Z","end":"2026-07-01T13:14:52.462337Z","steps":["trace[1848365576] 'process raft request' (duration: 221.293697ms)","trace[1848365576] 'compare' (duration: 15.985963ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T13:14:52.462519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.477627ms","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-07-01T13:14:52.462579Z","caller":"traceutil/trace.go:171","msg":"trace[1413939358] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1107; }","duration":"223.56978ms","start":"2026-07-01T13:14:52.238995Z","end":"2026-07-01T13:14:52.462565Z","steps":["trace[1413939358] 'agreement among raft nodes before linearized reading' (duration: 223.393924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:14:52.463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.393632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T13:14:52.463193Z","caller":"traceutil/trace.go:171","msg":"trace[2043377154] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1107; }","duration":"223.056863ms","start":"2026-07-01T13:14:52.240121Z","end":"2026-07-01T13:14:52.463178Z","steps":["trace[2043377154] 'agreement among raft nodes before linearized reading' (duration: 222.314439ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:14:52.82372Z","caller":"traceutil/trace.go:171","msg":"trace[1216994405] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1154; }","duration":"137.465909ms","start":"2026-07-01T13:14:52.686231Z","end":"2026-07-01T13:14:52.823697Z","steps":["trace[1216994405] 'read index received' (duration: 137.259302ms)","trace[1216994405] 'applied index is now lower than readState.Index' (duration: 205.737µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T13:14:52.823933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.673495ms","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-07-01T13:14:52.823966Z","caller":"traceutil/trace.go:171","msg":"trace[614832818] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1109; }","duration":"137.764208ms","start":"2026-07-01T13:14:52.686191Z","end":"2026-07-01T13:14:52.823955Z","steps":["trace[614832818] 'agreement among raft nodes before linearized reading' (duration: 137.610273ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:14:52.824008Z","caller":"traceutil/trace.go:171","msg":"trace[804560784] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"356.473402ms","start":"2026-07-01T13:14:52.467474Z","end":"2026-07-01T13:14:52.823948Z","steps":["trace[804560784] 'process raft request' (duration: 356.082719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:14:52.824158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:14:52.467465Z","time spent":"356.620726ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T13:15:08.605938Z","caller":"traceutil/trace.go:171","msg":"trace[1372922905] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"167.672347ms","start":"2026-07-01T13:15:08.438199Z","end":"2026-07-01T13:15:08.605872Z","steps":["trace[1372922905] 'process raft request' (duration: 166.416847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:09.653605Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316921127497059,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T13:15:10.154553Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316921127497059,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T13:15:10.65564Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316921127497059,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T13:15:10.731149Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.772477644s","expected-duration":"1s"} {"level":"info","ts":"2026-07-01T13:15:10.731614Z","caller":"traceutil/trace.go:171","msg":"trace[1088521538] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"1.77297214s","start":"2026-07-01T13:15:08.958615Z","end":"2026-07-01T13:15:10.731587Z","steps":["trace[1088521538] 'process raft request' (duration: 1.772813035s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:10.731766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:08.958606Z","time spent":"1.773082953s","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T13:15:10.73235Z","caller":"traceutil/trace.go:171","msg":"trace[844021657] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1262; }","duration":"1.579543307s","start":"2026-07-01T13:15:09.152777Z","end":"2026-07-01T13:15:10.73232Z","steps":["trace[844021657] 'read index received' (duration: 1.578620437s)","trace[844021657] 'applied index is now lower than readState.Index' (duration: 921.94µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:15:10.73244Z","caller":"traceutil/trace.go:171","msg":"trace[114425628] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"284.259876ms","start":"2026-07-01T13:15:10.44817Z","end":"2026-07-01T13:15:10.73243Z","steps":["trace[114425628] 'process raft request' (duration: 284.043639ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:10.732494Z","caller":"traceutil/trace.go:171","msg":"trace[187667302] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"1.772175114s","start":"2026-07-01T13:15:08.960273Z","end":"2026-07-01T13:15:10.732448Z","steps":["trace[187667302] 'process raft request' (duration: 1.771820432s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:10.732576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:08.96025Z","time spent":"1.772282327s","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T13:15:10.732827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.775424ms","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-07-01T13:15:10.732871Z","caller":"traceutil/trace.go:171","msg":"trace[1695412823] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1215; }","duration":"729.839386ms","start":"2026-07-01T13:15:10.003018Z","end":"2026-07-01T13:15:10.732857Z","steps":["trace[1695412823] 'agreement among raft nodes before linearized reading' (duration: 729.698651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:10.732903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:10.003006Z","time spent":"729.890698ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-01T13:15:10.732906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.958911ms","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-07-01T13:15:10.732946Z","caller":"traceutil/trace.go:171","msg":"trace[394259270] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1215; }","duration":"117.053014ms","start":"2026-07-01T13:15:10.615882Z","end":"2026-07-01T13:15:10.732935Z","steps":["trace[394259270] 'agreement among raft nodes before linearized reading' (duration: 116.881269ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:10.733001Z","caller":"traceutil/trace.go:171","msg":"trace[1949407368] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"122.598992ms","start":"2026-07-01T13:15:10.610387Z","end":"2026-07-01T13:15:10.732986Z","steps":["trace[1949407368] 'process raft request' (duration: 121.89854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:10.733001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.580216718s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:15:10.733059Z","caller":"traceutil/trace.go:171","msg":"trace[2045443137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1215; }","duration":"1.58028749s","start":"2026-07-01T13:15:09.152758Z","end":"2026-07-01T13:15:10.733046Z","steps":["trace[2045443137] 'agreement among raft nodes before linearized reading' (duration: 1.580171316s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:10.733071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.000607ms","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-07-01T13:15:10.733044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.313476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T13:15:10.733089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:09.152693Z","time spent":"1.580391923s","remote":"127.0.0.1:48336","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-07-01T13:15:10.733098Z","caller":"traceutil/trace.go:171","msg":"trace[1827674066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1215; }","duration":"114.026857ms","start":"2026-07-01T13:15:10.619064Z","end":"2026-07-01T13:15:10.733091Z","steps":["trace[1827674066] 'agreement among raft nodes before linearized reading' (duration: 113.954155ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:10.73312Z","caller":"traceutil/trace.go:171","msg":"trace[182937952] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1215; }","duration":"278.430589ms","start":"2026-07-01T13:15:10.454671Z","end":"2026-07-01T13:15:10.733102Z","steps":["trace[182937952] 'agreement among raft nodes before linearized reading' (duration: 278.292084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:10.733216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.122025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-01T13:15:10.73319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.364881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:15:10.733242Z","caller":"traceutil/trace.go:171","msg":"trace[894753333] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:1215; }","duration":"165.183337ms","start":"2026-07-01T13:15:10.568051Z","end":"2026-07-01T13:15:10.733234Z","steps":["trace[894753333] 'agreement among raft nodes before linearized reading' (duration: 165.111755ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:10.733292Z","caller":"traceutil/trace.go:171","msg":"trace[1373922999] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1215; }","duration":"333.518126ms","start":"2026-07-01T13:15:10.399753Z","end":"2026-07-01T13:15:10.733271Z","steps":["trace[1373922999] 'agreement among raft nodes before linearized reading' (duration: 333.357981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:10.733346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:10.399735Z","time spent":"333.599829ms","remote":"127.0.0.1:56530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-07-01T13:15:23.093928Z","caller":"traceutil/trace.go:171","msg":"trace[1289744087] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1306; }","duration":"295.557254ms","start":"2026-07-01T13:15:22.798316Z","end":"2026-07-01T13:15:23.093873Z","steps":["trace[1289744087] 'read index received' (duration: 295.206933ms)","trace[1289744087] 'applied index is now lower than readState.Index' (duration: 348.961µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:15:23.09396Z","caller":"traceutil/trace.go:171","msg":"trace[799099318] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"323.271803ms","start":"2026-07-01T13:15:22.770655Z","end":"2026-07-01T13:15:23.093926Z","steps":["trace[799099318] 'process raft request' (duration: 322.993724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:23.094185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:22.770633Z","time spent":"323.455149ms","remote":"127.0.0.1:48638","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-07-01T13:15:23.09421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.859653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-01T13:15:23.094291Z","caller":"traceutil/trace.go:171","msg":"trace[1814917054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1253; }","duration":"296.012429ms","start":"2026-07-01T13:15:22.798259Z","end":"2026-07-01T13:15:23.094272Z","steps":["trace[1814917054] 'agreement among raft nodes before linearized reading' (duration: 295.760631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:23.094331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.456912ms","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-07-01T13:15:23.094407Z","caller":"traceutil/trace.go:171","msg":"trace[1411351919] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1253; }","duration":"291.573816ms","start":"2026-07-01T13:15:22.802811Z","end":"2026-07-01T13:15:23.094385Z","steps":["trace[1411351919] 'agreement among raft nodes before linearized reading' (duration: 291.39007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:23.094463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.727437ms","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-07-01T13:15:23.0945Z","caller":"traceutil/trace.go:171","msg":"trace[85785203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1253; }","duration":"289.797869ms","start":"2026-07-01T13:15:22.804693Z","end":"2026-07-01T13:15:23.094491Z","steps":["trace[85785203] 'agreement among raft nodes before linearized reading' (duration: 289.698556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:23.094508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.975797ms","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-07-01T13:15:23.094545Z","caller":"traceutil/trace.go:171","msg":"trace[28786440] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1253; }","duration":"291.042789ms","start":"2026-07-01T13:15:22.803493Z","end":"2026-07-01T13:15:23.094535Z","steps":["trace[28786440] 'agreement among raft nodes before linearized reading' (duration: 290.941896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:23.094689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.209025ms","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-07-01T13:15:23.094753Z","caller":"traceutil/trace.go:171","msg":"trace[1953940685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1253; }","duration":"291.272077ms","start":"2026-07-01T13:15:22.803466Z","end":"2026-07-01T13:15:23.094738Z","steps":["trace[1953940685] 'agreement among raft nodes before linearized reading' (duration: 291.105461ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:25.408528Z","caller":"traceutil/trace.go:171","msg":"trace[1542576769] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"203.266402ms","start":"2026-07-01T13:15:25.205231Z","end":"2026-07-01T13:15:25.408498Z","steps":["trace[1542576769] 'process raft request' (duration: 172.542156ms)","trace[1542576769] 'compare' (duration: 30.377805ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:15:31.419307Z","caller":"traceutil/trace.go:171","msg":"trace[2093483188] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1361; }","duration":"282.64644ms","start":"2026-07-01T13:15:31.136635Z","end":"2026-07-01T13:15:31.419282Z","steps":["trace[2093483188] 'read index received' (duration: 282.508325ms)","trace[2093483188] 'applied index is now lower than readState.Index' (duration: 136.905µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:15:31.419476Z","caller":"traceutil/trace.go:171","msg":"trace[2138919529] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"293.304602ms","start":"2026-07-01T13:15:31.126089Z","end":"2026-07-01T13:15:31.419394Z","steps":["trace[2138919529] 'process raft request' (duration: 293.037873ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:31.419603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.940519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T13:15:31.419682Z","caller":"traceutil/trace.go:171","msg":"trace[2087385335] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1306; }","duration":"283.088513ms","start":"2026-07-01T13:15:31.136576Z","end":"2026-07-01T13:15:31.419665Z","steps":["trace[2087385335] 'agreement among raft nodes before linearized reading' (duration: 282.831045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:32.02282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.981507ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T13:15:32.022958Z","caller":"traceutil/trace.go:171","msg":"trace[1975819854] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"757.213376ms","start":"2026-07-01T13:15:31.265692Z","end":"2026-07-01T13:15:32.022906Z","steps":["trace[1975819854] 'process raft request' (duration: 624.062024ms)","trace[1975819854] 'compare' (duration: 132.649116ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:15:32.023023Z","caller":"traceutil/trace.go:171","msg":"trace[1519677392] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"598.134463ms","start":"2026-07-01T13:15:31.42487Z","end":"2026-07-01T13:15:32.023005Z","steps":["trace[1519677392] 'process raft request' (duration: 598.04602ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:32.023023Z","caller":"traceutil/trace.go:171","msg":"trace[560166511] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1362; }","duration":"603.617548ms","start":"2026-07-01T13:15:31.419374Z","end":"2026-07-01T13:15:32.022991Z","steps":["trace[560166511] 'read index received' (duration: 470.392023ms)","trace[560166511] 'applied index is now lower than readState.Index' (duration: 133.222735ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T13:15:32.023093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:31.424851Z","time spent":"598.207304ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T13:15:32.023101Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:31.265675Z","time spent":"757.345891ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T13:15:32.023204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"801.834299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-01T13:15:32.023235Z","caller":"traceutil/trace.go:171","msg":"trace[26809486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1308; }","duration":"801.87668ms","start":"2026-07-01T13:15:31.221349Z","end":"2026-07-01T13:15:32.023226Z","steps":["trace[26809486] 'agreement among raft nodes before linearized reading' (duration: 801.774356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:32.023256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:31.221342Z","time spent":"801.90855ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T13:15:32.023263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.951709ms","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-07-01T13:15:32.023282Z","caller":"traceutil/trace.go:171","msg":"trace[1335765768] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1308; }","duration":"593.989489ms","start":"2026-07-01T13:15:31.429286Z","end":"2026-07-01T13:15:32.023275Z","steps":["trace[1335765768] 'agreement among raft nodes before linearized reading' (duration: 593.926437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:32.023275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.82516ms","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-07-01T13:15:32.023308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:31.429276Z","time spent":"594.02114ms","remote":"127.0.0.1:48638","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-07-01T13:15:32.023319Z","caller":"traceutil/trace.go:171","msg":"trace[1113346682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1308; }","duration":"802.898552ms","start":"2026-07-01T13:15:31.220408Z","end":"2026-07-01T13:15:32.023306Z","steps":["trace[1113346682] 'agreement among raft nodes before linearized reading' (duration: 802.727207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:32.023315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"868.716925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:15:32.023395Z","caller":"traceutil/trace.go:171","msg":"trace[1624872163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1308; }","duration":"868.806407ms","start":"2026-07-01T13:15:31.154567Z","end":"2026-07-01T13:15:32.023373Z","steps":["trace[1624872163] 'agreement among raft nodes before linearized reading' (duration: 868.638722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:32.023394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.811352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-01T13:15:32.023417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.680158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-01T13:15:32.023434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:31.154512Z","time spent":"868.914411ms","remote":"127.0.0.1:48336","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-07-01T13:15:32.02344Z","caller":"traceutil/trace.go:171","msg":"trace[424704444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1308; }","duration":"798.903554ms","start":"2026-07-01T13:15:31.224523Z","end":"2026-07-01T13:15:32.023427Z","steps":["trace[424704444] 'agreement among raft nodes before linearized reading' (duration: 798.792801ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:32.023439Z","caller":"traceutil/trace.go:171","msg":"trace[646310683] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1308; }","duration":"458.704398ms","start":"2026-07-01T13:15:31.564728Z","end":"2026-07-01T13:15:32.023433Z","steps":["trace[646310683] 'agreement among raft nodes before linearized reading' (duration: 458.650857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:32.023477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:31.564671Z","time spent":"458.800842ms","remote":"127.0.0.1:48562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-07-01T13:15:32.023479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:31.224504Z","time spent":"798.966496ms","remote":"127.0.0.1:48638","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-07-01T13:15:32.023347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:31.220402Z","time spent":"802.939284ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T13:15:32.203297Z","caller":"traceutil/trace.go:171","msg":"trace[1192643444] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"175.085008ms","start":"2026-07-01T13:15:32.028182Z","end":"2026-07-01T13:15:32.203267Z","steps":["trace[1192643444] 'process raft request' (duration: 153.299319ms)","trace[1192643444] 'compare' (duration: 21.453368ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:15:32.203507Z","caller":"traceutil/trace.go:171","msg":"trace[1882724886] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"175.282254ms","start":"2026-07-01T13:15:32.028207Z","end":"2026-07-01T13:15:32.203489Z","steps":["trace[1882724886] 'process raft request' (duration: 175.016845ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:32.203814Z","caller":"traceutil/trace.go:171","msg":"trace[144022099] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"175.541222ms","start":"2026-07-01T13:15:32.028244Z","end":"2026-07-01T13:15:32.203785Z","steps":["trace[144022099] 'process raft request' (duration: 175.181961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:33.7894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.481407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:15:33.789504Z","caller":"traceutil/trace.go:171","msg":"trace[1837889508] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1319; }","duration":"604.61859ms","start":"2026-07-01T13:15:33.184862Z","end":"2026-07-01T13:15:33.78948Z","steps":["trace[1837889508] 'count revisions from in-memory index tree' (duration: 604.391413ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:33.789564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:33.184845Z","time spent":"604.698803ms","remote":"127.0.0.1:48610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-07-01T13:15:34.237089Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17576316921127497292,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T13:15:34.273421Z","caller":"traceutil/trace.go:171","msg":"trace[1056269484] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1375; }","duration":"537.225608ms","start":"2026-07-01T13:15:33.736172Z","end":"2026-07-01T13:15:34.273398Z","steps":["trace[1056269484] 'read index received' (duration: 537.017871ms)","trace[1056269484] 'applied index is now lower than readState.Index' (duration: 206.677µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:15:34.273491Z","caller":"traceutil/trace.go:171","msg":"trace[688639261] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"848.348671ms","start":"2026-07-01T13:15:33.425116Z","end":"2026-07-01T13:15:34.273465Z","steps":["trace[688639261] 'process raft request' (duration: 848.142894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:34.273609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.409593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-01T13:15:34.273625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:33.425081Z","time spent":"848.463974ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T13:15:34.273647Z","caller":"traceutil/trace.go:171","msg":"trace[196589112] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1320; }","duration":"537.469095ms","start":"2026-07-01T13:15:33.736167Z","end":"2026-07-01T13:15:34.273636Z","steps":["trace[196589112] 'agreement among raft nodes before linearized reading' (duration: 537.380272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:34.273686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:33.736124Z","time spent":"537.551929ms","remote":"127.0.0.1:48542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":5,"response size":31,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-07-01T13:15:34.274119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.191156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:15:34.274164Z","caller":"traceutil/trace.go:171","msg":"trace[1236426453] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1320; }","duration":"359.291199ms","start":"2026-07-01T13:15:33.914861Z","end":"2026-07-01T13:15:34.274153Z","steps":["trace[1236426453] 'agreement among raft nodes before linearized reading' (duration: 359.203286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:34.274201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:33.914843Z","time spent":"359.34943ms","remote":"127.0.0.1:33466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-07-01T13:15:34.274408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.583567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:15:34.274444Z","caller":"traceutil/trace.go:171","msg":"trace[143303656] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1320; }","duration":"119.67985ms","start":"2026-07-01T13:15:34.154756Z","end":"2026-07-01T13:15:34.274436Z","steps":["trace[143303656] 'agreement among raft nodes before linearized reading' (duration: 119.635088ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:34.274478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.893828ms","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-07-01T13:15:34.274517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.268182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-01T13:15:34.274549Z","caller":"traceutil/trace.go:171","msg":"trace[915262723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1320; }","duration":"246.336284ms","start":"2026-07-01T13:15:34.028205Z","end":"2026-07-01T13:15:34.274541Z","steps":["trace[915262723] 'agreement among raft nodes before linearized reading' (duration: 246.249931ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:34.274542Z","caller":"traceutil/trace.go:171","msg":"trace[1530559698] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1320; }","duration":"191.998631ms","start":"2026-07-01T13:15:34.082527Z","end":"2026-07-01T13:15:34.274526Z","steps":["trace[1530559698] 'agreement among raft nodes before linearized reading' (duration: 191.812065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:34.68425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.167079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T13:15:34.684407Z","caller":"traceutil/trace.go:171","msg":"trace[482280992] linearizableReadLoop","detail":"{readStateIndex:1381; appliedIndex:1376; }","duration":"273.174556ms","start":"2026-07-01T13:15:34.411223Z","end":"2026-07-01T13:15:34.684398Z","steps":["trace[482280992] 'read index received' (duration: 112.754768ms)","trace[482280992] 'applied index is now lower than readState.Index' (duration: 160.419308ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:15:34.684456Z","caller":"traceutil/trace.go:171","msg":"trace[506025373] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"406.640899ms","start":"2026-07-01T13:15:34.277807Z","end":"2026-07-01T13:15:34.684448Z","steps":["trace[506025373] 'process raft request' (duration: 406.506214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:34.684497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:34.277797Z","time spent":"406.68345ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T13:15:34.684657Z","caller":"traceutil/trace.go:171","msg":"trace[1614423450] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"405.940176ms","start":"2026-07-01T13:15:34.278705Z","end":"2026-07-01T13:15:34.684645Z","steps":["trace[1614423450] 'process raft request' (duration: 405.654607ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:34.684688Z","caller":"traceutil/trace.go:171","msg":"trace[217445579] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"407.416493ms","start":"2026-07-01T13:15:34.277264Z","end":"2026-07-01T13:15:34.68468Z","steps":["trace[217445579] 'process raft request' (duration: 246.774098ms)","trace[217445579] 'compare' (duration: 160.104958ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:15:34.684688Z","caller":"traceutil/trace.go:171","msg":"trace[1330160437] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"405.818991ms","start":"2026-07-01T13:15:34.278844Z","end":"2026-07-01T13:15:34.684663Z","steps":["trace[1330160437] 'process raft request' (duration: 405.537422ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:34.684729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:34.278685Z","time spent":"406.013399ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T13:15:34.684806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:34.278829Z","time spent":"405.918145ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T13:15:34.684879Z","caller":"traceutil/trace.go:171","msg":"trace[1380215114] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"406.498784ms","start":"2026-07-01T13:15:34.27837Z","end":"2026-07-01T13:15:34.684869Z","steps":["trace[1380215114] 'process raft request' (duration: 405.974257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:34.684727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:34.277248Z","time spent":"407.457215ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T13:15:34.684933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:34.278362Z","time spent":"406.542745ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T13:15:34.685193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.977491ms","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-07-01T13:15:34.685214Z","caller":"traceutil/trace.go:171","msg":"trace[173482329] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1325; }","duration":"274.024353ms","start":"2026-07-01T13:15:34.411184Z","end":"2026-07-01T13:15:34.685208Z","steps":["trace[173482329] 'agreement among raft nodes before linearized reading' (duration: 273.242458ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:36.310781Z","caller":"traceutil/trace.go:171","msg":"trace[802074559] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"319.789021ms","start":"2026-07-01T13:15:35.990964Z","end":"2026-07-01T13:15:36.310753Z","steps":["trace[802074559] 'process raft request' (duration: 319.580154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:36.311157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:35.99094Z","time spent":"319.922476ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T13:15:36.368892Z","caller":"traceutil/trace.go:171","msg":"trace[370979910] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1382; }","duration":"314.899944ms","start":"2026-07-01T13:15:36.053976Z","end":"2026-07-01T13:15:36.368876Z","steps":["trace[370979910] 'read index received' (duration: 256.734278ms)","trace[370979910] 'applied index is now lower than readState.Index' (duration: 58.164856ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T13:15:36.369156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.751159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T13:15:36.369198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.214214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T13:15:36.369233Z","caller":"traceutil/trace.go:171","msg":"trace[1580552566] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1328; }","duration":"315.301237ms","start":"2026-07-01T13:15:36.053923Z","end":"2026-07-01T13:15:36.369224Z","steps":["trace[1580552566] 'agreement among raft nodes before linearized reading' (duration: 315.228425ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:36.369238Z","caller":"traceutil/trace.go:171","msg":"trace[2032429259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1328; }","duration":"213.871583ms","start":"2026-07-01T13:15:36.15534Z","end":"2026-07-01T13:15:36.369211Z","steps":["trace[2032429259] 'agreement among raft nodes before linearized reading' (duration: 213.746209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:36.36926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:36.053902Z","time spent":"315.349779ms","remote":"127.0.0.1:48522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"info","ts":"2026-07-01T13:15:36.97516Z","caller":"traceutil/trace.go:171","msg":"trace[1933765791] transaction","detail":"{read_only:false; response_revision:1333; number_of_response:1; }","duration":"193.5451ms","start":"2026-07-01T13:15:36.78159Z","end":"2026-07-01T13:15:36.975135Z","steps":["trace[1933765791] 'process raft request' (duration: 193.499698ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:36.975272Z","caller":"traceutil/trace.go:171","msg":"trace[18348784] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"193.850399ms","start":"2026-07-01T13:15:36.781393Z","end":"2026-07-01T13:15:36.975243Z","steps":["trace[18348784] 'process raft request' (duration: 133.483793ms)","trace[18348784] 'compare' (duration: 59.980544ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:15:36.97559Z","caller":"traceutil/trace.go:171","msg":"trace[1175538033] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"194.16763ms","start":"2026-07-01T13:15:36.781405Z","end":"2026-07-01T13:15:36.975573Z","steps":["trace[1175538033] 'process raft request' (duration: 193.649663ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:36.975669Z","caller":"traceutil/trace.go:171","msg":"trace[319523108] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"194.228352ms","start":"2026-07-01T13:15:36.781395Z","end":"2026-07-01T13:15:36.975624Z","steps":["trace[319523108] 'process raft request' (duration: 193.617042ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:39.10265Z","caller":"traceutil/trace.go:171","msg":"trace[1798933767] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"117.704847ms","start":"2026-07-01T13:15:38.984917Z","end":"2026-07-01T13:15:39.102622Z","steps":["trace[1798933767] 'process raft request' (duration: 117.50143ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:39.106608Z","caller":"traceutil/trace.go:171","msg":"trace[524738735] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"121.323143ms","start":"2026-07-01T13:15:38.985265Z","end":"2026-07-01T13:15:39.106588Z","steps":["trace[524738735] 'process raft request' (duration: 121.114986ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:39.106933Z","caller":"traceutil/trace.go:171","msg":"trace[2128585250] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"121.295432ms","start":"2026-07-01T13:15:38.985613Z","end":"2026-07-01T13:15:39.106909Z","steps":["trace[2128585250] 'process raft request' (duration: 120.90877ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:39.107036Z","caller":"traceutil/trace.go:171","msg":"trace[1682109460] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"121.443946ms","start":"2026-07-01T13:15:38.985558Z","end":"2026-07-01T13:15:39.107002Z","steps":["trace[1682109460] 'process raft request' (duration: 120.92682ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:15:40.873167Z","caller":"traceutil/trace.go:171","msg":"trace[591429592] linearizableReadLoop","detail":"{readStateIndex:1425; appliedIndex:1424; }","duration":"302.974212ms","start":"2026-07-01T13:15:40.57017Z","end":"2026-07-01T13:15:40.873144Z","steps":["trace[591429592] 'read index received' (duration: 302.756345ms)","trace[591429592] 'applied index is now lower than readState.Index' (duration: 216.717µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:15:40.873226Z","caller":"traceutil/trace.go:171","msg":"trace[1986494142] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"493.63698ms","start":"2026-07-01T13:15:40.379568Z","end":"2026-07-01T13:15:40.873205Z","steps":["trace[1986494142] 'process raft request' (duration: 493.443604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:40.87335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:40.379548Z","time spent":"493.733542ms","remote":"127.0.0.1:48638","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-07-01T13:15:40.873419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.24615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.95\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-01T13:15:40.873479Z","caller":"traceutil/trace.go:171","msg":"trace[120189076] range","detail":"{range_begin:/registry/masterleases/199.204.45.95; range_end:; response_count:1; response_revision:1368; }","duration":"303.346893ms","start":"2026-07-01T13:15:40.570116Z","end":"2026-07-01T13:15:40.873463Z","steps":["trace[120189076] 'agreement among raft nodes before linearized reading' (duration: 303.172148ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:40.873524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:40.570087Z","time spent":"303.424426ms","remote":"127.0.0.1:48404","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.95\" "} {"level":"warn","ts":"2026-07-01T13:15:41.095162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.993399ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-07-01T13:15:41.095443Z","caller":"traceutil/trace.go:171","msg":"trace[652639390] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"215.350269ms","start":"2026-07-01T13:15:40.880075Z","end":"2026-07-01T13:15:41.095425Z","steps":["trace[652639390] 'process raft request' (duration: 215.193824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:42.191133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.449877ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T13:15:42.191221Z","caller":"traceutil/trace.go:171","msg":"trace[1308429682] linearizableReadLoop","detail":"{readStateIndex:1429; appliedIndex:1428; }","duration":"1.034324548s","start":"2026-07-01T13:15:41.156882Z","end":"2026-07-01T13:15:42.191207Z","steps":["trace[1308429682] 'read index received' (duration: 160.747387ms)","trace[1308429682] 'applied index is now lower than readState.Index' (duration: 873.576121ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T13:15:42.191298Z","caller":"traceutil/trace.go:171","msg":"trace[1904742744] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"1.034820414s","start":"2026-07-01T13:15:41.156451Z","end":"2026-07-01T13:15:42.191272Z","steps":["trace[1904742744] 'process raft request' (duration: 161.164711ms)","trace[1904742744] 'compare' (duration: 873.133037ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T13:15:42.191348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:41.156432Z","time spent":"1.034888517s","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T13:15:42.191538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03465392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-01T13:15:42.191597Z","caller":"traceutil/trace.go:171","msg":"trace[172517318] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1371; }","duration":"1.034731922s","start":"2026-07-01T13:15:41.156854Z","end":"2026-07-01T13:15:42.191586Z","steps":["trace[172517318] 'agreement among raft nodes before linearized reading' (duration: 1.034594657s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:42.191636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:41.156838Z","time spent":"1.034780154s","remote":"127.0.0.1:48404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-07-01T13:15:42.196925Z","caller":"traceutil/trace.go:171","msg":"trace[1014473048] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"1.03997811s","start":"2026-07-01T13:15:41.156931Z","end":"2026-07-01T13:15:42.196909Z","steps":["trace[1014473048] 'process raft request' (duration: 1.039732452s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:42.197065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:41.156908Z","time spent":"1.040112085s","remote":"127.0.0.1:48638","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-07-01T13:15:42.197303Z","caller":"traceutil/trace.go:171","msg":"trace[2259340] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"1.040312161s","start":"2026-07-01T13:15:41.156972Z","end":"2026-07-01T13:15:42.197284Z","steps":["trace[2259340] 'process raft request' (duration: 1.039890508s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:42.197489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:41.156957Z","time spent":"1.040401804s","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T13:15:42.197747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.766115ms","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:532"} {"level":"info","ts":"2026-07-01T13:15:42.197826Z","caller":"traceutil/trace.go:171","msg":"trace[136259979] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1374; }","duration":"374.881009ms","start":"2026-07-01T13:15:41.822923Z","end":"2026-07-01T13:15:42.197804Z","steps":["trace[136259979] 'agreement among raft nodes before linearized reading' (duration: 374.682933ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:42.197908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:41.822905Z","time spent":"374.987903ms","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-01T13:15:42.197514Z","caller":"traceutil/trace.go:171","msg":"trace[1626293698] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"1.040235568s","start":"2026-07-01T13:15:41.157266Z","end":"2026-07-01T13:15:42.197501Z","steps":["trace[1626293698] 'process raft request' (duration: 1.039927618s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T13:15:42.19883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T13:15:41.157253Z","time spent":"1.04153311s","remote":"127.0.0.1:48638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T13:19:29.4488Z","caller":"traceutil/trace.go:171","msg":"trace[1868992800] transaction","detail":"{read_only:false; response_revision:2441; number_of_response:1; }","duration":"169.436776ms","start":"2026-07-01T13:19:29.279146Z","end":"2026-07-01T13:19:29.448583Z","steps":["trace[1868992800] 'process raft request' (duration: 166.093358ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:19:29.471796Z","caller":"traceutil/trace.go:171","msg":"trace[857075205] transaction","detail":"{read_only:false; response_revision:2444; number_of_response:1; }","duration":"190.895267ms","start":"2026-07-01T13:19:29.280874Z","end":"2026-07-01T13:19:29.471769Z","steps":["trace[857075205] 'process raft request' (duration: 190.850145ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:19:29.471933Z","caller":"traceutil/trace.go:171","msg":"trace[774176491] transaction","detail":"{read_only:false; response_revision:2442; number_of_response:1; }","duration":"191.685742ms","start":"2026-07-01T13:19:29.280222Z","end":"2026-07-01T13:19:29.471908Z","steps":["trace[774176491] 'process raft request' (duration: 191.332421ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T13:19:29.472097Z","caller":"traceutil/trace.go:171","msg":"trace[566660356] transaction","detail":"{read_only:false; response_revision:2443; number_of_response:1; }","duration":"191.698452ms","start":"2026-07-01T13:19:29.28037Z","end":"2026-07-01T13:19:29.472068Z","steps":["trace[566660356] 'process raft request' (duration: 191.31166ms)"],"step_count":1}