{"level":"warn","ts":"2026-08-24T15:14:40.762339Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:14:40.762447Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.100: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.100:2380","--initial-cluster=instance=https://199.19.213.100:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.100:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.100: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-08-24T15:14:40.76257Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:14:40.762587Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-08-24T15:14:40.762609Z","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-08-24T15:14:40.763258Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"]} {"level":"info","ts":"2026-08-24T15:14:40.763464Z","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.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.100: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-08-24T15:14:40.769363Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.587389ms"} {"level":"info","ts":"2026-08-24T15:14:40.779959Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"79e65b4197a2a1a","cluster-id":"bba89cbb2a6fc22a"} {"level":"info","ts":"2026-08-24T15:14:40.780605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T15:14:40.780682Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 0"} {"level":"info","ts":"2026-08-24T15:14:40.780703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 79e65b4197a2a1a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T15:14:40.780748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became follower at term 1"} {"level":"info","ts":"2026-08-24T15:14:40.780844Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"warn","ts":"2026-08-24T15:14:40.793478Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T15:14:40.794862Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T15:14:40.795937Z","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-08-24T15:14:40.798058Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"79e65b4197a2a1a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T15:14:40.798271Z","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-08-24T15:14:40.798325Z","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-08-24T15:14:40.798336Z","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-08-24T15:14:40.798264Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"79e65b4197a2a1a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T15:14:40.801861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a switched to configuration voters=(548988028781734426)"} {"level":"info","ts":"2026-08-24T15:14:40.802019Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","added-peer-id":"79e65b4197a2a1a","added-peer-peer-urls":["https://199.19.213.100:2380"]} {"level":"info","ts":"2026-08-24T15:14:40.803122Z","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-08-24T15:14:40.803347Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-08-24T15:14:40.803382Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.100:2380"} {"level":"info","ts":"2026-08-24T15:14:40.803661Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"79e65b4197a2a1a","initial-advertise-peer-urls":["https://199.19.213.100:2380"],"listen-peer-urls":["https://199.19.213.100:2380"],"advertise-client-urls":["https://199.19.213.100:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.100:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T15:14:40.803701Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T15:14:41.582309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T15:14:41.582379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T15:14:41.582421Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgPreVoteResp from 79e65b4197a2a1a at term 1"} {"level":"info","ts":"2026-08-24T15:14:41.58244Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became candidate at term 2"} {"level":"info","ts":"2026-08-24T15:14:41.582451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a received MsgVoteResp from 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-08-24T15:14:41.582466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"79e65b4197a2a1a became leader at term 2"} {"level":"info","ts":"2026-08-24T15:14:41.582478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 79e65b4197a2a1a elected leader 79e65b4197a2a1a at term 2"} {"level":"info","ts":"2026-08-24T15:14:41.583821Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"79e65b4197a2a1a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.100:2379]}","request-path":"/0/members/79e65b4197a2a1a/attributes","cluster-id":"bba89cbb2a6fc22a","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T15:14:41.58389Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:14:41.584134Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:14:41.584582Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:14:41.58491Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T15:14:41.584976Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T15:14:41.585579Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bba89cbb2a6fc22a","local-member-id":"79e65b4197a2a1a","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:14:41.585786Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:14:41.585821Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:14:41.5876Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.100:2379"} {"level":"info","ts":"2026-08-24T15:14:41.590376Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-24T15:15:05.174224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:04.4953Z","time spent":"678.920152ms","remote":"127.0.0.1:35378","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-24T15:15:05.174359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.844565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:15:05.174226Z","caller":"traceutil/trace.go:171","msg":"trace[1254300713] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:432; }","duration":"309.710219ms","start":"2026-08-24T15:15:04.864495Z","end":"2026-08-24T15:15:05.174205Z","steps":["trace[1254300713] 'read index received' (duration: 309.53262ms)","trace[1254300713] 'applied index is now lower than readState.Index' (duration: 176.179µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:05.174416Z","caller":"traceutil/trace.go:171","msg":"trace[2057147806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:420; }","duration":"309.998302ms","start":"2026-08-24T15:15:04.864408Z","end":"2026-08-24T15:15:05.174406Z","steps":["trace[2057147806] 'agreement among raft nodes before linearized reading' (duration: 309.883457ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:05.174447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:04.864326Z","time spent":"310.114608ms","remote":"127.0.0.1:35328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:15:05.471152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.489845ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:15:05.471272Z","caller":"traceutil/trace.go:171","msg":"trace[1594217686] linearizableReadLoop","detail":"{readStateIndex:434; appliedIndex:433; }","duration":"295.787611ms","start":"2026-08-24T15:15:05.175468Z","end":"2026-08-24T15:15:05.471256Z","steps":["trace[1594217686] 'read index received' (duration: 135.678617ms)","trace[1594217686] 'applied index is now lower than readState.Index' (duration: 160.107874ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:05.471454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.97885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:15:05.471403Z","caller":"traceutil/trace.go:171","msg":"trace[1873358385] transaction","detail":"{read_only:false; response_revision:421; number_of_response:1; }","duration":"295.953199ms","start":"2026-08-24T15:15:05.175379Z","end":"2026-08-24T15:15:05.471332Z","steps":["trace[1873358385] 'process raft request' (duration: 135.75747ms)","trace[1873358385] 'compare' (duration: 159.37495ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:05.471499Z","caller":"traceutil/trace.go:171","msg":"trace[740049038] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:421; }","duration":"296.059094ms","start":"2026-08-24T15:15:05.175427Z","end":"2026-08-24T15:15:05.471486Z","steps":["trace[740049038] 'agreement among raft nodes before linearized reading' (duration: 295.959839ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:05.683637Z","caller":"traceutil/trace.go:171","msg":"trace[340438924] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:434; }","duration":"207.294382ms","start":"2026-08-24T15:15:05.476325Z","end":"2026-08-24T15:15:05.683619Z","steps":["trace[340438924] 'read index received' (duration: 157.620576ms)","trace[340438924] 'applied index is now lower than readState.Index' (duration: 49.672306ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:05.683743Z","caller":"traceutil/trace.go:171","msg":"trace[1905069964] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"206.077155ms","start":"2026-08-24T15:15:05.477642Z","end":"2026-08-24T15:15:05.683719Z","steps":["trace[1905069964] 'process raft request' (duration: 205.812183ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:05.683966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.417878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-24T15:15:05.684027Z","caller":"traceutil/trace.go:171","msg":"trace[2093429790] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:424; }","duration":"207.739534ms","start":"2026-08-24T15:15:05.476274Z","end":"2026-08-24T15:15:05.684014Z","steps":["trace[2093429790] 'agreement among raft nodes before linearized reading' (duration: 207.420449ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:05.683535Z","caller":"traceutil/trace.go:171","msg":"trace[25759561] transaction","detail":"{read_only:false; response_revision:422; number_of_response:1; }","duration":"208.511121ms","start":"2026-08-24T15:15:05.474989Z","end":"2026-08-24T15:15:05.6835Z","steps":["trace[25759561] 'process raft request' (duration: 158.990531ms)","trace[25759561] 'compare' (duration: 49.300609ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:05.683555Z","caller":"traceutil/trace.go:171","msg":"trace[1572021065] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"208.110712ms","start":"2026-08-24T15:15:05.475396Z","end":"2026-08-24T15:15:05.683507Z","steps":["trace[1572021065] 'process raft request' (duration: 208.019378ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:13.897185Z","caller":"traceutil/trace.go:171","msg":"trace[2100801288] linearizableReadLoop","detail":"{readStateIndex:454; appliedIndex:453; }","duration":"173.46481ms","start":"2026-08-24T15:15:13.723667Z","end":"2026-08-24T15:15:13.897132Z","steps":["trace[2100801288] 'read index received' (duration: 173.176077ms)","trace[2100801288] 'applied index is now lower than readState.Index' (duration: 287.253µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:13.89731Z","caller":"traceutil/trace.go:171","msg":"trace[1420223512] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"173.884818ms","start":"2026-08-24T15:15:13.723414Z","end":"2026-08-24T15:15:13.897299Z","steps":["trace[1420223512] 'process raft request' (duration: 173.575425ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:13.897491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.804434ms","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-08-24T15:15:13.897534Z","caller":"traceutil/trace.go:171","msg":"trace[1151719862] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:440; }","duration":"173.878508ms","start":"2026-08-24T15:15:13.723644Z","end":"2026-08-24T15:15:13.897523Z","steps":["trace[1151719862] 'agreement among raft nodes before linearized reading' (duration: 173.792214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:14.397592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033913445637820657,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T15:15:14.657118Z","caller":"traceutil/trace.go:171","msg":"trace[1663015132] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"932.926981ms","start":"2026-08-24T15:15:13.724159Z","end":"2026-08-24T15:15:14.657086Z","steps":["trace[1663015132] 'process raft request' (duration: 932.755923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:14.657382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:13.724143Z","time spent":"933.092258ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:15:15.863526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999924337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:15:15.863596Z","caller":"traceutil/trace.go:171","msg":"trace[1048359325] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000043382s","start":"2026-08-24T15:15:13.863536Z","end":"2026-08-24T15:15:15.863579Z","steps":["trace[1048359325] 'agreement among raft nodes before linearized reading' (duration: 1.999956178s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:15.863632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:13.863515Z","time spent":"2.000109555s","remote":"127.0.0.1:35328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/24 15:15:15 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:15:16.837197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282276192s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:15:16.837594Z","caller":"traceutil/trace.go:171","msg":"trace[1777301921] linearizableReadLoop","detail":"{readStateIndex:456; appliedIndex:454; }","duration":"2.940291049s","start":"2026-08-24T15:15:13.897264Z","end":"2026-08-24T15:15:16.837555Z","steps":["trace[1777301921] 'read index received' (duration: 759.657789ms)","trace[1777301921] 'applied index is now lower than readState.Index' (duration: 2.1806316s)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:16.83738Z","caller":"traceutil/trace.go:171","msg":"trace[1856730418] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"2.934765138s","start":"2026-08-24T15:15:13.902584Z","end":"2026-08-24T15:15:16.837349Z","steps":["trace[1856730418] 'process raft request' (duration: 1.652198014s)","trace[1856730418] 'compare' (duration: 1.281945798s)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:16.837844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.718065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-24T15:15:16.837863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.448238795s","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-08-24T15:15:16.837914Z","caller":"traceutil/trace.go:171","msg":"trace[1380626252] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:443; }","duration":"936.83448ms","start":"2026-08-24T15:15:15.901068Z","end":"2026-08-24T15:15:16.837902Z","steps":["trace[1380626252] 'agreement among raft nodes before linearized reading' (duration: 936.724115ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:16.838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.343542788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.100\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-24T15:15:16.838027Z","caller":"traceutil/trace.go:171","msg":"trace[1826489161] range","detail":"{range_begin:/registry/masterleases/199.19.213.100; range_end:; response_count:1; response_revision:443; }","duration":"2.343597631s","start":"2026-08-24T15:15:14.494424Z","end":"2026-08-24T15:15:16.838021Z","steps":["trace[1826489161] 'agreement among raft nodes before linearized reading' (duration: 2.343550889s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:16.838057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:14.494407Z","time spent":"2.343643332s","remote":"127.0.0.1:35378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.100\" "} {"level":"warn","ts":"2026-08-24T15:15:16.838063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:15.901017Z","time spent":"936.997406ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-24T15:15:16.838114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.886331139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12677"} {"level":"warn","ts":"2026-08-24T15:15:16.838159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"974.246985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:15:16.83821Z","caller":"traceutil/trace.go:171","msg":"trace[175080298] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:443; }","duration":"974.341468ms","start":"2026-08-24T15:15:15.86386Z","end":"2026-08-24T15:15:16.838201Z","steps":["trace[175080298] 'agreement among raft nodes before linearized reading' (duration: 974.257545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:16.838227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:15.863841Z","time spent":"974.381491ms","remote":"127.0.0.1:35312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:15:16.838168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.904527ms","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-08-24T15:15:16.83833Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.904742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:15:16.83821Z","caller":"traceutil/trace.go:171","msg":"trace[1875127551] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:443; }","duration":"1.886526968s","start":"2026-08-24T15:15:14.951667Z","end":"2026-08-24T15:15:16.838194Z","steps":["trace[1875127551] 'agreement among raft nodes before linearized reading' (duration: 1.886192454s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:16.838296Z","caller":"traceutil/trace.go:171","msg":"trace[1472048041] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:443; }","duration":"177.037344ms","start":"2026-08-24T15:15:16.661219Z","end":"2026-08-24T15:15:16.838257Z","steps":["trace[1472048041] 'agreement among raft nodes before linearized reading' (duration: 176.765111ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:16.838364Z","caller":"traceutil/trace.go:171","msg":"trace[1855478358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:443; }","duration":"351.972636ms","start":"2026-08-24T15:15:16.486384Z","end":"2026-08-24T15:15:16.838356Z","steps":["trace[1855478358] 'agreement among raft nodes before linearized reading' (duration: 351.925544ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:16.837954Z","caller":"traceutil/trace.go:171","msg":"trace[925384141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:443; }","duration":"2.44836722s","start":"2026-08-24T15:15:14.389568Z","end":"2026-08-24T15:15:16.837935Z","steps":["trace[925384141] 'agreement among raft nodes before linearized reading' (duration: 2.448199803s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:16.838387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:16.486365Z","time spent":"352.017317ms","remote":"127.0.0.1:35328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:15:16.838392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:14.389552Z","time spent":"2.448833901s","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:15:16.837888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:13.902563Z","time spent":"2.935200757s","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:15:16.837614Z","caller":"traceutil/trace.go:171","msg":"trace[1030318816] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"119.462968ms","start":"2026-08-24T15:15:16.718064Z","end":"2026-08-24T15:15:16.837527Z","steps":["trace[1030318816] 'process raft request' (duration: 119.256659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:16.838377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:14.951643Z","time spent":"1.886723467s","remote":"127.0.0.1:35424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12700,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-08-24T15:15:17.054071Z","caller":"traceutil/trace.go:171","msg":"trace[1951143864] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:457; }","duration":"103.680656ms","start":"2026-08-24T15:15:16.950371Z","end":"2026-08-24T15:15:17.054052Z","steps":["trace[1951143864] 'read index received' (duration: 8.621567ms)","trace[1951143864] 'applied index is now lower than readState.Index' (duration: 95.056849ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:17.05438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.997179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:15:17.054407Z","caller":"traceutil/trace.go:171","msg":"trace[1996504077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:447; }","duration":"104.062941ms","start":"2026-08-24T15:15:16.950338Z","end":"2026-08-24T15:15:17.054401Z","steps":["trace[1996504077] 'agreement among raft nodes before linearized reading' (duration: 103.999539ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:17.054392Z","caller":"traceutil/trace.go:171","msg":"trace[539542987] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"211.720236ms","start":"2026-08-24T15:15:16.842627Z","end":"2026-08-24T15:15:17.054347Z","steps":["trace[539542987] 'process raft request' (duration: 211.405333ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:17.054459Z","caller":"traceutil/trace.go:171","msg":"trace[1165205951] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"212.557972ms","start":"2026-08-24T15:15:16.841876Z","end":"2026-08-24T15:15:17.054434Z","steps":["trace[1165205951] 'process raft request' (duration: 212.044329ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:17.05451Z","caller":"traceutil/trace.go:171","msg":"trace[886538410] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"212.530011ms","start":"2026-08-24T15:15:16.841974Z","end":"2026-08-24T15:15:17.054504Z","steps":["trace[886538410] 'process raft request' (duration: 212.015139ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:17.054595Z","caller":"traceutil/trace.go:171","msg":"trace[1770018288] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"212.399045ms","start":"2026-08-24T15:15:16.842189Z","end":"2026-08-24T15:15:17.054588Z","steps":["trace[1770018288] 'process raft request' (duration: 211.825781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:18.298961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.462128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:15:18.299058Z","caller":"traceutil/trace.go:171","msg":"trace[926806226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"431.599554ms","start":"2026-08-24T15:15:17.867433Z","end":"2026-08-24T15:15:18.299032Z","steps":["trace[926806226] 'range keys from in-memory index tree' (duration: 431.398746ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:18.299144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:17.867416Z","time spent":"431.710788ms","remote":"127.0.0.1:35328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:15:18.299545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.946152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:15:18.299604Z","caller":"traceutil/trace.go:171","msg":"trace[1593170231] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:448; }","duration":"357.040606ms","start":"2026-08-24T15:15:17.94255Z","end":"2026-08-24T15:15:18.299591Z","steps":["trace[1593170231] 'range keys from in-memory index tree' (duration: 356.881219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:18.299629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:17.942535Z","time spent":"357.087687ms","remote":"127.0.0.1:35534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"info","ts":"2026-08-24T15:15:18.766688Z","caller":"traceutil/trace.go:171","msg":"trace[170867054] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"368.251669ms","start":"2026-08-24T15:15:18.398418Z","end":"2026-08-24T15:15:18.76667Z","steps":["trace[170867054] 'process raft request' (duration: 368.022469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:18.766793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:18.398403Z","time spent":"368.340433ms","remote":"127.0.0.1:35424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:15:19.280868Z","caller":"traceutil/trace.go:171","msg":"trace[976470263] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"128.072146ms","start":"2026-08-24T15:15:19.152765Z","end":"2026-08-24T15:15:19.280837Z","steps":["trace[976470263] 'process raft request' (duration: 127.707961ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:19.281352Z","caller":"traceutil/trace.go:171","msg":"trace[1968924328] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"108.236839ms","start":"2026-08-24T15:15:19.172982Z","end":"2026-08-24T15:15:19.281219Z","steps":["trace[1968924328] 'process raft request' (duration: 107.768579ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:21.310482Z","caller":"traceutil/trace.go:171","msg":"trace[633411670] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"539.106059ms","start":"2026-08-24T15:15:20.771349Z","end":"2026-08-24T15:15:21.310455Z","steps":["trace[633411670] 'process raft request' (duration: 538.952112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.31074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:20.771333Z","time spent":"539.232404ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:15:21.471358Z","caller":"traceutil/trace.go:171","msg":"trace[1684353337] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"690.369852ms","start":"2026-08-24T15:15:20.78095Z","end":"2026-08-24T15:15:21.47132Z","steps":["trace[1684353337] 'process raft request' (duration: 690.229146ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:21.471379Z","caller":"traceutil/trace.go:171","msg":"trace[877855870] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"690.435695ms","start":"2026-08-24T15:15:20.780884Z","end":"2026-08-24T15:15:21.471319Z","steps":["trace[877855870] 'process raft request' (duration: 690.229187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.471586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:20.780868Z","time spent":"690.627512ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:15:21.471779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:20.780946Z","time spent":"690.519068ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:15:21.472044Z","caller":"traceutil/trace.go:171","msg":"trace[1717100730] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"686.515195ms","start":"2026-08-24T15:15:20.785498Z","end":"2026-08-24T15:15:21.472013Z","steps":["trace[1717100730] 'process raft request' (duration: 685.724822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.472228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:20.785481Z","time spent":"686.678191ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:15:21.472207Z","caller":"traceutil/trace.go:171","msg":"trace[117474641] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"686.636399ms","start":"2026-08-24T15:15:20.785556Z","end":"2026-08-24T15:15:21.472192Z","steps":["trace[117474641] 'process raft request' (duration: 685.708711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.472503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:20.785552Z","time spent":"686.885089ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:15:21.471402Z","caller":"traceutil/trace.go:171","msg":"trace[1162722461] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"698.422462ms","start":"2026-08-24T15:15:20.77281Z","end":"2026-08-24T15:15:21.471232Z","steps":["trace[1162722461] 'process raft request' (duration: 698.164951ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:21.473239Z","caller":"traceutil/trace.go:171","msg":"trace[1495830771] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"685.760023ms","start":"2026-08-24T15:15:20.787455Z","end":"2026-08-24T15:15:21.473215Z","steps":["trace[1495830771] 'process raft request' (duration: 684.646808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.473332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:20.787449Z","time spent":"685.843217ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:15:21.473644Z","caller":"traceutil/trace.go:171","msg":"trace[243579646] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:502; }","duration":"607.642238ms","start":"2026-08-24T15:15:20.865976Z","end":"2026-08-24T15:15:21.473618Z","steps":["trace[243579646] 'read index received' (duration: 444.280081ms)","trace[243579646] 'applied index is now lower than readState.Index' (duration: 163.361237ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:21.473687Z","caller":"traceutil/trace.go:171","msg":"trace[427006429] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"664.196592ms","start":"2026-08-24T15:15:20.809477Z","end":"2026-08-24T15:15:21.473673Z","steps":["trace[427006429] 'process raft request' (duration: 663.788895ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.473791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:20.809464Z","time spent":"664.260614ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:15:21.474003Z","caller":"traceutil/trace.go:171","msg":"trace[848471093] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"577.774916ms","start":"2026-08-24T15:15:20.896215Z","end":"2026-08-24T15:15:21.47399Z","steps":["trace[848471093] 'process raft request' (duration: 577.136451ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:21.474054Z","caller":"traceutil/trace.go:171","msg":"trace[2097231196] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"685.895408ms","start":"2026-08-24T15:15:20.788145Z","end":"2026-08-24T15:15:21.47404Z","steps":["trace[2097231196] 'process raft request' (duration: 684.978661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.474121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:20.788136Z","time spent":"685.954971ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:15:21.474318Z","caller":"traceutil/trace.go:171","msg":"trace[1552259923] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"672.642377ms","start":"2026-08-24T15:15:20.801663Z","end":"2026-08-24T15:15:21.474306Z","steps":["trace[1552259923] 'process raft request' (duration: 671.547953ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.474378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:20.801644Z","time spent":"672.707209ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:15:21.474406Z","caller":"traceutil/trace.go:171","msg":"trace[424531182] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"487.021005ms","start":"2026-08-24T15:15:20.987349Z","end":"2026-08-24T15:15:21.47437Z","steps":["trace[424531182] 'process raft request' (duration: 486.208072ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.4746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:20.987332Z","time spent":"487.197572ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:15:21.474912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.926432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:15:21.474961Z","caller":"traceutil/trace.go:171","msg":"trace[2058424328] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"609.000855ms","start":"2026-08-24T15:15:20.865948Z","end":"2026-08-24T15:15:21.474948Z","steps":["trace[2058424328] 'agreement among raft nodes before linearized reading' (duration: 608.894711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.474997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:20.865934Z","time spent":"609.057307ms","remote":"127.0.0.1:35328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:15:21.47495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:20.896187Z","time spent":"577.84941ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:15:21.47724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:20.772782Z","time spent":"699.871291ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:15:21.694664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.17608ms","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-08-24T15:15:21.694825Z","caller":"traceutil/trace.go:171","msg":"trace[1434866274] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:498; }","duration":"105.358397ms","start":"2026-08-24T15:15:21.589443Z","end":"2026-08-24T15:15:21.694802Z","steps":["trace[1434866274] 'range keys from in-memory index tree' (duration: 105.15765ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:21.695061Z","caller":"traceutil/trace.go:171","msg":"trace[156602745] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"377.401199ms","start":"2026-08-24T15:15:21.317627Z","end":"2026-08-24T15:15:21.695028Z","steps":["trace[156602745] 'process raft request' (duration: 377.19639ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:21.695161Z","caller":"traceutil/trace.go:171","msg":"trace[70842232] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"374.326903ms","start":"2026-08-24T15:15:21.320819Z","end":"2026-08-24T15:15:21.695146Z","steps":["trace[70842232] 'process raft request' (duration: 374.068913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.695225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:21.320807Z","time spent":"374.380425ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:15:21.695215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:21.317613Z","time spent":"377.531284ms","remote":"127.0.0.1:35850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:15:21.695432Z","caller":"traceutil/trace.go:171","msg":"trace[1078071427] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"203.225642ms","start":"2026-08-24T15:15:21.492187Z","end":"2026-08-24T15:15:21.695413Z","steps":["trace[1078071427] 'process raft request' (duration: 203.126608ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:21.695499Z","caller":"traceutil/trace.go:171","msg":"trace[1156111619] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"208.329979ms","start":"2026-08-24T15:15:21.487162Z","end":"2026-08-24T15:15:21.695492Z","steps":["trace[1156111619] 'process raft request' (duration: 208.042158ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:21.6956Z","caller":"traceutil/trace.go:171","msg":"trace[1256874078] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"209.653742ms","start":"2026-08-24T15:15:21.485846Z","end":"2026-08-24T15:15:21.695499Z","steps":["trace[1256874078] 'process raft request' (duration: 209.247506ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:21.695668Z","caller":"traceutil/trace.go:171","msg":"trace[1884721033] linearizableReadLoop","detail":"{readStateIndex:518; appliedIndex:514; }","duration":"221.968432ms","start":"2026-08-24T15:15:21.473693Z","end":"2026-08-24T15:15:21.695661Z","steps":["trace[1884721033] 'read index received' (duration: 177.043402ms)","trace[1884721033] 'applied index is now lower than readState.Index' (duration: 44.92401ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:21.695734Z","caller":"traceutil/trace.go:171","msg":"trace[128622704] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"378.234442ms","start":"2026-08-24T15:15:21.316715Z","end":"2026-08-24T15:15:21.69495Z","steps":["trace[128622704] 'process raft request' (duration: 333.970469ms)","trace[128622704] 'compare' (duration: 43.879248ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:21.695892Z","caller":"traceutil/trace.go:171","msg":"trace[1638113730] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"190.976386ms","start":"2026-08-24T15:15:21.50491Z","end":"2026-08-24T15:15:21.695887Z","steps":["trace[1638113730] 'process raft request' (duration: 190.457955ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.695908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:21.316691Z","time spent":"379.151979ms","remote":"127.0.0.1:35850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:15:21.695968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.241409ms","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-08-24T15:15:21.696019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.134194ms","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-08-24T15:15:21.69604Z","caller":"traceutil/trace.go:171","msg":"trace[40626458] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:505; }","duration":"584.207457ms","start":"2026-08-24T15:15:21.111828Z","end":"2026-08-24T15:15:21.696035Z","steps":["trace[40626458] 'agreement among raft nodes before linearized reading' (duration: 583.871622ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:21.696043Z","caller":"traceutil/trace.go:171","msg":"trace[655745972] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:505; }","duration":"564.346853ms","start":"2026-08-24T15:15:21.131679Z","end":"2026-08-24T15:15:21.696026Z","steps":["trace[655745972] 'agreement among raft nodes before linearized reading' (duration: 564.098942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.696096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:21.131665Z","time spent":"564.418655ms","remote":"127.0.0.1:35592","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-08-24T15:15:21.696142Z","caller":"traceutil/trace.go:171","msg":"trace[27203908] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"191.150324ms","start":"2026-08-24T15:15:21.504988Z","end":"2026-08-24T15:15:21.696138Z","steps":["trace[27203908] 'process raft request' (duration: 190.804839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.695975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.974084ms","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-08-24T15:15:21.696185Z","caller":"traceutil/trace.go:171","msg":"trace[675465370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:505; }","duration":"795.205573ms","start":"2026-08-24T15:15:20.900963Z","end":"2026-08-24T15:15:21.696168Z","steps":["trace[675465370] 'agreement among raft nodes before linearized reading' (duration: 794.747274ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.696249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:20.900955Z","time spent":"795.278186ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:15:21.696059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:21.111805Z","time spent":"584.250309ms","remote":"127.0.0.1:35592","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-08-24T15:15:21.695633Z","caller":"traceutil/trace.go:171","msg":"trace[747333771] transaction","detail":"{read_only:false; number_of_response:1; response_revision:501; }","duration":"374.674467ms","start":"2026-08-24T15:15:21.32094Z","end":"2026-08-24T15:15:21.695614Z","steps":["trace[747333771] 'process raft request' (duration: 374.017011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.696529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:21.320931Z","time spent":"375.540833ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":10112,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:15:21.696839Z","caller":"traceutil/trace.go:171","msg":"trace[125496972] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"191.7938ms","start":"2026-08-24T15:15:21.505029Z","end":"2026-08-24T15:15:21.696823Z","steps":["trace[125496972] 'process raft request' (duration: 191.06465ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.697058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.865081ms","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-08-24T15:15:21.700189Z","caller":"traceutil/trace.go:171","msg":"trace[405507313] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:507; }","duration":"584.012158ms","start":"2026-08-24T15:15:21.116149Z","end":"2026-08-24T15:15:21.700161Z","steps":["trace[405507313] 'agreement among raft nodes before linearized reading' (duration: 580.212824ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.700419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:21.116133Z","time spent":"584.132283ms","remote":"127.0.0.1:35592","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-08-24T15:15:21.701196Z","caller":"traceutil/trace.go:171","msg":"trace[1524825929] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"195.020309ms","start":"2026-08-24T15:15:21.506123Z","end":"2026-08-24T15:15:21.701143Z","steps":["trace[1524825929] 'process raft request' (duration: 190.57453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:21.703017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.632909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:15:21.703184Z","caller":"traceutil/trace.go:171","msg":"trace[1505703146] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"222.728583ms","start":"2026-08-24T15:15:21.480347Z","end":"2026-08-24T15:15:21.703076Z","steps":["trace[1505703146] 'agreement among raft nodes before linearized reading' (duration: 221.918ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:21.703869Z","caller":"traceutil/trace.go:171","msg":"trace[2032731694] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"180.756422ms","start":"2026-08-24T15:15:21.523078Z","end":"2026-08-24T15:15:21.703835Z","steps":["trace[2032731694] 'process raft request' (duration: 176.271491ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:23.114945Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.098342319s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-24T15:15:23.652991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.813153ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:15:23.653094Z","caller":"traceutil/trace.go:171","msg":"trace[1525111654] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.636588469s","start":"2026-08-24T15:15:22.016488Z","end":"2026-08-24T15:15:23.653076Z","steps":["trace[1525111654] 'process raft request' (duration: 1.09863099s)","trace[1525111654] 'compare' (duration: 537.20534ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:23.653145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:22.016467Z","time spent":"1.636657802s","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:15:23.668157Z","caller":"traceutil/trace.go:171","msg":"trace[2014157502] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:553; }","duration":"805.567644ms","start":"2026-08-24T15:15:22.862571Z","end":"2026-08-24T15:15:23.668139Z","steps":["trace[2014157502] 'read index received' (duration: 252.711881ms)","trace[2014157502] 'applied index is now lower than readState.Index' (duration: 552.855193ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:23.668314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"805.774022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:15:23.668364Z","caller":"traceutil/trace.go:171","msg":"trace[2138650788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"805.871745ms","start":"2026-08-24T15:15:22.862484Z","end":"2026-08-24T15:15:23.668356Z","steps":["trace[2138650788] 'agreement among raft nodes before linearized reading' (duration: 805.7287ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:23.668456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:22.862464Z","time spent":"805.965619ms","remote":"127.0.0.1:35312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:15:23.669281Z","caller":"traceutil/trace.go:171","msg":"trace[501065469] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.636214728s","start":"2026-08-24T15:15:22.033032Z","end":"2026-08-24T15:15:23.669247Z","steps":["trace[501065469] 'process raft request' (duration: 1.63502358s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:23.669427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:22.033021Z","time spent":"1.636335623s","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:15:23.66947Z","caller":"traceutil/trace.go:171","msg":"trace[1231160102] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.63799747s","start":"2026-08-24T15:15:22.031453Z","end":"2026-08-24T15:15:23.669451Z","steps":["trace[1231160102] 'process raft request' (duration: 1.636440748s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:23.669581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:22.031432Z","time spent":"1.638091014s","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80679,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:15:24.637621Z","caller":"traceutil/trace.go:171","msg":"trace[2044369719] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"311.860086ms","start":"2026-08-24T15:15:24.325731Z","end":"2026-08-24T15:15:24.637591Z","steps":["trace[2044369719] 'read index received' (duration: 311.422628ms)","trace[2044369719] 'applied index is now lower than readState.Index' (duration: 436.228µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:24.637643Z","caller":"traceutil/trace.go:171","msg":"trace[179280799] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"314.427998ms","start":"2026-08-24T15:15:24.323176Z","end":"2026-08-24T15:15:24.637604Z","steps":["trace[179280799] 'process raft request' (duration: 314.137906ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:24.656029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:24.323156Z","time spent":"314.589224ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208461,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:15:24.656115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.374785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:80703"} {"level":"info","ts":"2026-08-24T15:15:24.656248Z","caller":"traceutil/trace.go:171","msg":"trace[557243783] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:567; }","duration":"330.513361ms","start":"2026-08-24T15:15:24.325707Z","end":"2026-08-24T15:15:24.65622Z","steps":["trace[557243783] 'agreement among raft nodes before linearized reading' (duration: 312.050174ms)","trace[557243783] 'range keys from bolt db' (duration: 18.3122ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:24.656287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:24.325699Z","time spent":"330.578033ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":1,"response size":80726,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/grpcroutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-08-24T15:15:24.706348Z","caller":"traceutil/trace.go:171","msg":"trace[2059360715] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"328.294002ms","start":"2026-08-24T15:15:24.378028Z","end":"2026-08-24T15:15:24.706322Z","steps":["trace[2059360715] 'process raft request' (duration: 327.941059ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:24.706401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.342567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.100\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-08-24T15:15:24.706466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:24.378009Z","time spent":"328.395397ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:15:24.706566Z","caller":"traceutil/trace.go:171","msg":"trace[1689116052] range","detail":"{range_begin:/registry/masterleases/199.19.213.100; range_end:; response_count:1; response_revision:568; }","duration":"210.43891ms","start":"2026-08-24T15:15:24.496003Z","end":"2026-08-24T15:15:24.706442Z","steps":["trace[1689116052] 'agreement among raft nodes before linearized reading' (duration: 210.296925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:25.013246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.233622ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-08-24T15:15:25.013419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:24.709346Z","time spent":"304.067676ms","remote":"127.0.0.1:35378","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-24T15:15:25.013561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.081134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-08-24T15:15:25.013451Z","caller":"traceutil/trace.go:171","msg":"trace[1559872192] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:585; }","duration":"241.96787ms","start":"2026-08-24T15:15:24.771471Z","end":"2026-08-24T15:15:25.013438Z","steps":["trace[1559872192] 'read index received' (duration: 47.648507ms)","trace[1559872192] 'applied index is now lower than readState.Index' (duration: 194.318853ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:25.0136Z","caller":"traceutil/trace.go:171","msg":"trace[1831221799] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"250.272417ms","start":"2026-08-24T15:15:24.763323Z","end":"2026-08-24T15:15:25.013596Z","steps":["trace[1831221799] 'process raft request' (duration: 250.017817ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:25.013581Z","caller":"traceutil/trace.go:171","msg":"trace[845659914] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:569; }","duration":"242.120306ms","start":"2026-08-24T15:15:24.771453Z","end":"2026-08-24T15:15:25.013573Z","steps":["trace[845659914] 'agreement among raft nodes before linearized reading' (duration: 242.015052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:25.013766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.05905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:15:25.013845Z","caller":"traceutil/trace.go:171","msg":"trace[1065495604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"131.185896ms","start":"2026-08-24T15:15:24.882639Z","end":"2026-08-24T15:15:25.013825Z","steps":["trace[1065495604] 'agreement among raft nodes before linearized reading' (duration: 131.060121ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:25.328525Z","caller":"traceutil/trace.go:171","msg":"trace[1250226581] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"309.879045ms","start":"2026-08-24T15:15:25.018621Z","end":"2026-08-24T15:15:25.3285Z","steps":["trace[1250226581] 'process raft request' (duration: 211.339489ms)","trace[1250226581] 'compare' (duration: 98.38761ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:25.32864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:25.018604Z","time spent":"309.972449ms","remote":"127.0.0.1:35378","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:15:25.328831Z","caller":"traceutil/trace.go:171","msg":"trace[1841264480] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"309.203909ms","start":"2026-08-24T15:15:25.019594Z","end":"2026-08-24T15:15:25.328798Z","steps":["trace[1841264480] 'read index received' (duration: 210.460805ms)","trace[1841264480] 'applied index is now lower than readState.Index' (duration: 98.738124ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:25.328802Z","caller":"traceutil/trace.go:171","msg":"trace[107862590] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"264.610588ms","start":"2026-08-24T15:15:25.064168Z","end":"2026-08-24T15:15:25.328779Z","steps":["trace[107862590] 'process raft request' (duration: 264.460892ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:25.328969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.370915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:31145"} {"level":"info","ts":"2026-08-24T15:15:25.328998Z","caller":"traceutil/trace.go:171","msg":"trace[594202088] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:571; }","duration":"309.410797ms","start":"2026-08-24T15:15:25.019579Z","end":"2026-08-24T15:15:25.32899Z","steps":["trace[594202088] 'agreement among raft nodes before linearized reading' (duration: 309.305693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:25.329018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:25.019572Z","time spent":"309.442818ms","remote":"127.0.0.1:35394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":31168,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-08-24T15:15:30.777438Z","caller":"traceutil/trace.go:171","msg":"trace[249932653] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"512.90068ms","start":"2026-08-24T15:15:30.264513Z","end":"2026-08-24T15:15:30.777414Z","steps":["trace[249932653] 'process raft request' (duration: 512.719833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:30.777564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:30.264499Z","time spent":"513.001984ms","remote":"127.0.0.1:35518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:15:31.109606Z","caller":"traceutil/trace.go:171","msg":"trace[1035249240] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"832.781372ms","start":"2026-08-24T15:15:30.276798Z","end":"2026-08-24T15:15:31.10958Z","steps":["trace[1035249240] 'process raft request' (duration: 832.167139ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:31.109826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:30.276765Z","time spent":"832.987719ms","remote":"127.0.0.1:35424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:15:31.994442Z","caller":"traceutil/trace.go:171","msg":"trace[210657588] linearizableReadLoop","detail":"{readStateIndex:622; appliedIndex:621; }","duration":"197.006857ms","start":"2026-08-24T15:15:31.797412Z","end":"2026-08-24T15:15:31.994419Z","steps":["trace[210657588] 'read index received' (duration: 196.837991ms)","trace[210657588] 'applied index is now lower than readState.Index' (duration: 167.726µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:31.994751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.854905ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:15:31.994881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.452194ms","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-08-24T15:15:31.994925Z","caller":"traceutil/trace.go:171","msg":"trace[1828607298] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:604; }","duration":"197.531676ms","start":"2026-08-24T15:15:31.797378Z","end":"2026-08-24T15:15:31.99491Z","steps":["trace[1828607298] 'agreement among raft nodes before linearized reading' (duration: 197.326409ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:31.994924Z","caller":"traceutil/trace.go:171","msg":"trace[577069376] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:603; }","duration":"405.594412ms","start":"2026-08-24T15:15:31.589309Z","end":"2026-08-24T15:15:31.994903Z","steps":["trace[577069376] 'range keys from in-memory index tree' (duration: 404.7209ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:31.995069Z","caller":"traceutil/trace.go:171","msg":"trace[2075352220] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"815.967991ms","start":"2026-08-24T15:15:31.179085Z","end":"2026-08-24T15:15:31.995053Z","steps":["trace[2075352220] 'process raft request' (duration: 815.191732ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:31.995168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.734225ms","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-08-24T15:15:31.995191Z","caller":"traceutil/trace.go:171","msg":"trace[253762374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:604; }","duration":"124.791246ms","start":"2026-08-24T15:15:31.870393Z","end":"2026-08-24T15:15:31.995185Z","steps":["trace[253762374] 'agreement among raft nodes before linearized reading' (duration: 124.729584ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:31.995191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:31.179049Z","time spent":"816.062824ms","remote":"127.0.0.1:35836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:15:31.99524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.447343ms","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-08-24T15:15:31.995289Z","caller":"traceutil/trace.go:171","msg":"trace[1968216127] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"177.508375ms","start":"2026-08-24T15:15:31.817766Z","end":"2026-08-24T15:15:31.995274Z","steps":["trace[1968216127] 'agreement among raft nodes before linearized reading' (duration: 177.36712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:31.99585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.914054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:15:31.995917Z","caller":"traceutil/trace.go:171","msg":"trace[9009877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"134.013608ms","start":"2026-08-24T15:15:31.861886Z","end":"2026-08-24T15:15:31.9959Z","steps":["trace[9009877] 'agreement among raft nodes before linearized reading' (duration: 133.163817ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:31.994961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.57646ms","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-08-24T15:15:31.998155Z","caller":"traceutil/trace.go:171","msg":"trace[1444369530] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"199.747028ms","start":"2026-08-24T15:15:31.798344Z","end":"2026-08-24T15:15:31.998091Z","steps":["trace[1444369530] 'agreement among raft nodes before linearized reading' (duration: 196.434586ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:34.542071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.49180691s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-24T15:15:34.542226Z","caller":"traceutil/trace.go:171","msg":"trace[897519270] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:622; }","duration":"2.536490538s","start":"2026-08-24T15:15:32.005713Z","end":"2026-08-24T15:15:34.542203Z","steps":["trace[897519270] 'read index received' (duration: 26.058185ms)","trace[897519270] 'applied index is now lower than readState.Index' (duration: 2.510430513s)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:34.542798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.537063998s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:15:34.542873Z","caller":"traceutil/trace.go:171","msg":"trace[782688273] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:608; }","duration":"2.537146401s","start":"2026-08-24T15:15:32.005683Z","end":"2026-08-24T15:15:34.542829Z","steps":["trace[782688273] 'agreement among raft nodes before linearized reading' (duration: 2.537056118s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:34.542919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:32.005668Z","time spent":"2.537236465s","remote":"127.0.0.1:35562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"info","ts":"2026-08-24T15:15:34.643846Z","caller":"traceutil/trace.go:171","msg":"trace[1440786479] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"101.555869ms","start":"2026-08-24T15:15:34.54226Z","end":"2026-08-24T15:15:34.643816Z","steps":["trace[1440786479] 'read index received' (duration: 101.319091ms)","trace[1440786479] 'applied index is now lower than readState.Index' (duration: 235.348µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:34.643874Z","caller":"traceutil/trace.go:171","msg":"trace[837667855] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"603.024611ms","start":"2026-08-24T15:15:34.040814Z","end":"2026-08-24T15:15:34.643839Z","steps":["trace[837667855] 'process raft request' (duration: 602.788093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:34.644043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.781534832s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:15:34.644065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:34.040779Z","time spent":"603.183127ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:15:34.644209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.479283ms","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-08-24T15:15:34.644239Z","caller":"traceutil/trace.go:171","msg":"trace[1002308434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"589.551376ms","start":"2026-08-24T15:15:34.054682Z","end":"2026-08-24T15:15:34.644233Z","steps":["trace[1002308434] 'agreement among raft nodes before linearized reading' (duration: 589.416541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:34.644273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:34.054662Z","time spent":"589.602857ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:15:34.644244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.498612ms","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-08-24T15:15:34.644084Z","caller":"traceutil/trace.go:171","msg":"trace[307050518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"1.781640117s","start":"2026-08-24T15:15:32.862432Z","end":"2026-08-24T15:15:34.644072Z","steps":["trace[307050518] 'agreement among raft nodes before linearized reading' (duration: 1.781509121s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:34.644357Z","caller":"traceutil/trace.go:171","msg":"trace[1638683967] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:609; }","duration":"587.671338ms","start":"2026-08-24T15:15:34.056661Z","end":"2026-08-24T15:15:34.644333Z","steps":["trace[1638683967] 'agreement among raft nodes before linearized reading' (duration: 587.241752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:34.644372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:32.862411Z","time spent":"1.781949078s","remote":"127.0.0.1:35328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:15:34.644418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:34.056648Z","time spent":"587.760031ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-24T15:15:34.644648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.806797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.100\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-24T15:15:34.644688Z","caller":"traceutil/trace.go:171","msg":"trace[448420551] range","detail":"{range_begin:/registry/masterleases/199.19.213.100; range_end:; response_count:1; response_revision:609; }","duration":"147.90502ms","start":"2026-08-24T15:15:34.496773Z","end":"2026-08-24T15:15:34.644678Z","steps":["trace[448420551] 'agreement among raft nodes before linearized reading' (duration: 147.782426ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:34.644789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.000643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-24T15:15:34.644659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.179588ms","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-08-24T15:15:34.644841Z","caller":"traceutil/trace.go:171","msg":"trace[1166718481] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:609; }","duration":"562.049265ms","start":"2026-08-24T15:15:34.082778Z","end":"2026-08-24T15:15:34.644827Z","steps":["trace[1166718481] 'agreement among raft nodes before linearized reading' (duration: 561.857278ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:34.644873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:34.082733Z","time spent":"562.133498ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-24T15:15:34.644896Z","caller":"traceutil/trace.go:171","msg":"trace[642390340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"606.543229ms","start":"2026-08-24T15:15:34.038323Z","end":"2026-08-24T15:15:34.644866Z","steps":["trace[642390340] 'agreement among raft nodes before linearized reading' (duration: 606.285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:34.645005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:34.038296Z","time spent":"606.666803ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-24T15:15:35.294595Z","caller":"traceutil/trace.go:171","msg":"trace[639787205] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"319.047929ms","start":"2026-08-24T15:15:34.975523Z","end":"2026-08-24T15:15:35.294571Z","steps":["trace[639787205] 'process raft request' (duration: 318.381664ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:35.294742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:34.975491Z","time spent":"319.174153ms","remote":"127.0.0.1:35424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:15:35.419396Z","caller":"traceutil/trace.go:171","msg":"trace[1426975388] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"107.708077ms","start":"2026-08-24T15:15:35.31166Z","end":"2026-08-24T15:15:35.419368Z","steps":["trace[1426975388] 'process raft request' (duration: 107.629265ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:35.419411Z","caller":"traceutil/trace.go:171","msg":"trace[498344570] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"150.402553ms","start":"2026-08-24T15:15:35.268978Z","end":"2026-08-24T15:15:35.41938Z","steps":["trace[498344570] 'process raft request' (duration: 84.170557ms)","trace[498344570] 'compare' (duration: 65.956626ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:35.639926Z","caller":"traceutil/trace.go:171","msg":"trace[1732268848] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"275.452641ms","start":"2026-08-24T15:15:35.364447Z","end":"2026-08-24T15:15:35.6399Z","steps":["trace[1732268848] 'process raft request' (duration: 189.948166ms)","trace[1732268848] 'compare' (duration: 85.344999ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:35.999178Z","caller":"traceutil/trace.go:171","msg":"trace[1523967536] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"258.310988ms","start":"2026-08-24T15:15:35.740844Z","end":"2026-08-24T15:15:35.999155Z","steps":["trace[1523967536] 'process raft request' (duration: 257.892154ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:36.005041Z","caller":"traceutil/trace.go:171","msg":"trace[1558730888] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:639; }","duration":"139.811885ms","start":"2026-08-24T15:15:35.865214Z","end":"2026-08-24T15:15:36.005026Z","steps":["trace[1558730888] 'read index received' (duration: 133.535291ms)","trace[1558730888] 'applied index is now lower than readState.Index' (duration: 6.275644ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:36.005151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.918199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:15:36.005213Z","caller":"traceutil/trace.go:171","msg":"trace[298780983] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:623; }","duration":"140.077954ms","start":"2026-08-24T15:15:35.865126Z","end":"2026-08-24T15:15:36.005204Z","steps":["trace[298780983] 'agreement among raft nodes before linearized reading' (duration: 139.95969ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:36.005478Z","caller":"traceutil/trace.go:171","msg":"trace[617417670] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"262.875522ms","start":"2026-08-24T15:15:35.742593Z","end":"2026-08-24T15:15:36.005469Z","steps":["trace[617417670] 'process raft request' (duration: 262.277661ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:36.005609Z","caller":"traceutil/trace.go:171","msg":"trace[261279037] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"262.475178ms","start":"2026-08-24T15:15:35.743125Z","end":"2026-08-24T15:15:36.0056Z","steps":["trace[261279037] 'process raft request' (duration: 261.830945ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:36.005862Z","caller":"traceutil/trace.go:171","msg":"trace[174583926] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"260.721175ms","start":"2026-08-24T15:15:35.74513Z","end":"2026-08-24T15:15:36.005852Z","steps":["trace[174583926] 'process raft request' (duration: 259.855714ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:40.597763Z","caller":"traceutil/trace.go:171","msg":"trace[1690454888] linearizableReadLoop","detail":"{readStateIndex:713; appliedIndex:712; }","duration":"117.483045ms","start":"2026-08-24T15:15:40.48025Z","end":"2026-08-24T15:15:40.597733Z","steps":["trace[1690454888] 'read index received' (duration: 116.105188ms)","trace[1690454888] 'applied index is now lower than readState.Index' (duration: 1.376727ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:40.597994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.722963ms","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-08-24T15:15:40.598041Z","caller":"traceutil/trace.go:171","msg":"trace[1744725539] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:692; }","duration":"117.850117ms","start":"2026-08-24T15:15:40.480176Z","end":"2026-08-24T15:15:40.598027Z","steps":["trace[1744725539] 'agreement among raft nodes before linearized reading' (duration: 117.665141ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:40.598727Z","caller":"traceutil/trace.go:171","msg":"trace[1667132387] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"164.113933ms","start":"2026-08-24T15:15:40.43389Z","end":"2026-08-24T15:15:40.598004Z","steps":["trace[1667132387] 'process raft request' (duration: 162.612132ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:48.854759Z","caller":"traceutil/trace.go:171","msg":"trace[863195854] linearizableReadLoop","detail":"{readStateIndex:787; appliedIndex:786; }","duration":"121.846056ms","start":"2026-08-24T15:15:48.732896Z","end":"2026-08-24T15:15:48.854742Z","steps":["trace[863195854] 'read index received' (duration: 121.67278ms)","trace[863195854] 'applied index is now lower than readState.Index' (duration: 172.476µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:48.855179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.485088ms","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-08-24T15:15:48.855224Z","caller":"traceutil/trace.go:171","msg":"trace[935372069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:763; }","duration":"104.57281ms","start":"2026-08-24T15:15:48.750638Z","end":"2026-08-24T15:15:48.855211Z","steps":["trace[935372069] 'agreement among raft nodes before linearized reading' (duration: 104.445356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:48.855467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.43387ms","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-08-24T15:15:48.855576Z","caller":"traceutil/trace.go:171","msg":"trace[332827889] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"106.574435ms","start":"2026-08-24T15:15:48.748972Z","end":"2026-08-24T15:15:48.855547Z","steps":["trace[332827889] 'agreement among raft nodes before linearized reading' (duration: 106.232764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:48.855595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.819805ms","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-08-24T15:15:48.855632Z","caller":"traceutil/trace.go:171","msg":"trace[1691110527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:763; }","duration":"116.871967ms","start":"2026-08-24T15:15:48.73875Z","end":"2026-08-24T15:15:48.855622Z","steps":["trace[1691110527] 'agreement among raft nodes before linearized reading' (duration: 116.289467ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:48.856278Z","caller":"traceutil/trace.go:171","msg":"trace[1338845533] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"125.300397ms","start":"2026-08-24T15:15:48.730907Z","end":"2026-08-24T15:15:48.856208Z","steps":["trace[1338845533] 'process raft request' (duration: 123.710336ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:48.856356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.452408ms","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-08-24T15:15:48.856578Z","caller":"traceutil/trace.go:171","msg":"trace[834934692] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:763; }","duration":"123.672435ms","start":"2026-08-24T15:15:48.732862Z","end":"2026-08-24T15:15:48.856535Z","steps":["trace[834934692] 'agreement among raft nodes before linearized reading' (duration: 122.133565ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:52.684609Z","caller":"traceutil/trace.go:171","msg":"trace[456646306] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"301.920671ms","start":"2026-08-24T15:15:52.382663Z","end":"2026-08-24T15:15:52.684583Z","steps":["trace[456646306] 'process raft request' (duration: 301.370624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:52.684973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:52.382639Z","time spent":"302.149249ms","remote":"127.0.0.1:35562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:15:55.06661Z","caller":"traceutil/trace.go:171","msg":"trace[1514858811] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"113.72797ms","start":"2026-08-24T15:15:54.952849Z","end":"2026-08-24T15:15:55.066577Z","steps":["trace[1514858811] 'process raft request' (duration: 40.787223ms)","trace[1514858811] 'compare' (duration: 72.739661ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:55.066749Z","caller":"traceutil/trace.go:171","msg":"trace[999851345] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"113.211294ms","start":"2026-08-24T15:15:54.953515Z","end":"2026-08-24T15:15:55.066726Z","steps":["trace[999851345] 'process raft request' (duration: 113.008888ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:56.635859Z","caller":"traceutil/trace.go:171","msg":"trace[984799385] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"144.815394ms","start":"2026-08-24T15:15:56.491004Z","end":"2026-08-24T15:15:56.63582Z","steps":["trace[984799385] 'process raft request' (duration: 91.610374ms)","trace[984799385] 'compare' (duration: 53.067036ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:56.798316Z","caller":"traceutil/trace.go:171","msg":"trace[2010254297] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"158.040114ms","start":"2026-08-24T15:15:56.64024Z","end":"2026-08-24T15:15:56.79828Z","steps":["trace[2010254297] 'process raft request' (duration: 105.72805ms)","trace[2010254297] 'compare' (duration: 52.105348ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:57.191501Z","caller":"traceutil/trace.go:171","msg":"trace[2119276961] linearizableReadLoop","detail":"{readStateIndex:848; appliedIndex:845; }","duration":"120.703888ms","start":"2026-08-24T15:15:57.070769Z","end":"2026-08-24T15:15:57.191473Z","steps":["trace[2119276961] 'read index received' (duration: 63.85727ms)","trace[2119276961] 'applied index is now lower than readState.Index' (duration: 56.845088ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:57.191803Z","caller":"traceutil/trace.go:171","msg":"trace[1939313895] transaction","detail":"{read_only:false; response_revision:821; number_of_response:1; }","duration":"123.371318ms","start":"2026-08-24T15:15:57.068407Z","end":"2026-08-24T15:15:57.191778Z","steps":["trace[1939313895] 'process raft request' (duration: 120.080389ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:57.19178Z","caller":"traceutil/trace.go:171","msg":"trace[1942145073] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"122.633996ms","start":"2026-08-24T15:15:57.06906Z","end":"2026-08-24T15:15:57.191694Z","steps":["trace[1942145073] 'process raft request' (duration: 122.324597ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:57.192075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.277625ms","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-08-24T15:15:57.192211Z","caller":"traceutil/trace.go:171","msg":"trace[1558818007] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:822; }","duration":"121.376358ms","start":"2026-08-24T15:15:57.070737Z","end":"2026-08-24T15:15:57.192114Z","steps":["trace[1558818007] 'agreement among raft nodes before linearized reading' (duration: 121.055048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:57.192407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.602265ms","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-08-24T15:15:57.192455Z","caller":"traceutil/trace.go:171","msg":"trace[1537175632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:822; }","duration":"121.691987ms","start":"2026-08-24T15:15:57.070751Z","end":"2026-08-24T15:15:57.192443Z","steps":["trace[1537175632] 'agreement among raft nodes before linearized reading' (duration: 121.532152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:57.662287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.196783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:15:57.662426Z","caller":"traceutil/trace.go:171","msg":"trace[560787107] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"465.437788ms","start":"2026-08-24T15:15:57.196968Z","end":"2026-08-24T15:15:57.662406Z","steps":["trace[560787107] 'process raft request' (duration: 336.998358ms)","trace[560787107] 'compare' (duration: 127.968246ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:57.662496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:57.196946Z","time spent":"465.525561ms","remote":"127.0.0.1:35416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":755,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:15:57.904843Z","caller":"traceutil/trace.go:171","msg":"trace[2039887494] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"706.878025ms","start":"2026-08-24T15:15:57.197947Z","end":"2026-08-24T15:15:57.904825Z","steps":["trace[2039887494] 'process raft request' (duration: 706.583846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:57.904965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:57.19793Z","time spent":"706.970578ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:15:57.904952Z","caller":"traceutil/trace.go:171","msg":"trace[902111446] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"707.547555ms","start":"2026-08-24T15:15:57.197381Z","end":"2026-08-24T15:15:57.904929Z","steps":["trace[902111446] 'process raft request' (duration: 620.675904ms)","trace[902111446] 'compare' (duration: 86.363796ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:15:57.905087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:57.197374Z","time spent":"707.631577ms","remote":"127.0.0.1:35518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:15:57.905194Z","caller":"traceutil/trace.go:171","msg":"trace[899995804] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"706.702819ms","start":"2026-08-24T15:15:57.198014Z","end":"2026-08-24T15:15:57.904717Z","steps":["trace[899995804] 'process raft request' (duration: 706.552115ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:57.905277Z","caller":"traceutil/trace.go:171","msg":"trace[1081556557] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"706.841823ms","start":"2026-08-24T15:15:57.198429Z","end":"2026-08-24T15:15:57.905271Z","steps":["trace[1081556557] 'process raft request' (duration: 706.167493ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:57.905308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:57.198422Z","time spent":"706.867323ms","remote":"127.0.0.1:35818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5099,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:15:57.905229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:57.198009Z","time spent":"707.202214ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:15:57.906311Z","caller":"traceutil/trace.go:171","msg":"trace[2115672496] linearizableReadLoop","detail":"{readStateIndex:853; appliedIndex:848; }","duration":"704.961106ms","start":"2026-08-24T15:15:57.20133Z","end":"2026-08-24T15:15:57.906292Z","steps":["trace[2115672496] 'read index received' (duration: 332.811882ms)","trace[2115672496] 'applied index is now lower than readState.Index' (duration: 372.147974ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:57.906411Z","caller":"traceutil/trace.go:171","msg":"trace[1251045190] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"534.929646ms","start":"2026-08-24T15:15:57.371473Z","end":"2026-08-24T15:15:57.906403Z","steps":["trace[1251045190] 'process raft request' (duration: 533.677179ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:57.906504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:57.371443Z","time spent":"534.989208ms","remote":"127.0.0.1:35424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036290,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:15:57.906737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.387649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:5266"} {"level":"info","ts":"2026-08-24T15:15:57.906794Z","caller":"traceutil/trace.go:171","msg":"trace[287500054] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:828; }","duration":"705.490952ms","start":"2026-08-24T15:15:57.201291Z","end":"2026-08-24T15:15:57.906782Z","steps":["trace[287500054] 'agreement among raft nodes before linearized reading' (duration: 705.272905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:57.90687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:57.20128Z","time spent":"705.582384ms","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":5289,"request content":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" "} {"level":"info","ts":"2026-08-24T15:15:57.946801Z","caller":"traceutil/trace.go:171","msg":"trace[793849933] transaction","detail":"{read_only:false; response_revision:829; number_of_response:1; }","duration":"249.164249ms","start":"2026-08-24T15:15:57.69762Z","end":"2026-08-24T15:15:57.946784Z","steps":["trace[793849933] 'process raft request' (duration: 248.919751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:58.258619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.117735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:15:58.258767Z","caller":"traceutil/trace.go:171","msg":"trace[1221377226] linearizableReadLoop","detail":"{readStateIndex:858; appliedIndex:855; }","duration":"312.128036ms","start":"2026-08-24T15:15:57.946629Z","end":"2026-08-24T15:15:58.258757Z","steps":["trace[1221377226] 'read index received' (duration: 198.725692ms)","trace[1221377226] 'applied index is now lower than readState.Index' (duration: 113.401744ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:58.258822Z","caller":"traceutil/trace.go:171","msg":"trace[1002680113] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"347.616872ms","start":"2026-08-24T15:15:57.911198Z","end":"2026-08-24T15:15:58.258815Z","steps":["trace[1002680113] 'process raft request' (duration: 234.2465ms)","trace[1002680113] 'compare' (duration: 112.895348ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:15:58.25902Z","caller":"traceutil/trace.go:171","msg":"trace[1084557588] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"338.096866ms","start":"2026-08-24T15:15:57.92089Z","end":"2026-08-24T15:15:58.258987Z","steps":["trace[1084557588] 'process raft request' (duration: 337.837889ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:58.259142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.075306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-6vjmz\" ","response":"range_response_count:1 size:4495"} {"level":"info","ts":"2026-08-24T15:15:58.259168Z","caller":"traceutil/trace.go:171","msg":"trace[952917754] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-6vjmz; range_end:; response_count:1; response_revision:832; }","duration":"348.127788ms","start":"2026-08-24T15:15:57.911033Z","end":"2026-08-24T15:15:58.25916Z","steps":["trace[952917754] 'agreement among raft nodes before linearized reading' (duration: 348.049335ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:15:58.259153Z","caller":"traceutil/trace.go:171","msg":"trace[1241065602] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"341.53757ms","start":"2026-08-24T15:15:57.917596Z","end":"2026-08-24T15:15:58.259133Z","steps":["trace[1241065602] 'process raft request' (duration: 341.095117ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:58.259185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:57.911017Z","time spent":"348.165419ms","remote":"127.0.0.1:35518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":4518,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-6vjmz\" "} {"level":"warn","ts":"2026-08-24T15:15:58.259199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:57.920875Z","time spent":"338.225371ms","remote":"127.0.0.1:35818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5144,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:15:58.259286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:57.917575Z","time spent":"341.618403ms","remote":"127.0.0.1:35796","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:15:58.259069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.515208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:15:58.259352Z","caller":"traceutil/trace.go:171","msg":"trace[44617387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:832; }","duration":"311.821517ms","start":"2026-08-24T15:15:57.947518Z","end":"2026-08-24T15:15:58.25934Z","steps":["trace[44617387] 'agreement among raft nodes before linearized reading' (duration: 311.485547ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:58.259391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:57.947509Z","time spent":"311.874068ms","remote":"127.0.0.1:35312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:15:58.259632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.058062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050244"} {"level":"info","ts":"2026-08-24T15:15:58.259651Z","caller":"traceutil/trace.go:171","msg":"trace[387780239] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:832; }","duration":"182.122475ms","start":"2026-08-24T15:15:58.077522Z","end":"2026-08-24T15:15:58.259645Z","steps":["trace[387780239] 'agreement among raft nodes before linearized reading' (duration: 181.847577ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:15:58.258854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:15:57.911189Z","time spent":"347.652463ms","remote":"127.0.0.1:35416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:16:01.528732Z","caller":"traceutil/trace.go:171","msg":"trace[1297804673] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"341.566299ms","start":"2026-08-24T15:16:01.18712Z","end":"2026-08-24T15:16:01.528686Z","steps":["trace[1297804673] 'process raft request' (duration: 340.90753ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:16:01.530228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:16:01.187078Z","time spent":"343.002192ms","remote":"127.0.0.1:35518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3856,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:16:01.838177Z","caller":"traceutil/trace.go:171","msg":"trace[740753902] transaction","detail":"{read_only:false; response_revision:858; number_of_response:1; }","duration":"650.403432ms","start":"2026-08-24T15:16:01.18776Z","end":"2026-08-24T15:16:01.838163Z","steps":["trace[740753902] 'process raft request' (duration: 601.586785ms)","trace[740753902] 'compare' (duration: 48.665103ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:16:01.838298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:16:01.187743Z","time spent":"650.485124ms","remote":"127.0.0.1:35518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3856,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:16:01.838444Z","caller":"traceutil/trace.go:171","msg":"trace[1858062533] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"506.928801ms","start":"2026-08-24T15:16:01.331494Z","end":"2026-08-24T15:16:01.838423Z","steps":["trace[1858062533] 'process raft request' (duration: 506.635792ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:01.838524Z","caller":"traceutil/trace.go:171","msg":"trace[214983787] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"649.546577ms","start":"2026-08-24T15:16:01.188971Z","end":"2026-08-24T15:16:01.838518Z","steps":["trace[214983787] 'process raft request' (duration: 649.125285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:16:01.838555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:16:01.188952Z","time spent":"649.587478ms","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:16:01.838583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:16:01.331467Z","time spent":"507.043494ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:16:01.838659Z","caller":"traceutil/trace.go:171","msg":"trace[617283270] linearizableReadLoop","detail":"{readStateIndex:888; appliedIndex:884; }","duration":"301.642453ms","start":"2026-08-24T15:16:01.53701Z","end":"2026-08-24T15:16:01.838653Z","steps":["trace[617283270] 'read index received' (duration: 252.347853ms)","trace[617283270] 'applied index is now lower than readState.Index' (duration: 49.29416ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:16:01.838747Z","caller":"traceutil/trace.go:171","msg":"trace[1151556363] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"302.493198ms","start":"2026-08-24T15:16:01.536249Z","end":"2026-08-24T15:16:01.838742Z","steps":["trace[1151556363] 'process raft request' (duration: 302.104827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:16:01.838779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:16:01.536228Z","time spent":"302.536569ms","remote":"127.0.0.1:35416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":719,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:16:01.838898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.8878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-67659f764b-2hfdb\" ","response":"range_response_count:1 size:3871"} {"level":"info","ts":"2026-08-24T15:16:01.838925Z","caller":"traceutil/trace.go:171","msg":"trace[1291840865] range","detail":"{range_begin:/registry/pods/kube-system/coredns-67659f764b-2hfdb; range_end:; response_count:1; response_revision:861; }","duration":"301.962192ms","start":"2026-08-24T15:16:01.536958Z","end":"2026-08-24T15:16:01.83892Z","steps":["trace[1291840865] 'agreement among raft nodes before linearized reading' (duration: 301.88311ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:16:01.838939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:16:01.536951Z","time spent":"301.984774ms","remote":"127.0.0.1:35518","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":3894,"request content":"key:\"/registry/pods/kube-system/coredns-67659f764b-2hfdb\" "} {"level":"warn","ts":"2026-08-24T15:16:02.349242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.274223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:16:02.34934Z","caller":"traceutil/trace.go:171","msg":"trace[1674911542] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"503.82685ms","start":"2026-08-24T15:16:01.845495Z","end":"2026-08-24T15:16:02.349322Z","steps":["trace[1674911542] 'process raft request' (duration: 265.367302ms)","trace[1674911542] 'compare' (duration: 237.929933ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:16:02.349438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:16:01.845488Z","time spent":"503.926733ms","remote":"127.0.0.1:35416","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":719,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:16:02.529699Z","caller":"traceutil/trace.go:171","msg":"trace[277148485] linearizableReadLoop","detail":"{readStateIndex:890; appliedIndex:888; }","duration":"669.362241ms","start":"2026-08-24T15:16:01.860313Z","end":"2026-08-24T15:16:02.529675Z","steps":["trace[277148485] 'read index received' (duration: 250.557129ms)","trace[277148485] 'applied index is now lower than readState.Index' (duration: 418.804142ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:16:02.529821Z","caller":"traceutil/trace.go:171","msg":"trace[1612114839] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"680.567248ms","start":"2026-08-24T15:16:01.849245Z","end":"2026-08-24T15:16:02.529812Z","steps":["trace[1612114839] 'process raft request' (duration: 672.172965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:16:02.52993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:16:01.849239Z","time spent":"680.612339ms","remote":"127.0.0.1:35518","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4762,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:16:02.529984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.538733ms","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-08-24T15:16:02.53Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.918734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-08-24T15:16:02.530033Z","caller":"traceutil/trace.go:171","msg":"trace[10449300] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:863; }","duration":"429.993837ms","start":"2026-08-24T15:16:02.100032Z","end":"2026-08-24T15:16:02.530026Z","steps":["trace[10449300] 'agreement among raft nodes before linearized reading' (duration: 429.919005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:16:02.530049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:16:02.100005Z","time spent":"430.040278ms","remote":"127.0.0.1:35534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":202,"request content":"key:\"/registry/serviceaccounts/kube-system/coredns\" "} {"level":"info","ts":"2026-08-24T15:16:02.530033Z","caller":"traceutil/trace.go:171","msg":"trace[1142368587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:863; }","duration":"609.598975ms","start":"2026-08-24T15:16:01.920421Z","end":"2026-08-24T15:16:02.53002Z","steps":["trace[1142368587] 'agreement among raft nodes before linearized reading' (duration: 609.465181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:16:02.53008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:16:01.920385Z","time spent":"609.687628ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:16:02.53074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.378249ms","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-08-24T15:16:02.530854Z","caller":"traceutil/trace.go:171","msg":"trace[375070776] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:863; }","duration":"595.554783ms","start":"2026-08-24T15:16:01.935278Z","end":"2026-08-24T15:16:02.530833Z","steps":["trace[375070776] 'agreement among raft nodes before linearized reading' (duration: 595.248365ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:16:02.530902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:16:01.935255Z","time spent":"595.636537ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-24T15:16:02.531431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.111781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:16:02.531467Z","caller":"traceutil/trace.go:171","msg":"trace[1902407099] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:863; }","duration":"671.156072ms","start":"2026-08-24T15:16:01.860302Z","end":"2026-08-24T15:16:02.531458Z","steps":["trace[1902407099] 'agreement among raft nodes before linearized reading' (duration: 671.016978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:16:02.531488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:16:01.860297Z","time spent":"671.186093ms","remote":"127.0.0.1:35328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:16:02.762811Z","caller":"traceutil/trace.go:171","msg":"trace[1886614073] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"227.892072ms","start":"2026-08-24T15:16:02.534891Z","end":"2026-08-24T15:16:02.762783Z","steps":["trace[1886614073] 'process raft request' (duration: 227.48133ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:02.763464Z","caller":"traceutil/trace.go:171","msg":"trace[295712417] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"223.513966ms","start":"2026-08-24T15:16:02.539938Z","end":"2026-08-24T15:16:02.763452Z","steps":["trace[295712417] 'process raft request' (duration: 222.718503ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:03.102797Z","caller":"traceutil/trace.go:171","msg":"trace[374455982] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"103.122442ms","start":"2026-08-24T15:16:02.999642Z","end":"2026-08-24T15:16:03.102764Z","steps":["trace[374455982] 'process raft request' (duration: 102.643959ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:03.291103Z","caller":"traceutil/trace.go:171","msg":"trace[283602245] linearizableReadLoop","detail":"{readStateIndex:895; appliedIndex:894; }","duration":"176.32544ms","start":"2026-08-24T15:16:03.114752Z","end":"2026-08-24T15:16:03.291078Z","steps":["trace[283602245] 'read index received' (duration: 151.730092ms)","trace[283602245] 'applied index is now lower than readState.Index' (duration: 24.594268ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:16:03.291225Z","caller":"traceutil/trace.go:171","msg":"trace[595970912] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"181.760398ms","start":"2026-08-24T15:16:03.109421Z","end":"2026-08-24T15:16:03.291182Z","steps":["trace[595970912] 'process raft request' (duration: 157.19348ms)","trace[595970912] 'compare' (duration: 24.145275ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:16:03.291353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.581238ms","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-08-24T15:16:03.291383Z","caller":"traceutil/trace.go:171","msg":"trace[197240001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:868; }","duration":"176.65737ms","start":"2026-08-24T15:16:03.114718Z","end":"2026-08-24T15:16:03.291375Z","steps":["trace[197240001] 'agreement among raft nodes before linearized reading' (duration: 176.512266ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:16:03.291557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.506181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:16:03.291602Z","caller":"traceutil/trace.go:171","msg":"trace[1255604577] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:868; }","duration":"130.613375ms","start":"2026-08-24T15:16:03.160976Z","end":"2026-08-24T15:16:03.29159Z","steps":["trace[1255604577] 'agreement among raft nodes before linearized reading' (duration: 130.477911ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:03.411928Z","caller":"traceutil/trace.go:171","msg":"trace[735122576] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"111.523794ms","start":"2026-08-24T15:16:03.300379Z","end":"2026-08-24T15:16:03.411903Z","steps":["trace[735122576] 'process raft request' (duration: 96.631574ms)","trace[735122576] 'compare' (duration: 14.773876ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:16:03.565833Z","caller":"traceutil/trace.go:171","msg":"trace[106396038] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"100.892707ms","start":"2026-08-24T15:16:03.464908Z","end":"2026-08-24T15:16:03.5658Z","steps":["trace[106396038] 'process raft request' (duration: 76.719101ms)","trace[106396038] 'compare' (duration: 24.053343ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:16:04.35251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.695888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:16:04.352615Z","caller":"traceutil/trace.go:171","msg":"trace[1524730305] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:873; }","duration":"160.866633ms","start":"2026-08-24T15:16:04.191728Z","end":"2026-08-24T15:16:04.352595Z","steps":["trace[1524730305] 'range keys from in-memory index tree' (duration: 160.570495ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:04.5359Z","caller":"traceutil/trace.go:171","msg":"trace[1677542864] transaction","detail":"{read_only:false; response_revision:874; number_of_response:1; }","duration":"180.794805ms","start":"2026-08-24T15:16:04.355075Z","end":"2026-08-24T15:16:04.53587Z","steps":["trace[1677542864] 'process raft request' (duration: 180.632901ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:16:04.799178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.874895ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:16:04.799272Z","caller":"traceutil/trace.go:171","msg":"trace[1544112732] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:902; }","duration":"166.355182ms","start":"2026-08-24T15:16:04.632902Z","end":"2026-08-24T15:16:04.799257Z","steps":["trace[1544112732] 'read index received' (duration: 64.001352ms)","trace[1544112732] 'applied index is now lower than readState.Index' (duration: 102.35274ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:16:04.799349Z","caller":"traceutil/trace.go:171","msg":"trace[404821712] transaction","detail":"{read_only:false; response_revision:875; number_of_response:1; }","duration":"253.132945ms","start":"2026-08-24T15:16:04.546205Z","end":"2026-08-24T15:16:04.799338Z","steps":["trace[404821712] 'process raft request' (duration: 150.016593ms)","trace[404821712] 'compare' (duration: 102.764082ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:16:04.799656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.764363ms","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-08-24T15:16:04.799685Z","caller":"traceutil/trace.go:171","msg":"trace[1439319297] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:875; }","duration":"166.833995ms","start":"2026-08-24T15:16:04.632842Z","end":"2026-08-24T15:16:04.799676Z","steps":["trace[1439319297] 'agreement among raft nodes before linearized reading' (duration: 166.688721ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:04.92279Z","caller":"traceutil/trace.go:171","msg":"trace[1832264544] transaction","detail":"{read_only:false; response_revision:876; number_of_response:1; }","duration":"113.092866ms","start":"2026-08-24T15:16:04.809673Z","end":"2026-08-24T15:16:04.922766Z","steps":["trace[1832264544] 'process raft request' (duration: 89.188782ms)","trace[1832264544] 'compare' (duration: 23.74045ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:16:04.92302Z","caller":"traceutil/trace.go:171","msg":"trace[2055069905] linearizableReadLoop","detail":"{readStateIndex:905; appliedIndex:903; }","duration":"109.612767ms","start":"2026-08-24T15:16:04.813397Z","end":"2026-08-24T15:16:04.92301Z","steps":["trace[2055069905] 'read index received' (duration: 85.357853ms)","trace[2055069905] 'applied index is now lower than readState.Index' (duration: 24.253934ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:16:04.923129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.70852ms","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-08-24T15:16:04.923181Z","caller":"traceutil/trace.go:171","msg":"trace[2128508635] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:877; }","duration":"109.800813ms","start":"2026-08-24T15:16:04.813371Z","end":"2026-08-24T15:16:04.923172Z","steps":["trace[2128508635] 'agreement among raft nodes before linearized reading' (duration: 109.68035ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:04.923458Z","caller":"traceutil/trace.go:171","msg":"trace[651047287] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"110.025819ms","start":"2026-08-24T15:16:04.813353Z","end":"2026-08-24T15:16:04.923379Z","steps":["trace[651047287] 'process raft request' (duration: 109.37454ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:23.191704Z","caller":"traceutil/trace.go:171","msg":"trace[1209271361] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"122.900488ms","start":"2026-08-24T15:16:23.068775Z","end":"2026-08-24T15:16:23.191675Z","steps":["trace[1209271361] 'process raft request' (duration: 109.170908ms)","trace[1209271361] 'compare' (duration: 13.619367ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:16:55.978028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.075464ms","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-08-24T15:16:55.978114Z","caller":"traceutil/trace.go:171","msg":"trace[1127246983] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1119; }","duration":"229.222058ms","start":"2026-08-24T15:16:55.748872Z","end":"2026-08-24T15:16:55.978094Z","steps":["trace[1127246983] 'range keys from in-memory index tree' (duration: 228.88809ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:56.767549Z","caller":"traceutil/trace.go:171","msg":"trace[1395723437] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1158; }","duration":"280.963363ms","start":"2026-08-24T15:16:56.486567Z","end":"2026-08-24T15:16:56.76753Z","steps":["trace[1395723437] 'read index received' (duration: 280.937432ms)","trace[1395723437] 'applied index is now lower than readState.Index' (duration: 25.361µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:16:56.767628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.045464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:16:56.767644Z","caller":"traceutil/trace.go:171","msg":"trace[312720828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1121; }","duration":"281.110746ms","start":"2026-08-24T15:16:56.486529Z","end":"2026-08-24T15:16:56.767639Z","steps":["trace[312720828] 'agreement among raft nodes before linearized reading' (duration: 281.067535ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:16:56.76767Z","caller":"traceutil/trace.go:171","msg":"trace[1501159787] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"669.073628ms","start":"2026-08-24T15:16:56.09857Z","end":"2026-08-24T15:16:56.767644Z","steps":["trace[1501159787] 'process raft request' (duration: 668.866184ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:16:56.767806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:16:56.098547Z","time spent":"669.189951ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:16:56.772506Z","caller":"traceutil/trace.go:171","msg":"trace[1349794871] transaction","detail":"{read_only:false; response_revision:1122; number_of_response:1; }","duration":"260.162711ms","start":"2026-08-24T15:16:56.512315Z","end":"2026-08-24T15:16:56.772478Z","steps":["trace[1349794871] 'process raft request' (duration: 260.019118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:02.514043Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033913445637822145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:03.01451Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033913445637822145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:03.515504Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033913445637822145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:04.015825Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033913445637822145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:04.516514Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033913445637822145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:04.863928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000637135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-24T15:17:04.863986Z","caller":"traceutil/trace.go:171","msg":"trace[646317817] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000736668s","start":"2026-08-24T15:17:02.863238Z","end":"2026-08-24T15:17:04.863975Z","steps":["trace[646317817] 'agreement among raft nodes before linearized reading' (duration: 2.000670366s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:04.864013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:02.863221Z","time spent":"2.000786338s","remote":"127.0.0.1:35328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/24 15:17:04 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-24T15:17:05.017366Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033913445637822145,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:17:05.262013Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.56674422s","expected-duration":"1s"} {"level":"info","ts":"2026-08-24T15:17:05.26257Z","caller":"traceutil/trace.go:171","msg":"trace[808910768] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"3.567363893s","start":"2026-08-24T15:17:01.695169Z","end":"2026-08-24T15:17:05.262533Z","steps":["trace[808910768] 'process raft request' (duration: 3.567064977s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:05.262814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:01.695151Z","time spent":"3.567536487s","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:17:05.424876Z","caller":"traceutil/trace.go:171","msg":"trace[2068576478] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"3.727082803s","start":"2026-08-24T15:17:01.697745Z","end":"2026-08-24T15:17:05.424828Z","steps":["trace[2068576478] 'process raft request' (duration: 3.679337829s)","trace[2068576478] 'compare' (duration: 47.413278ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:05.425023Z","caller":"traceutil/trace.go:171","msg":"trace[1290424433] transaction","detail":"{read_only:false; response_revision:1140; number_of_response:1; }","duration":"2.623889244s","start":"2026-08-24T15:17:02.801124Z","end":"2026-08-24T15:17:05.425013Z","steps":["trace[1290424433] 'process raft request' (duration: 2.623799242s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:05.425278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:02.8011Z","time spent":"2.624149629s","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:17:05.425274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:01.697732Z","time spent":"3.727460791s","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:17:05.424985Z","caller":"traceutil/trace.go:171","msg":"trace[1295049646] linearizableReadLoop","detail":"{readStateIndex:1179; appliedIndex:1176; }","duration":"3.411921446s","start":"2026-08-24T15:17:02.013053Z","end":"2026-08-24T15:17:05.424975Z","steps":["trace[1295049646] 'read index received' (duration: 3.249205982s)","trace[1295049646] 'applied index is now lower than readState.Index' (duration: 162.714984ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:17:05.425379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.412152622s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-08-24T15:17:05.425465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.505767592s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:17:05.425498Z","caller":"traceutil/trace.go:171","msg":"trace[443278874] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1140; }","duration":"1.505835884s","start":"2026-08-24T15:17:03.919646Z","end":"2026-08-24T15:17:05.425482Z","steps":["trace[443278874] 'agreement among raft nodes before linearized reading' (duration: 1.505787073s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:05.42555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"924.104763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.100\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-24T15:17:05.425611Z","caller":"traceutil/trace.go:171","msg":"trace[1147242182] range","detail":"{range_begin:/registry/masterleases/199.19.213.100; range_end:; response_count:1; response_revision:1140; }","duration":"924.212204ms","start":"2026-08-24T15:17:04.501384Z","end":"2026-08-24T15:17:05.425596Z","steps":["trace[1147242182] 'agreement among raft nodes before linearized reading' (duration: 924.040191ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:05.425645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:04.501365Z","time spent":"924.273956ms","remote":"127.0.0.1:35378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.100\" "} {"level":"warn","ts":"2026-08-24T15:17:05.42565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.76378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:17:05.425647Z","caller":"traceutil/trace.go:171","msg":"trace[530014766] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1140; }","duration":"3.412614591s","start":"2026-08-24T15:17:02.013022Z","end":"2026-08-24T15:17:05.425636Z","steps":["trace[530014766] 'agreement among raft nodes before linearized reading' (duration: 3.412049299s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:05.425713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:02.013006Z","time spent":"3.412697584s","remote":"127.0.0.1:35592","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-08-24T15:17:05.425745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.149524387s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-08-24T15:17:05.42557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:03.919627Z","time spent":"1.505933646s","remote":"127.0.0.1:34044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-08-24T15:17:05.425799Z","caller":"traceutil/trace.go:171","msg":"trace[842873039] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1140; }","duration":"2.149624299s","start":"2026-08-24T15:17:03.27616Z","end":"2026-08-24T15:17:05.425784Z","steps":["trace[842873039] 'agreement among raft nodes before linearized reading' (duration: 2.1492494s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:05.425834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:03.276145Z","time spent":"2.14968396s","remote":"127.0.0.1:35592","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-08-24T15:17:05.425711Z","caller":"traceutil/trace.go:171","msg":"trace[1358056352] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1140; }","duration":"561.849973ms","start":"2026-08-24T15:17:04.863846Z","end":"2026-08-24T15:17:05.425696Z","steps":["trace[1358056352] 'agreement among raft nodes before linearized reading' (duration: 561.762791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:05.425929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:04.863834Z","time spent":"562.088068ms","remote":"127.0.0.1:35312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-24T15:17:05.425647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.058447196s","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-08-24T15:17:05.426007Z","caller":"traceutil/trace.go:171","msg":"trace[564372128] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1140; }","duration":"2.058898626s","start":"2026-08-24T15:17:03.367099Z","end":"2026-08-24T15:17:05.425998Z","steps":["trace[564372128] 'agreement among raft nodes before linearized reading' (duration: 2.058356963s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:05.426038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:03.367085Z","time spent":"2.058944207s","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-24T15:17:05.619178Z","caller":"traceutil/trace.go:171","msg":"trace[223218343] linearizableReadLoop","detail":"{readStateIndex:1182; appliedIndex:1179; }","duration":"188.281372ms","start":"2026-08-24T15:17:05.430874Z","end":"2026-08-24T15:17:05.619155Z","steps":["trace[223218343] 'read index received' (duration: 44.7094ms)","trace[223218343] 'applied index is now lower than readState.Index' (duration: 143.570962ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:05.619214Z","caller":"traceutil/trace.go:171","msg":"trace[1618936758] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"188.448046ms","start":"2026-08-24T15:17:05.430736Z","end":"2026-08-24T15:17:05.619184Z","steps":["trace[1618936758] 'process raft request' (duration: 188.349584ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:05.619348Z","caller":"traceutil/trace.go:171","msg":"trace[1418977318] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"187.71156ms","start":"2026-08-24T15:17:05.431624Z","end":"2026-08-24T15:17:05.619335Z","steps":["trace[1418977318] 'process raft request' (duration: 187.497006ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:05.619401Z","caller":"traceutil/trace.go:171","msg":"trace[757831480] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"188.662631ms","start":"2026-08-24T15:17:05.430721Z","end":"2026-08-24T15:17:05.619384Z","steps":["trace[757831480] 'process raft request' (duration: 175.750529ms)","trace[757831480] 'compare' (duration: 12.438241ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:17:05.619608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.730682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18cec6a5a437b6b4\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-08-24T15:17:05.619699Z","caller":"traceutil/trace.go:171","msg":"trace[1412370988] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18cec6a5a437b6b4; range_end:; response_count:1; response_revision:1143; }","duration":"188.850644ms","start":"2026-08-24T15:17:05.43083Z","end":"2026-08-24T15:17:05.619681Z","steps":["trace[1412370988] 'agreement among raft nodes before linearized reading' (duration: 188.717021ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:05.661582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.272265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:17:05.661665Z","caller":"traceutil/trace.go:171","msg":"trace[1427269239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1144; }","duration":"228.420318ms","start":"2026-08-24T15:17:05.433226Z","end":"2026-08-24T15:17:05.661646Z","steps":["trace[1427269239] 'agreement among raft nodes before linearized reading' (duration: 228.281395ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:05.661725Z","caller":"traceutil/trace.go:171","msg":"trace[1234559274] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"185.054484ms","start":"2026-08-24T15:17:05.476623Z","end":"2026-08-24T15:17:05.661677Z","steps":["trace[1234559274] 'process raft request' (duration: 184.731408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:07.704231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.799134ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:17:07.704444Z","caller":"traceutil/trace.go:171","msg":"trace[793251723] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"273.487865ms","start":"2026-08-24T15:17:07.430938Z","end":"2026-08-24T15:17:07.704426Z","steps":["trace[793251723] 'process raft request' (duration: 41.426506ms)","trace[793251723] 'compare' (duration: 231.495248ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:17:07.704584Z","caller":"traceutil/trace.go:171","msg":"trace[1734443822] transaction","detail":"{read_only:false; response_revision:1149; number_of_response:1; }","duration":"270.565724ms","start":"2026-08-24T15:17:07.434008Z","end":"2026-08-24T15:17:07.704574Z","steps":["trace[1734443822] 'process raft request' (duration: 270.314239ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:09.640686Z","caller":"traceutil/trace.go:171","msg":"trace[1623454270] transaction","detail":"{read_only:false; response_revision:1154; number_of_response:1; }","duration":"112.131598ms","start":"2026-08-24T15:17:09.528528Z","end":"2026-08-24T15:17:09.64066Z","steps":["trace[1623454270] 'process raft request' (duration: 111.969925ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:17:50.274667Z","caller":"traceutil/trace.go:171","msg":"trace[163421157] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"108.07066ms","start":"2026-08-24T15:17:50.166557Z","end":"2026-08-24T15:17:50.274628Z","steps":["trace[163421157] 'process raft request' (duration: 107.879572ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:51.194376Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3033913445637822664,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T15:17:51.223427Z","caller":"traceutil/trace.go:171","msg":"trace[384155118] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"567.724901ms","start":"2026-08-24T15:17:50.655676Z","end":"2026-08-24T15:17:51.223401Z","steps":["trace[384155118] 'process raft request' (duration: 567.567304ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:51.223716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:50.655655Z","time spent":"567.847617ms","remote":"127.0.0.1:35592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:17:51.224524Z","caller":"traceutil/trace.go:171","msg":"trace[1949962666] linearizableReadLoop","detail":"{readStateIndex:1382; appliedIndex:1382; }","duration":"538.281693ms","start":"2026-08-24T15:17:50.686209Z","end":"2026-08-24T15:17:51.224491Z","steps":["trace[1949962666] 'read index received' (duration: 538.255572ms)","trace[1949962666] 'applied index is now lower than readState.Index' (duration: 24.861µs)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:17:51.224743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.476311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:17:51.224806Z","caller":"traceutil/trace.go:171","msg":"trace[72642794] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1332; }","duration":"538.608557ms","start":"2026-08-24T15:17:50.68617Z","end":"2026-08-24T15:17:51.224778Z","steps":["trace[72642794] 'agreement among raft nodes before linearized reading' (duration: 538.482481ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:51.224889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:50.686148Z","time spent":"538.727632ms","remote":"127.0.0.1:48156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:17:51.225069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.187615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:17:51.225138Z","caller":"traceutil/trace.go:171","msg":"trace[1547126492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1332; }","duration":"361.29372ms","start":"2026-08-24T15:17:50.863828Z","end":"2026-08-24T15:17:51.225121Z","steps":["trace[1547126492] 'agreement among raft nodes before linearized reading' (duration: 361.188065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:17:51.225192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:17:50.863812Z","time spent":"361.374313ms","remote":"127.0.0.1:35328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:18:08.063474Z","caller":"traceutil/trace.go:171","msg":"trace[725054200] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"119.135225ms","start":"2026-08-24T15:18:07.944312Z","end":"2026-08-24T15:18:08.063447Z","steps":["trace[725054200] 'process raft request' (duration: 118.997159ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:08.107368Z","caller":"traceutil/trace.go:171","msg":"trace[1262402164] linearizableReadLoop","detail":"{readStateIndex:1444; appliedIndex:1442; }","duration":"130.371822ms","start":"2026-08-24T15:18:07.976968Z","end":"2026-08-24T15:18:08.10734Z","steps":["trace[1262402164] 'read index received' (duration: 86.444633ms)","trace[1262402164] 'applied index is now lower than readState.Index' (duration: 43.926369ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:18:08.107513Z","caller":"traceutil/trace.go:171","msg":"trace[1501640937] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"146.832102ms","start":"2026-08-24T15:18:07.96067Z","end":"2026-08-24T15:18:08.107502Z","steps":["trace[1501640937] 'process raft request' (duration: 146.472618ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:08.10761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.624442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:767"} {"level":"info","ts":"2026-08-24T15:18:08.107672Z","caller":"traceutil/trace.go:171","msg":"trace[1917984850] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1391; }","duration":"130.723936ms","start":"2026-08-24T15:18:07.976933Z","end":"2026-08-24T15:18:08.107657Z","steps":["trace[1917984850] 'agreement among raft nodes before linearized reading' (duration: 130.622031ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:18:08.107824Z","caller":"traceutil/trace.go:171","msg":"trace[512282125] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"123.416924ms","start":"2026-08-24T15:18:07.984398Z","end":"2026-08-24T15:18:08.107815Z","steps":["trace[512282125] 'process raft request' (duration: 122.900703ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:18:08.108066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.276328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:basic-user\" ","response":"range_response_count:1 size:697"} {"level":"info","ts":"2026-08-24T15:18:08.108158Z","caller":"traceutil/trace.go:171","msg":"trace[1670510004] range","detail":"{range_begin:/registry/clusterrolebindings/system:basic-user; range_end:; response_count:1; response_revision:1391; }","duration":"123.391753ms","start":"2026-08-24T15:18:07.984741Z","end":"2026-08-24T15:18:08.108133Z","steps":["trace[1670510004] 'agreement among raft nodes before linearized reading' (duration: 123.244327ms)"],"step_count":1}