{"level":"warn","ts":"2026-07-31T07:13:58.667239Z","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-31T07:13:58.66741Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.194: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.19.213.194:2380","--initial-cluster=instance=https://199.19.213.194:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.194:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.194: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-31T07:13:58.667564Z","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-31T07:13:58.667604Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.194:2380"]} {"level":"info","ts":"2026-07-31T07:13:58.667635Z","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-31T07:13:58.669539Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.194:2379"]} {"level":"info","ts":"2026-07-31T07:13:58.669695Z","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.19.213.194:2380"],"listen-peer-urls":["https://199.19.213.194:2380"],"advertise-client-urls":["https://199.19.213.194:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.194:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.194: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-31T07:13:58.67588Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.130137ms"} {"level":"info","ts":"2026-07-31T07:13:58.683324Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1444e0f5dfca45ef","cluster-id":"46f0df2abd5471c9"} {"level":"info","ts":"2026-07-31T07:13:58.683403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef switched to configuration voters=()"} {"level":"info","ts":"2026-07-31T07:13:58.683423Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became follower at term 0"} {"level":"info","ts":"2026-07-31T07:13:58.683442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1444e0f5dfca45ef [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-31T07:13:58.683454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became follower at term 1"} {"level":"info","ts":"2026-07-31T07:13:58.683494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef switched to configuration voters=(1460539525801068015)"} {"level":"warn","ts":"2026-07-31T07:13:58.691545Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-31T07:13:58.692952Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-31T07:13:58.694158Z","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-31T07:13:58.696424Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1444e0f5dfca45ef","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-31T07:13:58.696754Z","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-31T07:13:58.696916Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1444e0f5dfca45ef","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-31T07:13:58.696938Z","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-31T07:13:58.697148Z","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-31T07:13:58.698039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef switched to configuration voters=(1460539525801068015)"} {"level":"info","ts":"2026-07-31T07:13:58.698298Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"46f0df2abd5471c9","local-member-id":"1444e0f5dfca45ef","added-peer-id":"1444e0f5dfca45ef","added-peer-peer-urls":["https://199.19.213.194:2380"]} {"level":"info","ts":"2026-07-31T07:13:58.702584Z","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-31T07:13:58.702783Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.194:2380"} {"level":"info","ts":"2026-07-31T07:13:58.702824Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.194:2380"} {"level":"info","ts":"2026-07-31T07:13:58.703135Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1444e0f5dfca45ef","initial-advertise-peer-urls":["https://199.19.213.194:2380"],"listen-peer-urls":["https://199.19.213.194:2380"],"advertise-client-urls":["https://199.19.213.194:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.194:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-31T07:13:58.703202Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-31T07:13:59.68464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef is starting a new election at term 1"} {"level":"info","ts":"2026-07-31T07:13:59.684725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became pre-candidate at term 1"} {"level":"info","ts":"2026-07-31T07:13:59.684876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef received MsgPreVoteResp from 1444e0f5dfca45ef at term 1"} {"level":"info","ts":"2026-07-31T07:13:59.684908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became candidate at term 2"} {"level":"info","ts":"2026-07-31T07:13:59.68492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef received MsgVoteResp from 1444e0f5dfca45ef at term 2"} {"level":"info","ts":"2026-07-31T07:13:59.684936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1444e0f5dfca45ef became leader at term 2"} {"level":"info","ts":"2026-07-31T07:13:59.684963Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1444e0f5dfca45ef elected leader 1444e0f5dfca45ef at term 2"} {"level":"info","ts":"2026-07-31T07:13:59.688835Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T07:13:59.690191Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"46f0df2abd5471c9","local-member-id":"1444e0f5dfca45ef","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T07:13:59.690286Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T07:13:59.690318Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T07:13:59.690356Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1444e0f5dfca45ef","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.194:2379]}","request-path":"/0/members/1444e0f5dfca45ef/attributes","cluster-id":"46f0df2abd5471c9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-31T07:13:59.690749Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-31T07:13:59.690808Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-31T07:13:59.690851Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-31T07:13:59.690869Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-31T07:13:59.694142Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.194:2379"} {"level":"info","ts":"2026-07-31T07:13:59.695634Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-31T07:14:02.577096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476066817,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:14:03.260245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.899773ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-31T07:14:03.260463Z","caller":"traceutil/trace.go:171","msg":"trace[240454292] linearizableReadLoop","detail":"{readStateIndex:92; appliedIndex:91; }","duration":"1.183387947s","start":"2026-07-31T07:14:02.077048Z","end":"2026-07-31T07:14:03.260436Z","steps":["trace[240454292] 'read index received' (duration: 914.765436ms)","trace[240454292] 'applied index is now lower than readState.Index' (duration: 268.620871ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:14:03.26057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183509521s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-31T07:14:03.260532Z","caller":"traceutil/trace.go:171","msg":"trace[1551765739] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"1.186625381s","start":"2026-07-31T07:14:02.07384Z","end":"2026-07-31T07:14:03.260466Z","steps":["trace[1551765739] 'process raft request' (duration: 918.02074ms)","trace[1551765739] 'compare' (duration: 267.746559ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:14:03.260633Z","caller":"traceutil/trace.go:171","msg":"trace[590487286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:88; }","duration":"1.183574103s","start":"2026-07-31T07:14:02.077045Z","end":"2026-07-31T07:14:03.260619Z","steps":["trace[590487286] 'agreement among raft nodes before linearized reading' (duration: 1.18346544s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:03.260678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:02.077018Z","time spent":"1.183650724s","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:14:03.260734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:02.07383Z","time spent":"1.186817765s","remote":"127.0.0.1:43814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":620,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T07:14:08.898074Z","caller":"traceutil/trace.go:171","msg":"trace[1295336638] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"335.81936ms","start":"2026-07-31T07:14:08.562236Z","end":"2026-07-31T07:14:08.898055Z","steps":["trace[1295336638] 'process raft request' (duration: 335.708717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:08.898185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:08.562214Z","time spent":"335.925273ms","remote":"127.0.0.1:43624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T07:14:09.595484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476067220,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:14:10.096388Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476067220,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:14:10.596914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476067220,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:14:10.822671Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.918288934s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-31T07:14:12.458747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635749811s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:14:12.458819Z","caller":"traceutil/trace.go:171","msg":"trace[496934875] linearizableReadLoop","detail":"{readStateIndex:290; appliedIndex:289; }","duration":"3.36381156s","start":"2026-07-31T07:14:09.094994Z","end":"2026-07-31T07:14:12.458806Z","steps":["trace[496934875] 'read index received' (duration: 1.727841723s)","trace[496934875] 'applied index is now lower than readState.Index' (duration: 1.635969057s)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:14:12.45892Z","caller":"traceutil/trace.go:171","msg":"trace[1310127009] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"3.55461465s","start":"2026-07-31T07:14:08.904296Z","end":"2026-07-31T07:14:12.45891Z","steps":["trace[1310127009] 'process raft request' (duration: 1.918654944s)","trace[1310127009] 'compare' (duration: 1.635603498s)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:14:12.458959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:08.904282Z","time spent":"3.554658471s","remote":"127.0.0.1:43624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T07:14:12.459117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.364128057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-31T07:14:12.459139Z","caller":"traceutil/trace.go:171","msg":"trace[1938539138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:281; }","duration":"3.364194878s","start":"2026-07-31T07:14:09.094936Z","end":"2026-07-31T07:14:12.459131Z","steps":["trace[1938539138] 'agreement among raft nodes before linearized reading' (duration: 3.364125807s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:12.459158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:09.094915Z","time spent":"3.364237809s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T07:14:12.959649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476067223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:14:13.460196Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476067223,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:14:13.598817Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.111099728s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-31T07:14:14.057092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.755123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:14:14.05726Z","caller":"traceutil/trace.go:171","msg":"trace[371904868] linearizableReadLoop","detail":"{readStateIndex:292; appliedIndex:290; }","duration":"1.598409229s","start":"2026-07-31T07:14:12.458835Z","end":"2026-07-31T07:14:14.057244Z","steps":["trace[371904868] 'read index received' (duration: 1.140320628s)","trace[371904868] 'applied index is now lower than readState.Index' (duration: 458.087551ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:14:14.057363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.434454283s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-31T07:14:14.057398Z","caller":"traceutil/trace.go:171","msg":"trace[122428054] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:283; }","duration":"4.434517015s","start":"2026-07-31T07:14:09.622872Z","end":"2026-07-31T07:14:14.057389Z","steps":["trace[122428054] 'agreement among raft nodes before linearized reading' (duration: 4.434460104s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:14.057435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:09.622853Z","time spent":"4.434573236s","remote":"127.0.0.1:43704","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-31T07:14:14.057476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.723465685s","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-31T07:14:14.057568Z","caller":"traceutil/trace.go:171","msg":"trace[278767696] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"2.569911065s","start":"2026-07-31T07:14:11.487624Z","end":"2026-07-31T07:14:14.057535Z","steps":["trace[278767696] 'process raft request' (duration: 2.111425935s)","trace[278767696] 'compare' (duration: 457.60735ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:14:14.057607Z","caller":"traceutil/trace.go:171","msg":"trace[724559875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:283; }","duration":"4.72362591s","start":"2026-07-31T07:14:09.333932Z","end":"2026-07-31T07:14:14.057558Z","steps":["trace[724559875] 'agreement among raft nodes before linearized reading' (duration: 4.723418375s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:14.057595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.596143794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-07-31T07:14:14.057649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:09.33391Z","time spent":"4.723730342s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-31T07:14:14.057702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:11.487598Z","time spent":"2.570030388s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:14:14.0577Z","caller":"traceutil/trace.go:171","msg":"trace[451832057] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:1; response_revision:283; }","duration":"1.596320039s","start":"2026-07-31T07:14:12.461365Z","end":"2026-07-31T07:14:14.057685Z","steps":["trace[451832057] 'agreement among raft nodes before linearized reading' (duration: 1.596144265s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:14.057817Z","caller":"traceutil/trace.go:171","msg":"trace[1177009350] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"1.595973651s","start":"2026-07-31T07:14:12.461828Z","end":"2026-07-31T07:14:14.057802Z","steps":["trace[1177009350] 'process raft request' (duration: 1.595365227s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:14.057828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.320413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.194\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-31T07:14:14.057891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.686035124s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4326"} {"level":"warn","ts":"2026-07-31T07:14:14.057911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:12.461806Z","time spent":"1.596058753s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:14:14.05794Z","caller":"traceutil/trace.go:171","msg":"trace[642860822] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:283; }","duration":"4.686088005s","start":"2026-07-31T07:14:09.371822Z","end":"2026-07-31T07:14:14.05791Z","steps":["trace[642860822] 'agreement among raft nodes before linearized reading' (duration: 4.685998903s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:14.057978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:09.371807Z","time spent":"4.686153137s","remote":"127.0.0.1:43610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":4348,"request content":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" "} {"level":"warn","ts":"2026-07-31T07:14:14.057986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.71828675s","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-31T07:14:14.057326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.410360016s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:14:14.058166Z","caller":"traceutil/trace.go:171","msg":"trace[1637457786] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:283; }","duration":"3.411197875s","start":"2026-07-31T07:14:10.646947Z","end":"2026-07-31T07:14:14.058145Z","steps":["trace[1637457786] 'agreement among raft nodes before linearized reading' (duration: 3.410308455s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:14.05818Z","caller":"traceutil/trace.go:171","msg":"trace[1944072654] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:283; }","duration":"4.718507495s","start":"2026-07-31T07:14:09.33961Z","end":"2026-07-31T07:14:14.058117Z","steps":["trace[1944072654] 'agreement among raft nodes before linearized reading' (duration: 4.718242719s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:14.05831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:09.339585Z","time spent":"4.71869488s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-31T07:14:14.059253Z","caller":"traceutil/trace.go:171","msg":"trace[538629018] range","detail":"{range_begin:/registry/masterleases/199.19.213.194; range_end:; response_count:1; response_revision:283; }","duration":"180.811527ms","start":"2026-07-31T07:14:13.878419Z","end":"2026-07-31T07:14:14.059231Z","steps":["trace[538629018] 'agreement among raft nodes before linearized reading' (duration: 179.272131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:14.057831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:12.461346Z","time spent":"1.596472033s","remote":"127.0.0.1:43624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-07-31T07:14:14.781346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.400092ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-07-31T07:14:14.781451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:14.060204Z","time spent":"721.242353ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-31T07:14:14.785634Z","caller":"traceutil/trace.go:171","msg":"trace[878442640] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"723.219169ms","start":"2026-07-31T07:14:14.062381Z","end":"2026-07-31T07:14:14.7856Z","steps":["trace[878442640] 'process raft request' (duration: 722.442922ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:14.785902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"723.313471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:14:14.785938Z","caller":"traceutil/trace.go:171","msg":"trace[1749607321] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/statefulset-controller; range_end:; response_count:0; response_revision:288; }","duration":"723.386063ms","start":"2026-07-31T07:14:14.062544Z","end":"2026-07-31T07:14:14.78593Z","steps":["trace[1749607321] 'agreement among raft nodes before linearized reading' (duration: 723.310221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:14.785961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:14.062534Z","time spent":"723.421873ms","remote":"127.0.0.1:43624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/statefulset-controller\" "} {"level":"info","ts":"2026-07-31T07:14:14.785692Z","caller":"traceutil/trace.go:171","msg":"trace[197733126] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"722.123824ms","start":"2026-07-31T07:14:14.063546Z","end":"2026-07-31T07:14:14.78567Z","steps":["trace[197733126] 'process raft request' (duration: 722.008581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:14.78624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:14.063529Z","time spent":"722.669907ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:14:14.785786Z","caller":"traceutil/trace.go:171","msg":"trace[1880943958] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"720.615489ms","start":"2026-07-31T07:14:14.065154Z","end":"2026-07-31T07:14:14.78577Z","steps":["trace[1880943958] 'process raft request' (duration: 720.445965ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:14.785821Z","caller":"traceutil/trace.go:171","msg":"trace[1571715552] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"722.271688ms","start":"2026-07-31T07:14:14.063342Z","end":"2026-07-31T07:14:14.785614Z","steps":["trace[1571715552] 'process raft request' (duration: 722.158335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:14.786444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:14.065145Z","time spent":"721.203102ms","remote":"127.0.0.1:43610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4311,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:14:14.786519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:14.063329Z","time spent":"723.164788ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:14:14.785834Z","caller":"traceutil/trace.go:171","msg":"trace[469240944] linearizableReadLoop","detail":"{readStateIndex:294; appliedIndex:292; }","duration":"723.214549ms","start":"2026-07-31T07:14:14.062578Z","end":"2026-07-31T07:14:14.785793Z","steps":["trace[469240944] 'read index received' (duration: 109.499817ms)","trace[469240944] 'applied index is now lower than readState.Index' (duration: 613.713492ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:14:14.786664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.23919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:14:14.786699Z","caller":"traceutil/trace.go:171","msg":"trace[1440553947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:288; }","duration":"372.331692ms","start":"2026-07-31T07:14:14.41436Z","end":"2026-07-31T07:14:14.786692Z","steps":["trace[1440553947] 'agreement among raft nodes before linearized reading' (duration: 372.287081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:14.786717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:14.414341Z","time spent":"372.371933ms","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:14:14.78804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:14.062359Z","time spent":"723.554906ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:14:21.042642Z","caller":"traceutil/trace.go:171","msg":"trace[310470077] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"126.338307ms","start":"2026-07-31T07:14:20.916276Z","end":"2026-07-31T07:14:21.042614Z","steps":["trace[310470077] 'process raft request' (duration: 124.891766ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:26.057767Z","caller":"traceutil/trace.go:171","msg":"trace[929493053] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"107.123672ms","start":"2026-07-31T07:14:25.950614Z","end":"2026-07-31T07:14:26.057738Z","steps":["trace[929493053] 'process raft request' (duration: 77.685435ms)","trace[929493053] 'compare' (duration: 29.237683ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:14:26.273247Z","caller":"traceutil/trace.go:171","msg":"trace[1629407856] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"161.498243ms","start":"2026-07-31T07:14:26.111723Z","end":"2026-07-31T07:14:26.273221Z","steps":["trace[1629407856] 'process raft request' (duration: 138.586632ms)","trace[1629407856] 'compare' (duration: 22.355668ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:14:37.124919Z","caller":"traceutil/trace.go:171","msg":"trace[1487118979] linearizableReadLoop","detail":"{readStateIndex:469; appliedIndex:468; }","duration":"128.957881ms","start":"2026-07-31T07:14:36.995941Z","end":"2026-07-31T07:14:37.124899Z","steps":["trace[1487118979] 'read index received' (duration: 128.819599ms)","trace[1487118979] 'applied index is now lower than readState.Index' (duration: 137.352µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:14:37.125127Z","caller":"traceutil/trace.go:171","msg":"trace[261945761] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"141.953404ms","start":"2026-07-31T07:14:36.983162Z","end":"2026-07-31T07:14:37.125115Z","steps":["trace[261945761] 'process raft request' (duration: 141.609608ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:37.125455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.489992ms","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-31T07:14:37.125554Z","caller":"traceutil/trace.go:171","msg":"trace[1033113974] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:452; }","duration":"129.612045ms","start":"2026-07-31T07:14:36.995917Z","end":"2026-07-31T07:14:37.125529Z","steps":["trace[1033113974] 'agreement among raft nodes before linearized reading' (duration: 129.473223ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:37.317179Z","caller":"traceutil/trace.go:171","msg":"trace[766272703] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"186.365369ms","start":"2026-07-31T07:14:37.130789Z","end":"2026-07-31T07:14:37.317155Z","steps":["trace[766272703] 'process raft request' (duration: 110.691258ms)","trace[766272703] 'compare' (duration: 75.59151ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:14:43.404012Z","caller":"traceutil/trace.go:171","msg":"trace[301901073] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"149.390949ms","start":"2026-07-31T07:14:43.254598Z","end":"2026-07-31T07:14:43.403989Z","steps":["trace[301901073] 'process raft request' (duration: 148.683906ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:43.404142Z","caller":"traceutil/trace.go:171","msg":"trace[1906199535] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"145.068839ms","start":"2026-07-31T07:14:43.259064Z","end":"2026-07-31T07:14:43.404133Z","steps":["trace[1906199535] 'process raft request' (duration: 144.357026ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:43.404236Z","caller":"traceutil/trace.go:171","msg":"trace[1111574830] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"136.056643ms","start":"2026-07-31T07:14:43.268168Z","end":"2026-07-31T07:14:43.404224Z","steps":["trace[1111574830] 'process raft request' (duration: 135.504882ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:43.404282Z","caller":"traceutil/trace.go:171","msg":"trace[23740815] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"141.038023ms","start":"2026-07-31T07:14:43.263237Z","end":"2026-07-31T07:14:43.404275Z","steps":["trace[23740815] 'process raft request' (duration: 140.242789ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:43.404219Z","caller":"traceutil/trace.go:171","msg":"trace[1768890979] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"138.641801ms","start":"2026-07-31T07:14:43.265535Z","end":"2026-07-31T07:14:43.404177Z","steps":["trace[1768890979] 'process raft request' (duration: 138.099561ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:43.404355Z","caller":"traceutil/trace.go:171","msg":"trace[1723942984] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"130.372127ms","start":"2026-07-31T07:14:43.273976Z","end":"2026-07-31T07:14:43.404348Z","steps":["trace[1723942984] 'process raft request' (duration: 129.738135ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:43.404322Z","caller":"traceutil/trace.go:171","msg":"trace[888115704] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"115.022692ms","start":"2026-07-31T07:14:43.289286Z","end":"2026-07-31T07:14:43.404308Z","steps":["trace[888115704] 'process raft request' (duration: 114.483192ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:43.40441Z","caller":"traceutil/trace.go:171","msg":"trace[1932953701] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"141.129636ms","start":"2026-07-31T07:14:43.263274Z","end":"2026-07-31T07:14:43.404403Z","steps":["trace[1932953701] 'process raft request' (duration: 140.26748ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:43.40443Z","caller":"traceutil/trace.go:171","msg":"trace[1338309658] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"139.534716ms","start":"2026-07-31T07:14:43.26489Z","end":"2026-07-31T07:14:43.404425Z","steps":["trace[1338309658] 'process raft request' (duration: 138.698091ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:44.101101Z","caller":"traceutil/trace.go:171","msg":"trace[47171773] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"122.207864ms","start":"2026-07-31T07:14:43.978876Z","end":"2026-07-31T07:14:44.101084Z","steps":["trace[47171773] 'process raft request' (duration: 122.122342ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:44.101372Z","caller":"traceutil/trace.go:171","msg":"trace[1503957798] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"141.373199ms","start":"2026-07-31T07:14:43.959942Z","end":"2026-07-31T07:14:44.101315Z","steps":["trace[1503957798] 'process raft request' (duration: 85.613552ms)","trace[1503957798] 'compare' (duration: 55.287659ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:14:44.884632Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476067748,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:14:45.385611Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476067748,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:14:45.579655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.778482ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:14:45.579792Z","caller":"traceutil/trace.go:171","msg":"trace[253266066] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:560; }","duration":"770.939965ms","start":"2026-07-31T07:14:44.808834Z","end":"2026-07-31T07:14:45.579774Z","steps":["trace[253266066] 'range keys from in-memory index tree' (duration: 770.761742ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:45.580109Z","caller":"traceutil/trace.go:171","msg":"trace[252633901] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"1.454548683s","start":"2026-07-31T07:14:44.125524Z","end":"2026-07-31T07:14:45.580073Z","steps":["trace[252633901] 'process raft request' (duration: 1.443762515s)","trace[252633901] 'compare' (duration: 10.234897ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:14:45.580321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:44.125501Z","time spent":"1.454727985s","remote":"127.0.0.1:43496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18041,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:14:45.580747Z","caller":"traceutil/trace.go:171","msg":"trace[1568245220] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"1.453718916s","start":"2026-07-31T07:14:44.126991Z","end":"2026-07-31T07:14:45.58071Z","steps":["trace[1568245220] 'process raft request' (duration: 1.452943902s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:45.580837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:44.126983Z","time spent":"1.453808599s","remote":"127.0.0.1:43496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:14:45.581143Z","caller":"traceutil/trace.go:171","msg":"trace[44216556] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:583; }","duration":"1.197106321s","start":"2026-07-31T07:14:44.383999Z","end":"2026-07-31T07:14:45.581105Z","steps":["trace[44216556] 'read index received' (duration: 1.185311065s)","trace[44216556] 'applied index is now lower than readState.Index' (duration: 11.794096ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:14:45.581157Z","caller":"traceutil/trace.go:171","msg":"trace[521761269] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.404078792s","start":"2026-07-31T07:14:44.177038Z","end":"2026-07-31T07:14:45.581116Z","steps":["trace[521761269] 'process raft request' (duration: 1.403464341s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:45.581229Z","caller":"traceutil/trace.go:171","msg":"trace[616172014] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"968.856449ms","start":"2026-07-31T07:14:44.612315Z","end":"2026-07-31T07:14:45.581171Z","steps":["trace[616172014] 'process raft request' (duration: 968.708287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:45.581332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:44.177005Z","time spent":"1.404253395s","remote":"127.0.0.1:43496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T07:14:45.581404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.36463ms","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-31T07:14:45.581345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.150490144s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T07:14:45.581411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.450254ms","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-31T07:14:45.581442Z","caller":"traceutil/trace.go:171","msg":"trace[297835682] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:564; }","duration":"408.436371ms","start":"2026-07-31T07:14:45.172995Z","end":"2026-07-31T07:14:45.581431Z","steps":["trace[297835682] 'agreement among raft nodes before linearized reading' (duration: 408.37576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:45.581377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.947332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-31T07:14:45.581436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:44.612289Z","time spent":"969.057793ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:14:45.581473Z","caller":"traceutil/trace.go:171","msg":"trace[194382179] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:564; }","duration":"377.548616ms","start":"2026-07-31T07:14:45.203909Z","end":"2026-07-31T07:14:45.581457Z","steps":["trace[194382179] 'agreement among raft nodes before linearized reading' (duration: 377.447585ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:45.581488Z","caller":"traceutil/trace.go:171","msg":"trace[1023947300] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:564; }","duration":"151.105324ms","start":"2026-07-31T07:14:45.430369Z","end":"2026-07-31T07:14:45.581475Z","steps":["trace[1023947300] 'agreement among raft nodes before linearized reading' (duration: 150.933871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:45.581485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:45.172974Z","time spent":"408.502673ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-31T07:14:45.581526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:45.203889Z","time spent":"377.628708ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-31T07:14:45.581477Z","caller":"traceutil/trace.go:171","msg":"trace[1850625654] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:564; }","duration":"1.150661766s","start":"2026-07-31T07:14:44.43079Z","end":"2026-07-31T07:14:45.581452Z","steps":["trace[1850625654] 'agreement among raft nodes before linearized reading' (duration: 1.150494133s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:45.581605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:44.430772Z","time spent":"1.150825s","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:14:45.581959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.197648972s","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-31T07:14:45.58208Z","caller":"traceutil/trace.go:171","msg":"trace[522019717] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:564; }","duration":"1.198106869s","start":"2026-07-31T07:14:44.383946Z","end":"2026-07-31T07:14:45.582053Z","steps":["trace[522019717] 'agreement among raft nodes before linearized reading' (duration: 1.197268314s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:45.582139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:44.383923Z","time spent":"1.198197631s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-31T07:14:52.459822Z","caller":"traceutil/trace.go:171","msg":"trace[1189573436] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"436.801073ms","start":"2026-07-31T07:14:52.02298Z","end":"2026-07-31T07:14:52.459781Z","steps":["trace[1189573436] 'process raft request' (duration: 436.571039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:52.459993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:52.022942Z","time spent":"436.968956ms","remote":"127.0.0.1:43610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:14:54.790881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:53.883023Z","time spent":"907.854158ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-31T07:14:54.790904Z","caller":"traceutil/trace.go:171","msg":"trace[690241938] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:650; }","duration":"360.478342ms","start":"2026-07-31T07:14:54.430391Z","end":"2026-07-31T07:14:54.790869Z","steps":["trace[690241938] 'read index received' (duration: 360.469522ms)","trace[690241938] 'applied index is now lower than readState.Index' (duration: 6.96µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:14:54.791036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.625464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:14:54.791088Z","caller":"traceutil/trace.go:171","msg":"trace[1430377288] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"360.722476ms","start":"2026-07-31T07:14:54.430352Z","end":"2026-07-31T07:14:54.791074Z","steps":["trace[1430377288] 'agreement among raft nodes before linearized reading' (duration: 360.614915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:54.791131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:54.430332Z","time spent":"360.788997ms","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:14:55.932481Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476067960,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:14:56.433586Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476067960,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:14:56.79538Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.003232414s","expected-duration":"1s"} {"level":"info","ts":"2026-07-31T07:14:56.8675Z","caller":"traceutil/trace.go:171","msg":"trace[1537066033] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"2.075397247s","start":"2026-07-31T07:14:54.792062Z","end":"2026-07-31T07:14:56.867459Z","steps":["trace[1537066033] 'process raft request' (duration: 2.004523507s)","trace[1537066033] 'compare' (duration: 70.733318ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:14:56.86765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:54.792049Z","time spent":"2.075528119s","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:14:57.067684Z","caller":"traceutil/trace.go:171","msg":"trace[1362051250] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"118.872284ms","start":"2026-07-31T07:14:56.948791Z","end":"2026-07-31T07:14:57.067663Z","steps":["trace[1362051250] 'process raft request' (duration: 118.836653ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:57.067877Z","caller":"traceutil/trace.go:171","msg":"trace[1253519898] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:650; }","duration":"1.635666602s","start":"2026-07-31T07:14:55.4322Z","end":"2026-07-31T07:14:57.067867Z","steps":["trace[1253519898] 'read index received' (duration: 1.364433897s)","trace[1253519898] 'applied index is now lower than readState.Index' (duration: 271.231945ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:14:57.067923Z","caller":"traceutil/trace.go:171","msg":"trace[1951860110] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.415395277s","start":"2026-07-31T07:14:55.652491Z","end":"2026-07-31T07:14:57.067886Z","steps":["trace[1951860110] 'process raft request' (duration: 1.394127856s)","trace[1951860110] 'compare' (duration: 20.756532ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:14:57.068005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635796355s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:14:57.068035Z","caller":"traceutil/trace.go:171","msg":"trace[334215959] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"1.635869176s","start":"2026-07-31T07:14:55.432159Z","end":"2026-07-31T07:14:57.068028Z","steps":["trace[334215959] 'agreement among raft nodes before linearized reading' (duration: 1.635792365s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:57.068055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:55.431952Z","time spent":"1.63609804s","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:14:57.068054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:55.652469Z","time spent":"1.415499469s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:14:57.06828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.345868673s","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-31T07:14:57.068318Z","caller":"traceutil/trace.go:171","msg":"trace[1390357548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:628; }","duration":"1.345916974s","start":"2026-07-31T07:14:55.722388Z","end":"2026-07-31T07:14:57.068305Z","steps":["trace[1390357548] 'agreement among raft nodes before linearized reading' (duration: 1.345794251s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:57.068295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.390917257s","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-31T07:14:57.068296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34690287s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-31T07:14:57.068347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:55.722378Z","time spent":"1.345962624s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-31T07:14:57.068374Z","caller":"traceutil/trace.go:171","msg":"trace[1985855968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:628; }","duration":"1.391021789s","start":"2026-07-31T07:14:55.677329Z","end":"2026-07-31T07:14:57.068351Z","steps":["trace[1985855968] 'agreement among raft nodes before linearized reading' (duration: 1.390819584s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:57.068418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:55.677313Z","time spent":"1.391096481s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-31T07:14:57.068374Z","caller":"traceutil/trace.go:171","msg":"trace[705618292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:628; }","duration":"1.347023723s","start":"2026-07-31T07:14:55.721335Z","end":"2026-07-31T07:14:57.068358Z","steps":["trace[705618292] 'agreement among raft nodes before linearized reading' (duration: 1.346849349s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:57.068642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:55.721314Z","time spent":"1.347315817s","remote":"127.0.0.1:43704","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-31T07:14:57.068318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.737069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-31T07:14:57.068718Z","caller":"traceutil/trace.go:171","msg":"trace[1549288626] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:628; }","duration":"199.167105ms","start":"2026-07-31T07:14:56.869536Z","end":"2026-07-31T07:14:57.068703Z","steps":["trace[1549288626] 'agreement among raft nodes before linearized reading' (duration: 198.744658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:57.068465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384341494s","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-31T07:14:57.068804Z","caller":"traceutil/trace.go:171","msg":"trace[1785573465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"1.38467895s","start":"2026-07-31T07:14:55.68411Z","end":"2026-07-31T07:14:57.068789Z","steps":["trace[1785573465] 'agreement among raft nodes before linearized reading' (duration: 1.384240643s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:57.068843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:55.684061Z","time spent":"1.384771612s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-31T07:14:59.150806Z","caller":"traceutil/trace.go:171","msg":"trace[1542600993] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"148.732097ms","start":"2026-07-31T07:14:59.00205Z","end":"2026-07-31T07:14:59.150782Z","steps":["trace[1542600993] 'process raft request' (duration: 96.112324ms)","trace[1542600993] 'compare' (duration: 52.500931ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:14:59.381442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.886496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:14:59.381564Z","caller":"traceutil/trace.go:171","msg":"trace[789562691] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"230.345897ms","start":"2026-07-31T07:14:59.151208Z","end":"2026-07-31T07:14:59.381554Z","steps":["trace[789562691] 'read index received' (duration: 105.258578ms)","trace[789562691] 'applied index is now lower than readState.Index' (duration: 125.086279ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:14:59.381606Z","caller":"traceutil/trace.go:171","msg":"trace[1469825441] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"240.253962ms","start":"2026-07-31T07:14:59.141318Z","end":"2026-07-31T07:14:59.381572Z","steps":["trace[1469825441] 'process raft request' (duration: 115.159173ms)","trace[1469825441] 'compare' (duration: 124.724383ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:14:59.381659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.406533ms","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-31T07:14:59.381732Z","caller":"traceutil/trace.go:171","msg":"trace[887561367] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"303.531785ms","start":"2026-07-31T07:14:59.078192Z","end":"2026-07-31T07:14:59.381724Z","steps":["trace[887561367] 'agreement among raft nodes before linearized reading' (duration: 303.401202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:59.381784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:59.078177Z","time spent":"303.582436ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T07:14:59.381897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.476873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-31T07:14:59.381871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.534764ms","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-31T07:14:59.38201Z","caller":"traceutil/trace.go:171","msg":"trace[1729992244] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:650; }","duration":"294.721928ms","start":"2026-07-31T07:14:59.087265Z","end":"2026-07-31T07:14:59.381987Z","steps":["trace[1729992244] 'agreement among raft nodes before linearized reading' (duration: 294.336092ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:59.381938Z","caller":"traceutil/trace.go:171","msg":"trace[511791599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"294.570845ms","start":"2026-07-31T07:14:59.087357Z","end":"2026-07-31T07:14:59.381927Z","steps":["trace[511791599] 'agreement among raft nodes before linearized reading' (duration: 294.410982ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:59.382164Z","caller":"traceutil/trace.go:171","msg":"trace[1027188510] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"217.976489ms","start":"2026-07-31T07:14:59.164177Z","end":"2026-07-31T07:14:59.382153Z","steps":["trace[1027188510] 'process raft request' (duration: 217.922088ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:59.382271Z","caller":"traceutil/trace.go:171","msg":"trace[872953160] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"226.698866ms","start":"2026-07-31T07:14:59.155542Z","end":"2026-07-31T07:14:59.382241Z","steps":["trace[872953160] 'process raft request' (duration: 226.463071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:59.382831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.862837ms","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-31T07:14:59.382869Z","caller":"traceutil/trace.go:171","msg":"trace[1453573237] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:650; }","duration":"295.944888ms","start":"2026-07-31T07:14:59.086915Z","end":"2026-07-31T07:14:59.38286Z","steps":["trace[1453573237] 'agreement among raft nodes before linearized reading' (duration: 294.997872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:59.488365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.907884ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-31T07:14:59.488563Z","caller":"traceutil/trace.go:171","msg":"trace[1798512839] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:677; }","duration":"101.525415ms","start":"2026-07-31T07:14:59.387025Z","end":"2026-07-31T07:14:59.48855Z","steps":["trace[1798512839] 'read index received' (duration: 31.231µs)","trace[1798512839] 'applied index is now lower than readState.Index' (duration: 101.493514ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:14:59.48872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.683778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-7zdx2\" ","response":"range_response_count:1 size:4683"} {"level":"info","ts":"2026-07-31T07:14:59.488752Z","caller":"traceutil/trace.go:171","msg":"trace[357031106] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-7zdx2; range_end:; response_count:1; response_revision:653; }","duration":"101.749199ms","start":"2026-07-31T07:14:59.386995Z","end":"2026-07-31T07:14:59.488744Z","steps":["trace[357031106] 'agreement among raft nodes before linearized reading' (duration: 101.599745ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:59.488692Z","caller":"traceutil/trace.go:171","msg":"trace[1375891486] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"148.35495ms","start":"2026-07-31T07:14:59.340301Z","end":"2026-07-31T07:14:59.488656Z","steps":["trace[1375891486] 'process raft request' (duration: 148.152466ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:59.51375Z","caller":"traceutil/trace.go:171","msg":"trace[16909795] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"125.298332ms","start":"2026-07-31T07:14:59.388433Z","end":"2026-07-31T07:14:59.513731Z","steps":["trace[16909795] 'process raft request' (duration: 125.183881ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:59.778911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.019472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:14:59.779146Z","caller":"traceutil/trace.go:171","msg":"trace[1862549314] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"390.23473ms","start":"2026-07-31T07:14:59.388876Z","end":"2026-07-31T07:14:59.77911Z","steps":["trace[1862549314] 'process raft request' (duration: 212.930334ms)","trace[1862549314] 'compare' (duration: 176.867559ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:14:59.779316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:59.388858Z","time spent":"390.368332ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:14:59.779375Z","caller":"traceutil/trace.go:171","msg":"trace[1508853932] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"390.21402ms","start":"2026-07-31T07:14:59.389135Z","end":"2026-07-31T07:14:59.779349Z","steps":["trace[1508853932] 'process raft request' (duration: 389.874205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:59.779513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:59.389117Z","time spent":"390.319642ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:14:59.779546Z","caller":"traceutil/trace.go:171","msg":"trace[1722678578] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"281.8286ms","start":"2026-07-31T07:14:59.4977Z","end":"2026-07-31T07:14:59.779528Z","steps":["trace[1722678578] 'process raft request' (duration: 281.729549ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:59.779622Z","caller":"traceutil/trace.go:171","msg":"trace[1357559162] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"390.011796ms","start":"2026-07-31T07:14:59.389583Z","end":"2026-07-31T07:14:59.779595Z","steps":["trace[1357559162] 'process raft request' (duration: 389.62814ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:14:59.77965Z","caller":"traceutil/trace.go:171","msg":"trace[768843949] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"389.970566ms","start":"2026-07-31T07:14:59.389663Z","end":"2026-07-31T07:14:59.779634Z","steps":["trace[768843949] 'process raft request' (duration: 389.694341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:59.779757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:59.389563Z","time spent":"390.117429ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:14:59.779796Z","caller":"traceutil/trace.go:171","msg":"trace[267742940] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"237.557526ms","start":"2026-07-31T07:14:59.542226Z","end":"2026-07-31T07:14:59.779783Z","steps":["trace[267742940] 'process raft request' (duration: 237.254442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:14:59.779745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:59.389656Z","time spent":"390.035567ms","remote":"127.0.0.1:43496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9296,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:15:00.009191Z","caller":"traceutil/trace.go:171","msg":"trace[303361860] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"265.105739ms","start":"2026-07-31T07:14:59.744068Z","end":"2026-07-31T07:15:00.009174Z","steps":["trace[303361860] 'process raft request' (duration: 265.008448ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:00.467663Z","caller":"traceutil/trace.go:171","msg":"trace[749060306] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"680.725893ms","start":"2026-07-31T07:14:59.786904Z","end":"2026-07-31T07:15:00.46763Z","steps":["trace[749060306] 'process raft request' (duration: 611.477347ms)","trace[749060306] 'compare' (duration: 68.940151ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:15:00.467809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:59.786882Z","time spent":"680.851846ms","remote":"127.0.0.1:43496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:15:00.467841Z","caller":"traceutil/trace.go:171","msg":"trace[2079612020] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"680.374147ms","start":"2026-07-31T07:14:59.787444Z","end":"2026-07-31T07:15:00.467818Z","steps":["trace[2079612020] 'process raft request' (duration: 680.096203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:00.467974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:59.787433Z","time spent":"680.46849ms","remote":"127.0.0.1:43958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4387,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:15:00.468103Z","caller":"traceutil/trace.go:171","msg":"trace[1768983890] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"680.357668ms","start":"2026-07-31T07:14:59.787736Z","end":"2026-07-31T07:15:00.468094Z","steps":["trace[1768983890] 'process raft request' (duration: 680.013362ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:00.468137Z","caller":"traceutil/trace.go:171","msg":"trace[1353820387] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"535.957013ms","start":"2026-07-31T07:14:59.932159Z","end":"2026-07-31T07:15:00.468116Z","steps":["trace[1353820387] 'process raft request' (duration: 535.814091ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:00.468152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:59.787729Z","time spent":"680.395368ms","remote":"127.0.0.1:43496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:15:00.468235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:59.932131Z","time spent":"536.056985ms","remote":"127.0.0.1:43520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T07:15:00.468272Z","caller":"traceutil/trace.go:171","msg":"trace[1305530688] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:686; }","duration":"536.182357ms","start":"2026-07-31T07:14:59.932073Z","end":"2026-07-31T07:15:00.468255Z","steps":["trace[1305530688] 'read index received' (duration: 77.046072ms)","trace[1305530688] 'applied index is now lower than readState.Index' (duration: 459.135085ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:15:00.468359Z","caller":"traceutil/trace.go:171","msg":"trace[145890635] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"529.078118ms","start":"2026-07-31T07:14:59.939266Z","end":"2026-07-31T07:15:00.468344Z","steps":["trace[145890635] 'process raft request' (duration: 528.815604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:00.468429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:59.939239Z","time spent":"529.15021ms","remote":"127.0.0.1:43496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T07:15:00.46844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.357419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:15:00.468503Z","caller":"traceutil/trace.go:171","msg":"trace[113481888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:666; }","duration":"536.447651ms","start":"2026-07-31T07:14:59.932037Z","end":"2026-07-31T07:15:00.468485Z","steps":["trace[113481888] 'agreement among raft nodes before linearized reading' (duration: 536.33485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:00.468551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:14:59.93202Z","time spent":"536.518332ms","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-31T07:15:00.484058Z","caller":"traceutil/trace.go:171","msg":"trace[1729781484] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"344.310987ms","start":"2026-07-31T07:15:00.139729Z","end":"2026-07-31T07:15:00.48404Z","steps":["trace[1729781484] 'process raft request' (duration: 344.024363ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:00.484114Z","caller":"traceutil/trace.go:171","msg":"trace[171624372] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"143.374643ms","start":"2026-07-31T07:15:00.340729Z","end":"2026-07-31T07:15:00.484103Z","steps":["trace[171624372] 'process raft request' (duration: 143.14817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:00.48417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:00.139702Z","time spent":"344.41871ms","remote":"127.0.0.1:43496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T07:15:00.717911Z","caller":"traceutil/trace.go:171","msg":"trace[1506620267] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"170.300822ms","start":"2026-07-31T07:15:00.547587Z","end":"2026-07-31T07:15:00.717888Z","steps":["trace[1506620267] 'process raft request' (duration: 170.1473ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:00.880381Z","caller":"traceutil/trace.go:171","msg":"trace[328407271] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"154.647461ms","start":"2026-07-31T07:15:00.725711Z","end":"2026-07-31T07:15:00.880359Z","steps":["trace[328407271] 'process raft request' (duration: 99.022272ms)","trace[328407271] 'compare' (duration: 55.532438ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:15:00.881726Z","caller":"traceutil/trace.go:171","msg":"trace[2126274527] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:700; }","duration":"140.869761ms","start":"2026-07-31T07:15:00.740834Z","end":"2026-07-31T07:15:00.881703Z","steps":["trace[2126274527] 'read index received' (duration: 83.855929ms)","trace[2126274527] 'applied index is now lower than readState.Index' (duration: 57.012842ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:15:00.881933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.077355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:15:00.882014Z","caller":"traceutil/trace.go:171","msg":"trace[1657188466] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:676; }","duration":"141.193687ms","start":"2026-07-31T07:15:00.740807Z","end":"2026-07-31T07:15:00.882Z","steps":["trace[1657188466] 'agreement among raft nodes before linearized reading' (duration: 141.077465ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:00.881841Z","caller":"traceutil/trace.go:171","msg":"trace[1816510152] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"147.334339ms","start":"2026-07-31T07:15:00.734429Z","end":"2026-07-31T07:15:00.881763Z","steps":["trace[1816510152] 'process raft request' (duration: 147.157386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:00.882312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.402774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-31T07:15:00.882386Z","caller":"traceutil/trace.go:171","msg":"trace[806223576] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:676; }","duration":"125.511075ms","start":"2026-07-31T07:15:00.756859Z","end":"2026-07-31T07:15:00.88237Z","steps":["trace[806223576] 'agreement among raft nodes before linearized reading' (duration: 125.319112ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:01.002891Z","caller":"traceutil/trace.go:171","msg":"trace[2021546079] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"113.969682ms","start":"2026-07-31T07:15:00.888906Z","end":"2026-07-31T07:15:01.002875Z","steps":["trace[2021546079] 'process raft request' (duration: 113.889341ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:01.00289Z","caller":"traceutil/trace.go:171","msg":"trace[27901034] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"116.330951ms","start":"2026-07-31T07:15:00.886528Z","end":"2026-07-31T07:15:01.002859Z","steps":["trace[27901034] 'process raft request' (duration: 100.859003ms)","trace[27901034] 'compare' (duration: 15.283585ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:15:01.168527Z","caller":"traceutil/trace.go:171","msg":"trace[492862000] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"160.423762ms","start":"2026-07-31T07:15:01.008065Z","end":"2026-07-31T07:15:01.168489Z","steps":["trace[492862000] 'process raft request' (duration: 158.680954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:01.859197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.340685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:15:01.859306Z","caller":"traceutil/trace.go:171","msg":"trace[697677686] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"120.946317ms","start":"2026-07-31T07:15:01.738341Z","end":"2026-07-31T07:15:01.859287Z","steps":["trace[697677686] 'read index received' (duration: 5.352438ms)","trace[697677686] 'applied index is now lower than readState.Index' (duration: 115.583359ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:15:01.859336Z","caller":"traceutil/trace.go:171","msg":"trace[1911607571] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"265.858273ms","start":"2026-07-31T07:15:01.59344Z","end":"2026-07-31T07:15:01.859299Z","steps":["trace[1911607571] 'process raft request' (duration: 150.332855ms)","trace[1911607571] 'compare' (duration: 115.123631ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:15:01.85959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.227922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-07-31T07:15:01.859647Z","caller":"traceutil/trace.go:171","msg":"trace[973819328] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:686; }","duration":"121.338484ms","start":"2026-07-31T07:15:01.738293Z","end":"2026-07-31T07:15:01.859632Z","steps":["trace[973819328] 'agreement among raft nodes before linearized reading' (duration: 121.11139ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:02.05805Z","caller":"traceutil/trace.go:171","msg":"trace[1999889692] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"193.229312ms","start":"2026-07-31T07:15:01.864789Z","end":"2026-07-31T07:15:02.058018Z","steps":["trace[1999889692] 'process raft request' (duration: 191.806528ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:02.058087Z","caller":"traceutil/trace.go:171","msg":"trace[603023704] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"193.211782ms","start":"2026-07-31T07:15:01.864847Z","end":"2026-07-31T07:15:02.058059Z","steps":["trace[603023704] 'process raft request' (duration: 193.11012ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:02.092014Z","caller":"traceutil/trace.go:171","msg":"trace[1172144110] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"165.691192ms","start":"2026-07-31T07:15:01.926307Z","end":"2026-07-31T07:15:02.091998Z","steps":["trace[1172144110] 'process raft request' (duration: 165.57478ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:02.092Z","caller":"traceutil/trace.go:171","msg":"trace[524816544] linearizableReadLoop","detail":"{readStateIndex:716; appliedIndex:713; }","duration":"153.012802ms","start":"2026-07-31T07:15:01.93897Z","end":"2026-07-31T07:15:02.091983Z","steps":["trace[524816544] 'read index received' (duration: 117.677258ms)","trace[524816544] 'applied index is now lower than readState.Index' (duration: 35.334444ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:15:02.092395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.406768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-07-31T07:15:02.092808Z","caller":"traceutil/trace.go:171","msg":"trace[491873625] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:690; }","duration":"153.857746ms","start":"2026-07-31T07:15:01.938936Z","end":"2026-07-31T07:15:02.092794Z","steps":["trace[491873625] 'agreement among raft nodes before linearized reading' (duration: 153.104843ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:02.290104Z","caller":"traceutil/trace.go:171","msg":"trace[63096980] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"122.95593ms","start":"2026-07-31T07:15:02.167124Z","end":"2026-07-31T07:15:02.29008Z","steps":["trace[63096980] 'process raft request' (duration: 122.798986ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:02.638892Z","caller":"traceutil/trace.go:171","msg":"trace[561133121] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"272.470977ms","start":"2026-07-31T07:15:02.366382Z","end":"2026-07-31T07:15:02.638853Z","steps":["trace[561133121] 'process raft request' (duration: 272.252453ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:02.917341Z","caller":"traceutil/trace.go:171","msg":"trace[312168662] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:719; }","duration":"378.218263ms","start":"2026-07-31T07:15:02.539062Z","end":"2026-07-31T07:15:02.91728Z","steps":["trace[312168662] 'read index received' (duration: 99.581454ms)","trace[312168662] 'applied index is now lower than readState.Index' (duration: 278.635609ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:15:02.917345Z","caller":"traceutil/trace.go:171","msg":"trace[1062869225] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"392.476758ms","start":"2026-07-31T07:15:02.524831Z","end":"2026-07-31T07:15:02.917308Z","steps":["trace[1062869225] 'process raft request' (duration: 325.169167ms)","trace[1062869225] 'compare' (duration: 66.990426ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:15:02.917577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.500758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-07-31T07:15:02.917635Z","caller":"traceutil/trace.go:171","msg":"trace[850590105] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:695; }","duration":"378.601999ms","start":"2026-07-31T07:15:02.539019Z","end":"2026-07-31T07:15:02.917621Z","steps":["trace[850590105] 'agreement among raft nodes before linearized reading' (duration: 378.403575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:02.917675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:02.539001Z","time spent":"378.663381ms","remote":"127.0.0.1:43496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":5199,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" "} {"level":"warn","ts":"2026-07-31T07:15:02.917682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:02.524763Z","time spent":"392.781474ms","remote":"127.0.0.1:43496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:15:02.957514Z","caller":"traceutil/trace.go:171","msg":"trace[2025980725] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"229.567359ms","start":"2026-07-31T07:15:02.727921Z","end":"2026-07-31T07:15:02.957489Z","steps":["trace[2025980725] 'process raft request' (duration: 229.269675ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:02.957912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.022695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-07-31T07:15:02.957997Z","caller":"traceutil/trace.go:171","msg":"trace[691607666] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:696; }","duration":"219.139707ms","start":"2026-07-31T07:15:02.738836Z","end":"2026-07-31T07:15:02.957976Z","steps":["trace[691607666] 'agreement among raft nodes before linearized reading' (duration: 218.6968ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:07.305031Z","caller":"traceutil/trace.go:171","msg":"trace[869242463] transaction","detail":"{read_only:false; number_of_response:0; response_revision:728; }","duration":"101.140985ms","start":"2026-07-31T07:15:07.203877Z","end":"2026-07-31T07:15:07.305018Z","steps":["trace[869242463] 'process raft request' (duration: 101.115914ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:07.305193Z","caller":"traceutil/trace.go:171","msg":"trace[134643986] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"101.334868ms","start":"2026-07-31T07:15:07.203823Z","end":"2026-07-31T07:15:07.305158Z","steps":["trace[134643986] 'process raft request' (duration: 87.495356ms)","trace[134643986] 'compare' (duration: 13.579638ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:15:12.666043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476068236,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-31T07:15:12.703138Z","caller":"traceutil/trace.go:171","msg":"trace[690959123] transaction","detail":"{read_only:false; response_revision:769; number_of_response:1; }","duration":"590.05799ms","start":"2026-07-31T07:15:12.113051Z","end":"2026-07-31T07:15:12.703109Z","steps":["trace[690959123] 'process raft request' (duration: 589.893977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:12.703303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:12.113025Z","time spent":"590.184981ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:15:12.752712Z","caller":"traceutil/trace.go:171","msg":"trace[643185234] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:798; }","duration":"586.857078ms","start":"2026-07-31T07:15:12.165818Z","end":"2026-07-31T07:15:12.752675Z","steps":["trace[643185234] 'read index received' (duration: 537.11378ms)","trace[643185234] 'applied index is now lower than readState.Index' (duration: 49.741898ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:15:12.752794Z","caller":"traceutil/trace.go:171","msg":"trace[1997362711] transaction","detail":"{read_only:false; response_revision:770; number_of_response:1; }","duration":"639.568584ms","start":"2026-07-31T07:15:12.113198Z","end":"2026-07-31T07:15:12.752766Z","steps":["trace[1997362711] 'process raft request' (duration: 639.3183ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:12.752897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.873208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T07:15:12.752897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.078191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-f72bv\" ","response":"range_response_count:1 size:5047"} {"level":"info","ts":"2026-07-31T07:15:12.75294Z","caller":"traceutil/trace.go:171","msg":"trace[1601683211] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:770; }","duration":"319.98644ms","start":"2026-07-31T07:15:12.43294Z","end":"2026-07-31T07:15:12.752927Z","steps":["trace[1601683211] 'agreement among raft nodes before linearized reading' (duration: 319.908089ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:12.752941Z","caller":"traceutil/trace.go:171","msg":"trace[1615881999] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-f72bv; range_end:; response_count:1; response_revision:770; }","duration":"587.149983ms","start":"2026-07-31T07:15:12.165782Z","end":"2026-07-31T07:15:12.752932Z","steps":["trace[1615881999] 'agreement among raft nodes before linearized reading' (duration: 587.009571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:12.752933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:12.113189Z","time spent":"639.667666ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:15:12.752975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:12.432811Z","time spent":"320.155583ms","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:15:12.752975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:12.165767Z","time spent":"587.198994ms","remote":"127.0.0.1:43610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5069,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-f72bv\" "} {"level":"warn","ts":"2026-07-31T07:15:16.632899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.085908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T07:15:16.633097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.004495952s","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-31T07:15:16.633222Z","caller":"traceutil/trace.go:171","msg":"trace[2060903887] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:790; }","duration":"675.392975ms","start":"2026-07-31T07:15:15.957747Z","end":"2026-07-31T07:15:16.63314Z","steps":["trace[2060903887] 'count revisions from in-memory index tree' (duration: 675.002847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:16.633054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.457570793s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7wqrw\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-31T07:15:16.633432Z","caller":"traceutil/trace.go:171","msg":"trace[1659379599] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:790; }","duration":"1.004645954s","start":"2026-07-31T07:15:15.628534Z","end":"2026-07-31T07:15:16.63318Z","steps":["trace[1659379599] 'range keys from in-memory index tree' (duration: 1.004279128s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:16.633479Z","caller":"traceutil/trace.go:171","msg":"trace[1553356377] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7wqrw; range_end:; response_count:1; response_revision:790; }","duration":"1.457994119s","start":"2026-07-31T07:15:15.175385Z","end":"2026-07-31T07:15:16.633379Z","steps":["trace[1553356377] 'range keys from in-memory index tree' (duration: 1.457376108s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:16.633609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:15.957726Z","time spent":"675.636128ms","remote":"127.0.0.1:43684","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:15:16.633641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:15.628515Z","time spent":"1.004970899s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T07:15:16.633263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.10176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:15:16.633968Z","caller":"traceutil/trace.go:171","msg":"trace[1579632571] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:790; }","duration":"203.83557ms","start":"2026-07-31T07:15:16.430116Z","end":"2026-07-31T07:15:16.633952Z","steps":["trace[1579632571] 'range keys from in-memory index tree' (duration: 203.000207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:16.633622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:15.175361Z","time spent":"1.458201652s","remote":"127.0.0.1:43610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-7wqrw\" "} {"level":"warn","ts":"2026-07-31T07:15:16.633255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.046899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-31T07:15:16.634266Z","caller":"traceutil/trace.go:171","msg":"trace[1850356128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:790; }","duration":"667.145726ms","start":"2026-07-31T07:15:15.967093Z","end":"2026-07-31T07:15:16.634239Z","steps":["trace[1850356128] 'range keys from in-memory index tree' (duration: 665.916368ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:16.634409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:15.967081Z","time spent":"667.256868ms","remote":"127.0.0.1:43704","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":"info","ts":"2026-07-31T07:15:30.565992Z","caller":"traceutil/trace.go:171","msg":"trace[1643571362] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"112.590144ms","start":"2026-07-31T07:15:30.453379Z","end":"2026-07-31T07:15:30.565969Z","steps":["trace[1643571362] 'process raft request' (duration: 112.451662ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:38.862252Z","caller":"traceutil/trace.go:171","msg":"trace[1779905510] transaction","detail":"{read_only:false; response_revision:993; number_of_response:1; }","duration":"104.482762ms","start":"2026-07-31T07:15:38.757747Z","end":"2026-07-31T07:15:38.86223Z","steps":["trace[1779905510] 'process raft request' (duration: 68.236935ms)","trace[1779905510] 'compare' (duration: 36.142836ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:15:39.089112Z","caller":"traceutil/trace.go:171","msg":"trace[2140295978] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"204.292363ms","start":"2026-07-31T07:15:38.884795Z","end":"2026-07-31T07:15:39.089087Z","steps":["trace[2140295978] 'process raft request' (duration: 204.145641ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:15:39.114873Z","caller":"traceutil/trace.go:171","msg":"trace[1598305060] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"226.601614ms","start":"2026-07-31T07:15:38.888247Z","end":"2026-07-31T07:15:39.114848Z","steps":["trace[1598305060] 'process raft request' (duration: 226.385031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:49.318431Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476068754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:15:49.81914Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476068754,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:15:49.836285Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.037501774s","expected-duration":"1s"} {"level":"info","ts":"2026-07-31T07:15:49.836748Z","caller":"traceutil/trace.go:171","msg":"trace[203326531] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"2.038062472s","start":"2026-07-31T07:15:47.798655Z","end":"2026-07-31T07:15:49.836717Z","steps":["trace[203326531] 'process raft request' (duration: 2.037863129s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:49.836931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:47.79863Z","time spent":"2.038194974s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:15:49.837387Z","caller":"traceutil/trace.go:171","msg":"trace[1873442172] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"1.882458614s","start":"2026-07-31T07:15:47.95491Z","end":"2026-07-31T07:15:49.837368Z","steps":["trace[1873442172] 'process raft request' (duration: 1.88224539s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:49.837491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:47.954882Z","time spent":"1.882550074s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:15:49.840105Z","caller":"traceutil/trace.go:171","msg":"trace[657325192] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1059; }","duration":"1.022095538s","start":"2026-07-31T07:15:48.817994Z","end":"2026-07-31T07:15:49.840089Z","steps":["trace[657325192] 'read index received' (duration: 1.01852041s)","trace[657325192] 'applied index is now lower than readState.Index' (duration: 3.574368ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:15:49.840142Z","caller":"traceutil/trace.go:171","msg":"trace[1701961614] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"382.44579ms","start":"2026-07-31T07:15:49.457685Z","end":"2026-07-31T07:15:49.84013Z","steps":["trace[1701961614] 'process raft request' (duration: 382.304717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:49.8402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:49.457668Z","time spent":"382.50357ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:15:49.840233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022232851s","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":"warn","ts":"2026-07-31T07:15:49.840337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.381709ms","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-31T07:15:49.840565Z","caller":"traceutil/trace.go:171","msg":"trace[2004114975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1025; }","duration":"975.642862ms","start":"2026-07-31T07:15:48.864914Z","end":"2026-07-31T07:15:49.840557Z","steps":["trace[2004114975] 'agreement among raft nodes before linearized reading' (duration: 975.348939ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:49.840592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:48.864896Z","time spent":"975.690613ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T07:15:49.840687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.376652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:15:49.84072Z","caller":"traceutil/trace.go:171","msg":"trace[1396302040] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1025; }","duration":"409.450753ms","start":"2026-07-31T07:15:49.431262Z","end":"2026-07-31T07:15:49.840713Z","steps":["trace[1396302040] 'agreement among raft nodes before linearized reading' (duration: 409.404582ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:49.840744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:49.431246Z","time spent":"409.491633ms","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-31T07:15:49.840562Z","caller":"traceutil/trace.go:171","msg":"trace[723531528] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1025; }","duration":"1.022604496s","start":"2026-07-31T07:15:48.817943Z","end":"2026-07-31T07:15:49.840547Z","steps":["trace[723531528] 'agreement among raft nodes before linearized reading' (duration: 1.022241152s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:49.84087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:48.817923Z","time spent":"1.02292697s","remote":"127.0.0.1:43798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":29,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:15:49.840882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.199667ms","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-31T07:15:49.840912Z","caller":"traceutil/trace.go:171","msg":"trace[174363721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1025; }","duration":"645.265358ms","start":"2026-07-31T07:15:49.195638Z","end":"2026-07-31T07:15:49.840903Z","steps":["trace[174363721] 'agreement among raft nodes before linearized reading' (duration: 645.155467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:49.840929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.417519ms","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-31T07:15:49.840961Z","caller":"traceutil/trace.go:171","msg":"trace[162763066] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1025; }","duration":"694.484211ms","start":"2026-07-31T07:15:49.146468Z","end":"2026-07-31T07:15:49.840952Z","steps":["trace[162763066] 'agreement among raft nodes before linearized reading' (duration: 694.40062ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:49.840989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.407171ms","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-31T07:15:49.840995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:49.146448Z","time spent":"694.539901ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-31T07:15:49.841008Z","caller":"traceutil/trace.go:171","msg":"trace[997178201] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1025; }","duration":"611.450632ms","start":"2026-07-31T07:15:49.229552Z","end":"2026-07-31T07:15:49.841002Z","steps":["trace[997178201] 'agreement among raft nodes before linearized reading' (duration: 611.398472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:49.841031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:49.229537Z","time spent":"611.490693ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-31T07:15:49.841047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"929.262176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-31T07:15:49.841117Z","caller":"traceutil/trace.go:171","msg":"trace[2107996724] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1025; }","duration":"929.372847ms","start":"2026-07-31T07:15:48.911731Z","end":"2026-07-31T07:15:49.841104Z","steps":["trace[2107996724] 'agreement among raft nodes before linearized reading' (duration: 929.199524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:49.84115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:48.911712Z","time spent":"929.429967ms","remote":"127.0.0.1:43704","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-31T07:15:49.840938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:49.195623Z","time spent":"645.310049ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-31T07:15:56.933167Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476068828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:15:57.433854Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476068828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:15:57.93461Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476068828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:15:58.431897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999696442s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-31T07:15:58.432015Z","caller":"traceutil/trace.go:171","msg":"trace[966113416] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999894155s","start":"2026-07-31T07:15:56.432095Z","end":"2026-07-31T07:15:58.431989Z","steps":["trace[966113416] 'agreement among raft nodes before linearized reading' (duration: 1.999752013s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:15:58.432082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:56.432049Z","time spent":"2.000018696s","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/31 07:15:58 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:15:58.435378Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476068828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:15:58.936332Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476068828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:15:59.436859Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476068828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:15:59.937742Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476068828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:00.432751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000164687s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:00.432861Z","caller":"traceutil/trace.go:171","msg":"trace[456514867] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000353019s","start":"2026-07-31T07:15:58.432485Z","end":"2026-07-31T07:16:00.432838Z","steps":["trace[456514867] 'agreement among raft nodes before linearized reading' (duration: 2.000221477s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:00.432931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:58.432464Z","time spent":"2.000447121s","remote":"127.0.0.1:43438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/31 07:16:00 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:00.438784Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476068828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:00.707155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000868107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-31T07:16:00.707268Z","caller":"traceutil/trace.go:171","msg":"trace[1216051285] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000989158s","start":"2026-07-31T07:15:58.706257Z","end":"2026-07-31T07:16:00.707246Z","steps":["trace[1216051285] 'agreement among raft nodes before linearized reading' (duration: 2.000866107s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:00.707322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:58.706204Z","time spent":"2.001105139s","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/31 07:16:00 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:00.93941Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476068828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:00.947259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:55.948737Z","time spent":"4.99851416s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-07-31T07:16:00.947279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:55.948065Z","time spent":"4.999206488s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/31 07:16:00 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:01.439775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476068828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:01.932195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000392268s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:01.932285Z","caller":"traceutil/trace.go:171","msg":"trace[368029712] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00052931s","start":"2026-07-31T07:15:59.931735Z","end":"2026-07-31T07:16:01.932265Z","steps":["trace[368029712] 'agreement among raft nodes before linearized reading' (duration: 2.000419929s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:01.932349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:59.931725Z","time spent":"2.000613571s","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/31 07:16:01 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:01.940437Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476068828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:02.440425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000188599s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-31T07:16:02.440507Z","caller":"traceutil/trace.go:171","msg":"trace[381600331] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000332571s","start":"2026-07-31T07:16:00.440157Z","end":"2026-07-31T07:16:02.440489Z","steps":["trace[381600331] 'agreement among raft nodes before linearized reading' (duration: 2.00020805s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:02.440561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:00.440135Z","time spent":"2.000416403s","remote":"127.0.0.1:43438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:16:02.440708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476068828,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:02.873975Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"6.876361434s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-31T07:16:02.9465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:57.947985Z","time spent":"4.998509145s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/31 07:16:02 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:02.948625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:55.948455Z","time spent":"7.000162111s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-07-31T07:16:02.950266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:55.949058Z","time spent":"7.001204063s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-07-31T07:16:03.000849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999545178s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-31T07:16:03.000929Z","caller":"traceutil/trace.go:171","msg":"trace[238176549] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999678701s","start":"2026-07-31T07:15:58.001231Z","end":"2026-07-31T07:16:03.00091Z","steps":["trace[238176549] 'agreement among raft nodes before linearized reading' (duration: 4.999575729s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:03.00098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:58.001211Z","time spent":"4.999759712s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-31T07:16:03.373775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.412875ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-07-31T07:16:04.166446Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.292254919s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-31T07:16:04.285549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.792509ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-31T07:16:04.285973Z","caller":"traceutil/trace.go:171","msg":"trace[213542074] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"4.357609822s","start":"2026-07-31T07:15:59.928346Z","end":"2026-07-31T07:16:04.285955Z","steps":["trace[213542074] 'process raft request' (duration: 4.357362079s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:04.285983Z","caller":"traceutil/trace.go:171","msg":"trace[278168449] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1089; }","duration":"7.853781829s","start":"2026-07-31T07:15:56.43218Z","end":"2026-07-31T07:16:04.285962Z","steps":["trace[278168449] 'read index received' (duration: 6.442109453s)","trace[278168449] 'applied index is now lower than readState.Index' (duration: 1.411671316s)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:16:04.285999Z","caller":"traceutil/trace.go:171","msg":"trace[526279633] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"4.330810912s","start":"2026-07-31T07:15:59.955163Z","end":"2026-07-31T07:16:04.285974Z","steps":["trace[526279633] 'process raft request' (duration: 4.330689001s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.286073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:59.928325Z","time spent":"4.357677912s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:16:04.286085Z","caller":"traceutil/trace.go:171","msg":"trace[1290271903] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"3.849723715s","start":"2026-07-31T07:16:00.436348Z","end":"2026-07-31T07:16:04.286071Z","steps":["trace[1290271903] 'process raft request' (duration: 3.849556123s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.286271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:00.436322Z","time spent":"3.849906527s","remote":"127.0.0.1:43520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T07:16:04.286483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.462546343s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T07:16:04.286526Z","caller":"traceutil/trace.go:171","msg":"trace[273031406] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1057; }","duration":"3.462591034s","start":"2026-07-31T07:16:00.823923Z","end":"2026-07-31T07:16:04.286514Z","steps":["trace[273031406] 'agreement among raft nodes before linearized reading' (duration: 3.462486072s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.286551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:00.823861Z","time spent":"3.462683785s","remote":"127.0.0.1:43920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:16:04.286524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.236751531s","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-31T07:16:04.286619Z","caller":"traceutil/trace.go:171","msg":"trace[1201360586] 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:1057; }","duration":"7.236905454s","start":"2026-07-31T07:15:57.049694Z","end":"2026-07-31T07:16:04.286599Z","steps":["trace[1201360586] 'agreement among raft nodes before linearized reading' (duration: 7.236707241s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.286665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:57.049674Z","time spent":"7.236979816s","remote":"127.0.0.1:50480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:16:04.286694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.121398614s","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":"warn","ts":"2026-07-31T07:16:04.286749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.338074393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-31T07:16:04.286758Z","caller":"traceutil/trace.go:171","msg":"trace[1154816165] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1057; }","duration":"1.121511725s","start":"2026-07-31T07:16:03.16523Z","end":"2026-07-31T07:16:04.286741Z","steps":["trace[1154816165] 'agreement among raft nodes before linearized reading' (duration: 1.121394112s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.28674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337992822s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:549"} {"level":"warn","ts":"2026-07-31T07:16:04.286769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.224573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.194\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-31T07:16:04.286802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.837543811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T07:16:04.286796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:03.165211Z","time spent":"1.121576135s","remote":"127.0.0.1:50366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"info","ts":"2026-07-31T07:16:04.286819Z","caller":"traceutil/trace.go:171","msg":"trace[1076099198] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1057; }","duration":"1.837586742s","start":"2026-07-31T07:16:02.449228Z","end":"2026-07-31T07:16:04.286814Z","steps":["trace[1076099198] 'agreement among raft nodes before linearized reading' (duration: 1.837560791s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:04.286821Z","caller":"traceutil/trace.go:171","msg":"trace[563716097] range","detail":"{range_begin:/registry/masterleases/199.19.213.194; range_end:; response_count:1; response_revision:1057; }","duration":"401.279953ms","start":"2026-07-31T07:16:03.885526Z","end":"2026-07-31T07:16:04.286806Z","steps":["trace[563716097] 'agreement among raft nodes before linearized reading' (duration: 401.131832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.286837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:02.449191Z","time spent":"1.837642063s","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:16:04.286862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:03.885505Z","time spent":"401.347854ms","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.194\" "} {"level":"warn","ts":"2026-07-31T07:16:04.286897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.615260212s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-31T07:16:04.286917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.337066551s","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-31T07:16:04.286928Z","caller":"traceutil/trace.go:171","msg":"trace[149853067] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1057; }","duration":"3.615336654s","start":"2026-07-31T07:16:00.671582Z","end":"2026-07-31T07:16:04.286919Z","steps":["trace[149853067] 'agreement among raft nodes before linearized reading' (duration: 3.615276963s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.286962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:00.671564Z","time spent":"3.615390694s","remote":"127.0.0.1:43538","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":16,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-07-31T07:16:04.286949Z","caller":"traceutil/trace.go:171","msg":"trace[1513193579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1057; }","duration":"1.337097211s","start":"2026-07-31T07:16:02.949843Z","end":"2026-07-31T07:16:04.28694Z","steps":["trace[1513193579] 'agreement among raft nodes before linearized reading' (duration: 1.33700631s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.286994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.286189597s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T07:16:04.28682Z","caller":"traceutil/trace.go:171","msg":"trace[720929917] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:1057; }","duration":"1.338108423s","start":"2026-07-31T07:16:02.948692Z","end":"2026-07-31T07:16:04.2868Z","steps":["trace[720929917] 'agreement among raft nodes before linearized reading' (duration: 1.337876359s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.287004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:02.949607Z","time spent":"1.337388295s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-31T07:16:04.287015Z","caller":"traceutil/trace.go:171","msg":"trace[1165714777] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1057; }","duration":"3.286212767s","start":"2026-07-31T07:16:01.000797Z","end":"2026-07-31T07:16:04.28701Z","steps":["trace[1165714777] 'agreement among raft nodes before linearized reading' (duration: 3.286124186s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.286287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:59.955134Z","time spent":"4.330920124s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:16:04.287037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:02.948681Z","time spent":"1.338346246s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":571,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-07-31T07:16:04.287035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:01.000659Z","time spent":"3.286370969s","remote":"127.0.0.1:43520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":106,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-31T07:16:04.286771Z","caller":"traceutil/trace.go:171","msg":"trace[164269921] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1057; }","duration":"1.338119844s","start":"2026-07-31T07:16:02.948645Z","end":"2026-07-31T07:16:04.286765Z","steps":["trace[164269921] 'agreement among raft nodes before linearized reading' (duration: 1.338034593s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.287088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:02.948632Z","time spent":"1.338450458s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-31T07:16:04.287098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.703189527s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T07:16:04.287126Z","caller":"traceutil/trace.go:171","msg":"trace[16136167] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1057; }","duration":"3.703253297s","start":"2026-07-31T07:16:00.583865Z","end":"2026-07-31T07:16:04.287118Z","steps":["trace[16136167] 'agreement among raft nodes before linearized reading' (duration: 3.703209977s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.287153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:00.583846Z","time spent":"3.703299789s","remote":"127.0.0.1:50548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:16:04.287234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.087570915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T07:16:04.287255Z","caller":"traceutil/trace.go:171","msg":"trace[57433467] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1057; }","duration":"6.087628145s","start":"2026-07-31T07:15:58.19962Z","end":"2026-07-31T07:16:04.287248Z","steps":["trace[57433467] 'agreement among raft nodes before linearized reading' (duration: 6.087566674s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.287274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:58.199601Z","time spent":"6.087667626s","remote":"127.0.0.1:43822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":54,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:16:04.287318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.030012524s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T07:16:04.287349Z","caller":"traceutil/trace.go:171","msg":"trace[1437706735] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1057; }","duration":"5.030047424s","start":"2026-07-31T07:15:59.257293Z","end":"2026-07-31T07:16:04.28734Z","steps":["trace[1437706735] 'agreement among raft nodes before linearized reading' (duration: 5.029986003s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.287373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:59.25723Z","time spent":"5.030136685s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":8,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:16:04.287382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.538664925s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-31T07:16:04.28746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.97689912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T07:16:04.287461Z","caller":"traceutil/trace.go:171","msg":"trace[784704217] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1057; }","duration":"5.538814517s","start":"2026-07-31T07:15:58.748625Z","end":"2026-07-31T07:16:04.287439Z","steps":["trace[784704217] 'agreement among raft nodes before linearized reading' (duration: 5.538664565s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.287469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.991765024s","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-31T07:16:04.287483Z","caller":"traceutil/trace.go:171","msg":"trace[1615918715] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1057; }","duration":"4.976964111s","start":"2026-07-31T07:15:59.310511Z","end":"2026-07-31T07:16:04.287475Z","steps":["trace[1615918715] 'agreement among raft nodes before linearized reading' (duration: 4.97692394s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.287509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:59.310489Z","time spent":"4.977011521s","remote":"127.0.0.1:43936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"info","ts":"2026-07-31T07:16:04.287506Z","caller":"traceutil/trace.go:171","msg":"trace[695622051] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1057; }","duration":"6.991851105s","start":"2026-07-31T07:15:57.295645Z","end":"2026-07-31T07:16:04.287496Z","steps":["trace[695622051] 'agreement among raft nodes before linearized reading' (duration: 6.991792464s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.287507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:58.748582Z","time spent":"5.538914148s","remote":"127.0.0.1:43914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:16:04.287541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:57.295621Z","time spent":"6.991912046s","remote":"127.0.0.1:50486","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:16:04.288108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.423117155s","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":"info","ts":"2026-07-31T07:16:04.288145Z","caller":"traceutil/trace.go:171","msg":"trace[349619089] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1057; }","duration":"6.423158075s","start":"2026-07-31T07:15:57.864978Z","end":"2026-07-31T07:16:04.288136Z","steps":["trace[349619089] 'agreement among raft nodes before linearized reading' (duration: 6.422290774s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:04.288175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:15:57.864843Z","time spent":"6.423322737s","remote":"127.0.0.1:43610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:16:04.591016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.091654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:16:04.59126Z","caller":"traceutil/trace.go:171","msg":"trace[1795095953] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"296.769823ms","start":"2026-07-31T07:16:04.294474Z","end":"2026-07-31T07:16:04.591244Z","steps":["trace[1795095953] 'process raft request' (duration: 296.727492ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:04.59129Z","caller":"traceutil/trace.go:171","msg":"trace[1522471204] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"298.552365ms","start":"2026-07-31T07:16:04.29272Z","end":"2026-07-31T07:16:04.591273Z","steps":["trace[1522471204] 'process raft request' (duration: 298.382413ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:04.591349Z","caller":"traceutil/trace.go:171","msg":"trace[1160742928] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"298.300402ms","start":"2026-07-31T07:16:04.293018Z","end":"2026-07-31T07:16:04.591319Z","steps":["trace[1160742928] 'process raft request' (duration: 298.14505ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:04.591463Z","caller":"traceutil/trace.go:171","msg":"trace[2078289448] linearizableReadLoop","detail":"{readStateIndex:1102; appliedIndex:1099; }","duration":"297.539162ms","start":"2026-07-31T07:16:04.29391Z","end":"2026-07-31T07:16:04.59145Z","steps":["trace[2078289448] 'read index received' (duration: 156.983482ms)","trace[2078289448] 'applied index is now lower than readState.Index' (duration: 140.55418ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:16:04.59166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.65606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:549"} {"level":"warn","ts":"2026-07-31T07:16:04.591681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.351462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:16:04.5917Z","caller":"traceutil/trace.go:171","msg":"trace[1363605297] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:1061; }","duration":"299.72586ms","start":"2026-07-31T07:16:04.291964Z","end":"2026-07-31T07:16:04.59169Z","steps":["trace[1363605297] 'agreement among raft nodes before linearized reading' (duration: 299.604379ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:04.591709Z","caller":"traceutil/trace.go:171","msg":"trace[514219710] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1061; }","duration":"291.416514ms","start":"2026-07-31T07:16:04.300284Z","end":"2026-07-31T07:16:04.591701Z","steps":["trace[514219710] 'agreement among raft nodes before linearized reading' (duration: 291.355733ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:04.591475Z","caller":"traceutil/trace.go:171","msg":"trace[2010796870] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"300.564691ms","start":"2026-07-31T07:16:04.290897Z","end":"2026-07-31T07:16:04.591462Z","steps":["trace[2010796870] 'process raft request' (duration: 159.95557ms)","trace[2010796870] 'compare' (duration: 139.979593ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:16:04.592569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:04.290881Z","time spent":"300.975547ms","remote":"127.0.0.1:43520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-31T07:16:05.068406Z","caller":"traceutil/trace.go:171","msg":"trace[2055163347] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"110.921596ms","start":"2026-07-31T07:16:04.957463Z","end":"2026-07-31T07:16:05.068385Z","steps":["trace[2055163347] 'process raft request' (duration: 110.730603ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:05.345126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.742078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:16:05.345179Z","caller":"traceutil/trace.go:171","msg":"trace[1939302750] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1065; }","duration":"158.83133ms","start":"2026-07-31T07:16:05.186335Z","end":"2026-07-31T07:16:05.345167Z","steps":["trace[1939302750] 'count revisions from in-memory index tree' (duration: 158.535446ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:06.937179Z","caller":"traceutil/trace.go:171","msg":"trace[857389799] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"324.151933ms","start":"2026-07-31T07:16:06.613003Z","end":"2026-07-31T07:16:06.937154Z","steps":["trace[857389799] 'process raft request' (duration: 315.165627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:06.937318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:06.6128Z","time spent":"324.447277ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T07:16:06.943303Z","caller":"traceutil/trace.go:171","msg":"trace[493269874] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"329.104697ms","start":"2026-07-31T07:16:06.614179Z","end":"2026-07-31T07:16:06.943284Z","steps":["trace[493269874] 'process raft request' (duration: 329.015676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:06.943415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:06.614154Z","time spent":"329.198969ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:16:07.177161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.175254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-31T07:16:07.177218Z","caller":"traceutil/trace.go:171","msg":"trace[1241159025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1068; }","duration":"105.267614ms","start":"2026-07-31T07:16:07.071937Z","end":"2026-07-31T07:16:07.177204Z","steps":["trace[1241159025] 'range keys from in-memory index tree' (duration: 105.076822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:07.177293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.705586ms","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-31T07:16:07.177375Z","caller":"traceutil/trace.go:171","msg":"trace[364682614] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1068; }","duration":"125.804827ms","start":"2026-07-31T07:16:07.051552Z","end":"2026-07-31T07:16:07.177357Z","steps":["trace[364682614] 'range keys from in-memory index tree' (duration: 125.546833ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:07.436782Z","caller":"traceutil/trace.go:171","msg":"trace[2096967551] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"255.714647ms","start":"2026-07-31T07:16:07.181049Z","end":"2026-07-31T07:16:07.436764Z","steps":["trace[2096967551] 'process raft request' (duration: 177.887242ms)","trace[2096967551] 'compare' (duration: 77.718914ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:16:07.437156Z","caller":"traceutil/trace.go:171","msg":"trace[1612619482] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"254.495463ms","start":"2026-07-31T07:16:07.182643Z","end":"2026-07-31T07:16:07.437138Z","steps":["trace[1612619482] 'process raft request' (duration: 254.096978ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:11.182269Z","caller":"traceutil/trace.go:171","msg":"trace[1469159836] transaction","detail":"{read_only:false; response_revision:1079; number_of_response:1; }","duration":"225.753308ms","start":"2026-07-31T07:16:10.956485Z","end":"2026-07-31T07:16:11.182238Z","steps":["trace[1469159836] 'process raft request' (duration: 225.551956ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:11.303746Z","caller":"traceutil/trace.go:171","msg":"trace[1328793962] linearizableReadLoop","detail":"{readStateIndex:1123; appliedIndex:1121; }","duration":"312.889838ms","start":"2026-07-31T07:16:10.990828Z","end":"2026-07-31T07:16:11.303718Z","steps":["trace[1328793962] 'read index received' (duration: 191.268819ms)","trace[1328793962] 'applied index is now lower than readState.Index' (duration: 121.619849ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:16:11.303779Z","caller":"traceutil/trace.go:171","msg":"trace[1211200288] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"344.058523ms","start":"2026-07-31T07:16:10.959695Z","end":"2026-07-31T07:16:11.303753Z","steps":["trace[1211200288] 'process raft request' (duration: 341.399809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:11.303892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.043639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T07:16:11.303898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.513274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:16:11.303926Z","caller":"traceutil/trace.go:171","msg":"trace[47053907] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1080; }","duration":"263.576204ms","start":"2026-07-31T07:16:11.040343Z","end":"2026-07-31T07:16:11.303919Z","steps":["trace[47053907] 'agreement among raft nodes before linearized reading' (duration: 263.522953ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:11.303924Z","caller":"traceutil/trace.go:171","msg":"trace[1170369992] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1080; }","duration":"313.09275ms","start":"2026-07-31T07:16:10.990822Z","end":"2026-07-31T07:16:11.303915Z","steps":["trace[1170369992] 'agreement among raft nodes before linearized reading' (duration: 313.024349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:11.303925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:10.959671Z","time spent":"344.170214ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:16:11.303956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:10.990759Z","time spent":"313.187501ms","remote":"127.0.0.1:50402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:16:11.30418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.131831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:16:11.304204Z","caller":"traceutil/trace.go:171","msg":"trace[1092242433] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1080; }","duration":"260.195103ms","start":"2026-07-31T07:16:11.044003Z","end":"2026-07-31T07:16:11.304198Z","steps":["trace[1092242433] 'agreement among raft nodes before linearized reading' (duration: 260.158872ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:12.745097Z","caller":"traceutil/trace.go:171","msg":"trace[343614578] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"107.74336ms","start":"2026-07-31T07:16:12.637326Z","end":"2026-07-31T07:16:12.74507Z","steps":["trace[343614578] 'process raft request' (duration: 107.598708ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:13.465985Z","caller":"traceutil/trace.go:171","msg":"trace[1934919646] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"157.045347ms","start":"2026-07-31T07:16:13.308919Z","end":"2026-07-31T07:16:13.465964Z","steps":["trace[1934919646] 'read index received' (duration: 156.818534ms)","trace[1934919646] 'applied index is now lower than readState.Index' (duration: 226.073µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:16:13.466378Z","caller":"traceutil/trace.go:171","msg":"trace[656132165] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"277.031198ms","start":"2026-07-31T07:16:13.189335Z","end":"2026-07-31T07:16:13.466366Z","steps":["trace[656132165] 'process raft request' (duration: 276.473911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:13.466625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.697814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-31T07:16:13.466682Z","caller":"traceutil/trace.go:171","msg":"trace[443559190] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1085; }","duration":"157.767606ms","start":"2026-07-31T07:16:13.308906Z","end":"2026-07-31T07:16:13.466674Z","steps":["trace[443559190] 'agreement among raft nodes before linearized reading' (duration: 157.635744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:13.466841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.205713ms","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-31T07:16:13.466922Z","caller":"traceutil/trace.go:171","msg":"trace[1313264928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1085; }","duration":"151.305605ms","start":"2026-07-31T07:16:13.31561Z","end":"2026-07-31T07:16:13.466915Z","steps":["trace[1313264928] 'agreement among raft nodes before linearized reading' (duration: 151.189554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:13.718863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.547283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T07:16:13.719069Z","caller":"traceutil/trace.go:171","msg":"trace[1353824303] linearizableReadLoop","detail":"{readStateIndex:1130; appliedIndex:1128; }","duration":"217.390338ms","start":"2026-07-31T07:16:13.501659Z","end":"2026-07-31T07:16:13.71905Z","steps":["trace[1353824303] 'read index received' (duration: 102.593043ms)","trace[1353824303] 'applied index is now lower than readState.Index' (duration: 114.795915ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:16:13.719181Z","caller":"traceutil/trace.go:171","msg":"trace[1675108747] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"248.294017ms","start":"2026-07-31T07:16:13.470859Z","end":"2026-07-31T07:16:13.719153Z","steps":["trace[1675108747] 'process raft request' (duration: 133.38054ms)","trace[1675108747] 'compare' (duration: 114.280778ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:16:13.719231Z","caller":"traceutil/trace.go:171","msg":"trace[1393422826] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"246.642585ms","start":"2026-07-31T07:16:13.47254Z","end":"2026-07-31T07:16:13.719182Z","steps":["trace[1393422826] 'process raft request' (duration: 246.432273ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:13.719545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.199398ms","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-31T07:16:13.719593Z","caller":"traceutil/trace.go:171","msg":"trace[185998897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1087; }","duration":"183.296408ms","start":"2026-07-31T07:16:13.536284Z","end":"2026-07-31T07:16:13.719581Z","steps":["trace[185998897] 'agreement among raft nodes before linearized reading' (duration: 183.144786ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:13.719562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.886584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-31T07:16:13.719692Z","caller":"traceutil/trace.go:171","msg":"trace[1721016192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1087; }","duration":"218.070785ms","start":"2026-07-31T07:16:13.501603Z","end":"2026-07-31T07:16:13.719674Z","steps":["trace[1721016192] 'agreement among raft nodes before linearized reading' (duration: 217.723461ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:13.969896Z","caller":"traceutil/trace.go:171","msg":"trace[336429536] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"245.762495ms","start":"2026-07-31T07:16:13.724096Z","end":"2026-07-31T07:16:13.969858Z","steps":["trace[336429536] 'process raft request' (duration: 188.400543ms)","trace[336429536] 'compare' (duration: 57.21544ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T07:16:34.801358Z","caller":"traceutil/trace.go:171","msg":"trace[2043898524] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"244.482567ms","start":"2026-07-31T07:16:34.556856Z","end":"2026-07-31T07:16:34.801339Z","steps":["trace[2043898524] 'process raft request' (duration: 244.374086ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:35.931915Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:36.43301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:36.933342Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:37.431976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000378573s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:37.432029Z","caller":"traceutil/trace.go:171","msg":"trace[1364200064] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000480564s","start":"2026-07-31T07:16:35.431536Z","end":"2026-07-31T07:16:37.432017Z","steps":["trace[1364200064] 'agreement among raft nodes before linearized reading' (duration: 2.000409583s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:37.432057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:35.431516Z","time spent":"2.000534864s","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/31 07:16:37 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:37.434152Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:37.934523Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:38.435513Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:38.936212Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:39.432872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000514382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:39.433022Z","caller":"traceutil/trace.go:171","msg":"trace[1816205478] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000716655s","start":"2026-07-31T07:16:37.432287Z","end":"2026-07-31T07:16:39.433003Z","steps":["trace[1816205478] 'agreement among raft nodes before linearized reading' (duration: 2.000549813s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:39.433256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:37.432262Z","time spent":"2.000978177s","remote":"127.0.0.1:43438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/31 07:16:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:39.437307Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:39.859699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:34.861763Z","time spent":"4.997928901s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/31 07:16:39 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:39.938723Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:40.439125Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:40.606243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999267582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:40.606372Z","caller":"traceutil/trace.go:171","msg":"trace[1213233877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999443913s","start":"2026-07-31T07:16:35.606902Z","end":"2026-07-31T07:16:40.606346Z","steps":["trace[1213233877] 'agreement among raft nodes before linearized reading' (duration: 4.999300751s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:40.606442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:35.606884Z","time spent":"4.999542684s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/07/31 07:16:40 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:40.865556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999777061s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:40.865631Z","caller":"traceutil/trace.go:171","msg":"trace[1879014665] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999900573s","start":"2026-07-31T07:16:35.865713Z","end":"2026-07-31T07:16:40.865613Z","steps":["trace[1879014665] 'agreement among raft nodes before linearized reading' (duration: 4.999798242s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:40.865679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:35.865698Z","time spent":"4.999971404s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/07/31 07:16:40 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:40.876696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.000306346s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-31T07:16:40.87678Z","caller":"traceutil/trace.go:171","msg":"trace[703270877] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.000515209s","start":"2026-07-31T07:16:35.876244Z","end":"2026-07-31T07:16:40.876759Z","steps":["trace[703270877] 'agreement among raft nodes before linearized reading' (duration: 5.000345437s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:40.876837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:35.876223Z","time spent":"5.00060344s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/07/31 07:16:40 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:40.940238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:41.440877Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:41.441034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000441252s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:41.4411Z","caller":"traceutil/trace.go:171","msg":"trace[152432843] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000553223s","start":"2026-07-31T07:16:39.440528Z","end":"2026-07-31T07:16:41.441081Z","steps":["trace[152432843] 'agreement among raft nodes before linearized reading' (duration: 2.000474502s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:41.441151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:39.44051Z","time spent":"2.000631474s","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/31 07:16:41 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:41.771039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:34.77054Z","time spent":"7.000493574s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-07-31T07:16:41.941123Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069106,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:42.431728Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-07-31T07:16:42.932591Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:43.433088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:43.44201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000473345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-31T07:16:43.44212Z","caller":"traceutil/trace.go:171","msg":"trace[986035440] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000618697s","start":"2026-07-31T07:16:41.441452Z","end":"2026-07-31T07:16:43.442071Z","steps":["trace[986035440] 'agreement among raft nodes before linearized reading' (duration: 2.000521736s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:43.442224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:41.441422Z","time spent":"2.000741858s","remote":"127.0.0.1:43438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/31 07:16:43 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:43.934025Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:44.43479Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:44.860402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999192581s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:44.861035Z","caller":"traceutil/trace.go:171","msg":"trace[1881587360] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; }","duration":"4.999876198s","start":"2026-07-31T07:16:39.861134Z","end":"2026-07-31T07:16:44.86101Z","steps":["trace[1881587360] 'agreement among raft nodes before linearized reading' (duration: 4.999224541s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:44.861102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:39.861115Z","time spent":"4.99996804s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} 2026/07/31 07:16:44 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:44.935619Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:45.436108Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:45.450469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00057118s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:45.450538Z","caller":"traceutil/trace.go:171","msg":"trace[426780172] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000684742s","start":"2026-07-31T07:16:43.449836Z","end":"2026-07-31T07:16:45.450521Z","steps":["trace[426780172] 'agreement among raft nodes before linearized reading' (duration: 2.000598901s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:45.450582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:43.44982Z","time spent":"2.000753863s","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/31 07:16:45 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:45.606431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997702303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:45.606498Z","caller":"traceutil/trace.go:171","msg":"trace[789749808] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.997823076s","start":"2026-07-31T07:16:42.60866Z","end":"2026-07-31T07:16:45.606483Z","steps":["trace[789749808] 'agreement among raft nodes before linearized reading' (duration: 2.997736414s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:45.606533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:42.608639Z","time spent":"2.997886886s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/07/31 07:16:45 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:45.866191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997658608s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:45.866291Z","caller":"traceutil/trace.go:171","msg":"trace[16172164] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.99781891s","start":"2026-07-31T07:16:42.868454Z","end":"2026-07-31T07:16:45.866273Z","steps":["trace[16172164] 'agreement among raft nodes before linearized reading' (duration: 2.997697749s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:45.866337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:42.868431Z","time spent":"2.997896041s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/07/31 07:16:45 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:45.875872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.99789392s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:45.87594Z","caller":"traceutil/trace.go:171","msg":"trace[1540288588] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"2.998005672s","start":"2026-07-31T07:16:42.877912Z","end":"2026-07-31T07:16:45.875918Z","steps":["trace[1540288588] 'agreement among raft nodes before linearized reading' (duration: 2.997938291s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:45.876013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:42.87788Z","time spent":"2.998119284s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/07/31 07:16:45 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:45.936492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:45.949755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999885752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:45.949861Z","caller":"traceutil/trace.go:171","msg":"trace[494742643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"10.000037985s","start":"2026-07-31T07:16:35.949797Z","end":"2026-07-31T07:16:45.949835Z","steps":["trace[494742643] 'agreement among raft nodes before linearized reading' (duration: 9.999899913s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:45.949926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:35.949782Z","time spent":"10.000128525s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/07/31 07:16:45 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:45.965576Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"45ef9fb705cda492","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-07-31T07:16:46.075179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999653596s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:46.075393Z","caller":"traceutil/trace.go:171","msg":"trace[1618531992] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"9.999942339s","start":"2026-07-31T07:16:36.075431Z","end":"2026-07-31T07:16:46.075373Z","steps":["trace[1618531992] 'agreement among raft nodes before linearized reading' (duration: 9.999682446s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:46.075545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:36.075412Z","time spent":"10.00007911s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/07/31 07:16:46 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:46.222412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416989123s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:16:46.222542Z","caller":"traceutil/trace.go:171","msg":"trace[14610954] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1157; }","duration":"1.417139574s","start":"2026-07-31T07:16:44.805384Z","end":"2026-07-31T07:16:46.222524Z","steps":["trace[14610954] 'range keys from in-memory index tree' (duration: 1.416970753s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:46.224254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.180122127s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-07-31T07:16:46.437349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:46.938259Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:47.43947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:47.450936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999995662s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-31T07:16:47.451017Z","caller":"traceutil/trace.go:171","msg":"trace[559809867] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000135213s","start":"2026-07-31T07:16:45.450863Z","end":"2026-07-31T07:16:47.450998Z","steps":["trace[559809867] 'agreement among raft nodes before linearized reading' (duration: 2.000019782s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:47.451119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:45.450812Z","time spent":"2.000295135s","remote":"127.0.0.1:43438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:16:47.940421Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:48.441049Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:48.777091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:41.776107Z","time spent":"7.000976557s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-07-31T07:16:48.941859Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069112,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:49.432858Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-07-31T07:16:49.433068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.207775113s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-07-31T07:16:49.433061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.16488752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-07-31T07:16:49.433109Z","caller":"traceutil/trace.go:171","msg":"trace[2060503035] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; }","duration":"11.207864325s","start":"2026-07-31T07:16:38.22523Z","end":"2026-07-31T07:16:49.433095Z","steps":["trace[2060503035] 'agreement among raft nodes before linearized reading' (duration: 11.207772983s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:49.433113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.534490636s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-07-31T07:16:49.433197Z","caller":"traceutil/trace.go:171","msg":"trace[934949099] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; }","duration":"13.534626449s","start":"2026-07-31T07:16:35.898551Z","end":"2026-07-31T07:16:49.433178Z","steps":["trace[934949099] 'agreement among raft nodes before linearized reading' (duration: 13.534503886s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:49.43318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.473290381s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-07-31T07:16:49.433206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.997726237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18c74e90a5713dfe\" ","response":"","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-07-31T07:16:49.433152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:38.225162Z","time spent":"11.207980316s","remote":"127.0.0.1:43830","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":0,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-07-31T07:16:49.433249Z","caller":"traceutil/trace.go:171","msg":"trace[1123110451] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; }","duration":"10.473414493s","start":"2026-07-31T07:16:38.959818Z","end":"2026-07-31T07:16:49.433233Z","steps":["trace[1123110451] 'agreement among raft nodes before linearized reading' (duration: 10.473323962s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:49.433297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:38.959798Z","time spent":"10.473486754s","remote":"127.0.0.1:43542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":0,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-07-31T07:16:49.433293Z","caller":"traceutil/trace.go:171","msg":"trace[1332225761] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18c74e90a5713dfe; range_end:; }","duration":"9.997911479s","start":"2026-07-31T07:16:39.435361Z","end":"2026-07-31T07:16:49.433272Z","steps":["trace[1332225761] 'agreement among raft nodes before linearized reading' (duration: 9.997756027s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:49.433321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.044349777s","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":"","error":"etcdserver: request timed out"} {"level":"info","ts":"2026-07-31T07:16:49.433361Z","caller":"traceutil/trace.go:171","msg":"trace[1233015647] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; }","duration":"13.044422728s","start":"2026-07-31T07:16:36.388931Z","end":"2026-07-31T07:16:49.433353Z","steps":["trace[1233015647] 'agreement among raft nodes before linearized reading' (duration: 13.044376347s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:49.433245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:35.898539Z","time spent":"13.534694169s","remote":"127.0.0.1:50390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":0,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:16:49.433377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:39.435322Z","time spent":"9.998041631s","remote":"127.0.0.1:43520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":0,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18c74e90a5713dfe\" "} {"level":"warn","ts":"2026-07-31T07:16:49.433388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:36.388916Z","time spent":"13.044463618s","remote":"127.0.0.1:50516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":0,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-07-31T07:16:49.433252Z","caller":"traceutil/trace.go:171","msg":"trace[2026878691] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; }","duration":"11.165008452s","start":"2026-07-31T07:16:38.268121Z","end":"2026-07-31T07:16:49.43313Z","steps":["trace[2026878691] 'agreement among raft nodes before linearized reading' (duration: 11.16488048s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:49.434017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:38.2681Z","time spent":"11.165890762s","remote":"127.0.0.1:43844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":0,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:16:49.45917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000206983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-31T07:16:49.459227Z","caller":"traceutil/trace.go:171","msg":"trace[1364226635] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000294814s","start":"2026-07-31T07:16:47.458918Z","end":"2026-07-31T07:16:49.459213Z","steps":["trace[1364226635] 'agreement among raft nodes before linearized reading' (duration: 2.000228173s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:49.459269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:47.458906Z","time spent":"2.000354274s","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:16:49.934014Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:50.434968Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:50.607378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:45.608842Z","time spent":"4.998528872s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/31 07:16:50 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:50.867222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:45.869688Z","time spent":"4.997527916s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/31 07:16:50 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:50.936091Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:51.437033Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:51.460236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.00038196s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:51.460324Z","caller":"traceutil/trace.go:171","msg":"trace[1579563453] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000518521s","start":"2026-07-31T07:16:49.459787Z","end":"2026-07-31T07:16:51.460305Z","steps":["trace[1579563453] 'agreement among raft nodes before linearized reading' (duration: 2.000399679s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:51.460373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:49.459768Z","time spent":"2.000595101s","remote":"127.0.0.1:43438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/31 07:16:51 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:51.863401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:44.862734Z","time spent":"7.000659824s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-07-31T07:16:51.865679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:44.864804Z","time spent":"7.000868216s","remote":"127.0.0.1:43520","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-31T07:16:51.933567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000630447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:51.933666Z","caller":"traceutil/trace.go:171","msg":"trace[46515544] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00077038s","start":"2026-07-31T07:16:49.932874Z","end":"2026-07-31T07:16:51.933645Z","steps":["trace[46515544] 'agreement among raft nodes before linearized reading' (duration: 2.000647368s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:51.933725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:49.932863Z","time spent":"2.00084718s","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/31 07:16:51 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:51.937731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:52.43818Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:52.939254Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:52.953504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:45.952528Z","time spent":"7.000968998s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-07-31T07:16:52.959786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:46.07838Z","time spent":"6.881397242s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/07/31 07:16:52 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:52.972979Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"45ef9fb705cda492","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-07-31T07:16:53.439857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:53.468175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000341414s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-31T07:16:53.468228Z","caller":"traceutil/trace.go:171","msg":"trace[1924791271] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000451045s","start":"2026-07-31T07:16:51.467762Z","end":"2026-07-31T07:16:53.468213Z","steps":["trace[1924791271] 'agreement among raft nodes before linearized reading' (duration: 2.000373114s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:53.468273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:51.467742Z","time spent":"2.000514545s","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:16:53.940871Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:54.441978Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:54.942528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:55.442959Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5039422116476069122,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T07:16:55.468437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999868902s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-31T07:16:55.468516Z","caller":"traceutil/trace.go:171","msg":"trace[414207331] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999991823s","start":"2026-07-31T07:16:53.468509Z","end":"2026-07-31T07:16:55.4685Z","steps":["trace[414207331] 'agreement among raft nodes before linearized reading' (duration: 1.999888532s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:55.468552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:53.468491Z","time spent":"2.000053743s","remote":"127.0.0.1:43438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/31 07:16:55 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-31T07:16:55.731739Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"20.687740117s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-31T07:16:55.976252Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"45ef9fb705cda4e7","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-07-31T07:16:56.082133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.27671864s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:16:56.082261Z","caller":"traceutil/trace.go:171","msg":"trace[1087411536] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1158; }","duration":"1.276845082s","start":"2026-07-31T07:16:54.80539Z","end":"2026-07-31T07:16:56.082235Z","steps":["trace[1087411536] 'range keys from in-memory index tree' (duration: 1.27670423s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:56.083177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.965215ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-07-31T07:16:56.544145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.442847ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-07-31T07:16:56.545233Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"54.281µs","request":"header: lease_revoke:","response":"size:27","error":"lease not found"} {"level":"warn","ts":"2026-07-31T07:16:56.918131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.730684ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-07-31T07:16:56.918334Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"15.5µs","request":"header: lease_revoke:","response":"size:27","error":"lease not found"} {"level":"warn","ts":"2026-07-31T07:16:56.918401Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"9.68µs","request":"header: lease_revoke:","response":"size:27","error":"lease not found"} {"level":"warn","ts":"2026-07-31T07:16:56.918417Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"45ef9fb705cda492","error":"lease not found"} {"level":"info","ts":"2026-07-31T07:16:56.918428Z","caller":"traceutil/trace.go:171","msg":"trace[1318072517] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1165; }","duration":"5.050283498s","start":"2026-07-31T07:16:51.868114Z","end":"2026-07-31T07:16:56.918397Z","steps":["trace[1318072517] 'process raft request' (duration: 5.050110415s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:56.918498Z","caller":"traceutil/trace.go:171","msg":"trace[1629341793] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1206; }","duration":"7.485497783s","start":"2026-07-31T07:16:49.432934Z","end":"2026-07-31T07:16:56.918432Z","steps":["trace[1629341793] 'read index received' (duration: 6.299095001s)","trace[1629341793] 'applied index is now lower than readState.Index' (duration: 1.186400592s)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:16:56.918535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.328416666s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T07:16:56.918527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.863173036s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T07:16:56.91856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.559871177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T07:16:56.918579Z","caller":"traceutil/trace.go:171","msg":"trace[589387907] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1165; }","duration":"11.328516117s","start":"2026-07-31T07:16:45.590048Z","end":"2026-07-31T07:16:56.918564Z","steps":["trace[589387907] 'agreement among raft nodes before linearized reading' (duration: 11.328420966s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:56.918564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:51.868089Z","time spent":"5.050393559s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":720,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:16:56.91857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.208716728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-31T07:16:56.918598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.137227625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-07-31T07:16:56.918627Z","caller":"traceutil/trace.go:171","msg":"trace[141750685] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1165; }","duration":"11.559974777s","start":"2026-07-31T07:16:45.358629Z","end":"2026-07-31T07:16:56.918603Z","steps":["trace[141750685] 'agreement among raft nodes before linearized reading' (duration: 11.559862066s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:56.918653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.44253065s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T07:16:56.918661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.894682092s","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-31T07:16:56.918662Z","caller":"traceutil/trace.go:171","msg":"trace[1021213676] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:1165; }","duration":"8.137312536s","start":"2026-07-31T07:16:48.781329Z","end":"2026-07-31T07:16:56.918642Z","steps":["trace[1021213676] 'agreement among raft nodes before linearized reading' (duration: 8.137144064s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:56.918668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"13.030483107s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.194\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:16:56.918653Z","caller":"traceutil/trace.go:171","msg":"trace[546624610] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1165; }","duration":"8.20883688s","start":"2026-07-31T07:16:48.709796Z","end":"2026-07-31T07:16:56.918633Z","steps":["trace[546624610] 'agreement among raft nodes before linearized reading' (duration: 8.208690278s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:56.918682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:45.35861Z","time spent":"11.560056988s","remote":"127.0.0.1:43948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":29,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"info","ts":"2026-07-31T07:16:56.918694Z","caller":"traceutil/trace.go:171","msg":"trace[253572438] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1165; }","duration":"10.894745942s","start":"2026-07-31T07:16:46.023936Z","end":"2026-07-31T07:16:56.918682Z","steps":["trace[253572438] 'agreement among raft nodes before linearized reading' (duration: 10.894703572s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:56.918709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"14.243302456s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T07:16:56.918707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:48.781311Z","time spent":"8.137384678s","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-07-31T07:16:56.918714Z","caller":"traceutil/trace.go:171","msg":"trace[687949895] range","detail":"{range_begin:/registry/masterleases/199.19.213.194; range_end:; response_count:0; response_revision:1165; }","duration":"13.030572449s","start":"2026-07-31T07:16:43.888126Z","end":"2026-07-31T07:16:56.918698Z","steps":["trace[687949895] 'agreement among raft nodes before linearized reading' (duration: 13.030474687s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:56.918732Z","caller":"traceutil/trace.go:171","msg":"trace[511179715] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1165; }","duration":"14.243351866s","start":"2026-07-31T07:16:42.675372Z","end":"2026-07-31T07:16:56.918724Z","steps":["trace[511179715] 'agreement among raft nodes before linearized reading' (duration: 14.243319995s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:56.91873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:46.023917Z","time spent":"10.894805813s","remote":"127.0.0.1:50470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:16:56.918729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:48.709774Z","time spent":"8.208939641s","remote":"127.0.0.1:43496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:16:56.918754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:42.675293Z","time spent":"14.243455207s","remote":"127.0.0.1:50434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:16:56.91875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:43.888102Z","time spent":"13.030640069s","remote":"127.0.0.1:43472","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.194\" "} {"level":"warn","ts":"2026-07-31T07:16:56.918769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.99756539s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T07:16:56.91879Z","caller":"traceutil/trace.go:171","msg":"trace[1634649195] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1165; }","duration":"6.997621941s","start":"2026-07-31T07:16:49.921162Z","end":"2026-07-31T07:16:56.918784Z","steps":["trace[1634649195] 'agreement among raft nodes before linearized reading' (duration: 6.99758566s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:56.918527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.242364202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T07:16:56.918807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:49.921143Z","time spent":"6.997659941s","remote":"127.0.0.1:43906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-31T07:16:56.918828Z","caller":"traceutil/trace.go:171","msg":"trace[419359338] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1165; }","duration":"10.242716306s","start":"2026-07-31T07:16:46.676096Z","end":"2026-07-31T07:16:56.918812Z","steps":["trace[419359338] 'agreement among raft nodes before linearized reading' (duration: 10.242364502s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:56.918706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.481985461s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18c74e90fecd9602\" ","response":"range_response_count:1 size:773"} {"level":"warn","ts":"2026-07-31T07:16:56.918874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:46.676074Z","time spent":"10.242784477s","remote":"127.0.0.1:37302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"info","ts":"2026-07-31T07:16:56.918678Z","caller":"traceutil/trace.go:171","msg":"trace[903614575] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1165; }","duration":"1.44255278s","start":"2026-07-31T07:16:55.476118Z","end":"2026-07-31T07:16:56.91867Z","steps":["trace[903614575] 'agreement among raft nodes before linearized reading' (duration: 1.44251994s)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:56.918896Z","caller":"traceutil/trace.go:171","msg":"trace[1275449317] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18c74e90fecd9602; range_end:; response_count:1; response_revision:1165; }","duration":"7.482199073s","start":"2026-07-31T07:16:49.436686Z","end":"2026-07-31T07:16:56.918885Z","steps":["trace[1275449317] 'agreement among raft nodes before linearized reading' (duration: 7.48194136s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:56.918913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.862172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:16:56.918938Z","caller":"traceutil/trace.go:171","msg":"trace[413890136] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1165; }","duration":"678.914912ms","start":"2026-07-31T07:16:56.240015Z","end":"2026-07-31T07:16:56.91893Z","steps":["trace[413890136] 'agreement among raft nodes before linearized reading' (duration: 678.873402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:56.918927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:55.47608Z","time spent":"1.442836123s","remote":"127.0.0.1:43454","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T07:16:56.918603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.464227552s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7111"} {"level":"warn","ts":"2026-07-31T07:16:56.918958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:56.24Z","time spent":"678.951802ms","remote":"127.0.0.1:43648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-07-31T07:16:56.918968Z","caller":"traceutil/trace.go:171","msg":"trace[1632957104] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:1165; }","duration":"9.464628167s","start":"2026-07-31T07:16:47.454331Z","end":"2026-07-31T07:16:56.91896Z","steps":["trace[1632957104] 'agreement among raft nodes before linearized reading' (duration: 9.464237653s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:56.918994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:47.454309Z","time spent":"9.464678428s","remote":"127.0.0.1:43610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7133,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-07-31T07:16:56.918735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.81171278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T07:16:56.918618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:45.590026Z","time spent":"11.328582608s","remote":"127.0.0.1:50494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-07-31T07:16:56.919043Z","caller":"traceutil/trace.go:171","msg":"trace[185289776] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1165; }","duration":"4.812046173s","start":"2026-07-31T07:16:52.106978Z","end":"2026-07-31T07:16:56.919025Z","steps":["trace[185289776] 'agreement among raft nodes before linearized reading' (duration: 4.81174032s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:56.918936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:49.436668Z","time spent":"7.482252893s","remote":"127.0.0.1:43520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":795,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18c74e90fecd9602\" "} {"level":"warn","ts":"2026-07-31T07:16:56.919091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:52.106951Z","time spent":"4.812124004s","remote":"127.0.0.1:59020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:16:56.918438Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"45ef9fb705cda4e7","error":"lease not found"} {"level":"info","ts":"2026-07-31T07:16:56.918594Z","caller":"traceutil/trace.go:171","msg":"trace[730366832] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1165; }","duration":"7.863272117s","start":"2026-07-31T07:16:49.055305Z","end":"2026-07-31T07:16:56.918577Z","steps":["trace[730366832] 'agreement among raft nodes before linearized reading' (duration: 7.863177626s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:56.919232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:49.055289Z","time spent":"7.863932385s","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-07-31T07:16:57.392165Z","caller":"traceutil/trace.go:171","msg":"trace[605239946] linearizableReadLoop","detail":"{readStateIndex:1222; appliedIndex:1220; }","duration":"469.859981ms","start":"2026-07-31T07:16:56.922284Z","end":"2026-07-31T07:16:57.392144Z","steps":["trace[605239946] 'read index received' (duration: 265.885946ms)","trace[605239946] 'applied index is now lower than readState.Index' (duration: 203.972895ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T07:16:57.392266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:56.922211Z","time spent":"470.053033ms","remote":"127.0.0.1:43520","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-31T07:16:57.392577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.278176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:16:57.392663Z","caller":"traceutil/trace.go:171","msg":"trace[1161370494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1166; }","duration":"470.366607ms","start":"2026-07-31T07:16:56.922278Z","end":"2026-07-31T07:16:57.392644Z","steps":["trace[1161370494] 'agreement among raft nodes before linearized reading' (duration: 470.235705ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:57.392656Z","caller":"traceutil/trace.go:171","msg":"trace[613929768] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"467.649265ms","start":"2026-07-31T07:16:56.9248Z","end":"2026-07-31T07:16:57.392449Z","steps":["trace[613929768] 'process raft request' (duration: 467.21699ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:57.392928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:56.924784Z","time spent":"468.05278ms","remote":"127.0.0.1:43610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T07:16:57.392723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:56.922238Z","time spent":"470.471628ms","remote":"127.0.0.1:43438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-31T07:16:57.393587Z","caller":"traceutil/trace.go:171","msg":"trace[658047180] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"204.266878ms","start":"2026-07-31T07:16:57.189309Z","end":"2026-07-31T07:16:57.393576Z","steps":["trace[658047180] 'process raft request' (duration: 204.108496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:57.393693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.735151ms","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":"info","ts":"2026-07-31T07:16:57.393746Z","caller":"traceutil/trace.go:171","msg":"trace[803938673] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1167; }","duration":"355.833172ms","start":"2026-07-31T07:16:57.0379Z","end":"2026-07-31T07:16:57.393734Z","steps":["trace[803938673] 'agreement among raft nodes before linearized reading' (duration: 355.71509ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:57.393799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:57.037881Z","time spent":"355.907572ms","remote":"127.0.0.1:50338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-31T07:16:57.394001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.85924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T07:16:57.394051Z","caller":"traceutil/trace.go:171","msg":"trace[619211868] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1167; }","duration":"195.954611ms","start":"2026-07-31T07:16:57.198085Z","end":"2026-07-31T07:16:57.39404Z","steps":["trace[619211868] 'agreement among raft nodes before linearized reading' (duration: 195.87514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:57.394409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.745241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:1 size:687"} {"level":"warn","ts":"2026-07-31T07:16:57.394415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.842715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5548"} {"level":"info","ts":"2026-07-31T07:16:57.39446Z","caller":"traceutil/trace.go:171","msg":"trace[1159266418] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:1; response_revision:1167; }","duration":"470.810872ms","start":"2026-07-31T07:16:56.923635Z","end":"2026-07-31T07:16:57.394446Z","steps":["trace[1159266418] 'agreement among raft nodes before linearized reading' (duration: 470.69106ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T07:16:57.394475Z","caller":"traceutil/trace.go:171","msg":"trace[2125632476] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1167; }","duration":"388.934316ms","start":"2026-07-31T07:16:57.005523Z","end":"2026-07-31T07:16:57.394458Z","steps":["trace[2125632476] 'agreement among raft nodes before linearized reading' (duration: 388.775564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:57.394492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:56.923628Z","time spent":"470.855762ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":709,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"warn","ts":"2026-07-31T07:16:57.394517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:57.005504Z","time spent":"389.001028ms","remote":"127.0.0.1:43606","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5570,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-31T07:16:57.395685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.514719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:536"} {"level":"info","ts":"2026-07-31T07:16:57.395818Z","caller":"traceutil/trace.go:171","msg":"trace[677448499] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:1167; }","duration":"472.924276ms","start":"2026-07-31T07:16:56.922866Z","end":"2026-07-31T07:16:57.39579Z","steps":["trace[677448499] 'agreement among raft nodes before linearized reading' (duration: 470.7174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T07:16:57.395876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T07:16:56.922847Z","time spent":"473.013208ms","remote":"127.0.0.1:43704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "}