{"level":"warn","ts":"2026-08-04T15:22:00.925842Z","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-04T15:22:00.925922Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129: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-04T15:22:00.925983Z","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-04T15:22:00.925995Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-08-04T15:22:00.926012Z","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-04T15:22:00.926855Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-08-04T15:22:00.926931Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129: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-04T15:22:00.937292Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.157455ms"} {"level":"info","ts":"2026-08-04T15:22:00.950196Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-08-04T15:22:00.950309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-08-04T15:22:00.950337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-08-04T15:22:00.950364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-04T15:22:00.950387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-08-04T15:22:00.950432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-08-04T15:22:00.96171Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-04T15:22:00.963222Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-04T15:22:00.964885Z","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-04T15:22:00.969332Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-04T15:22:00.969518Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-04T15:22:00.969837Z","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-04T15:22:00.969959Z","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-04T15:22:00.970087Z","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-04T15:22:00.972866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-08-04T15:22:00.973006Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-08-04T15:22:00.976056Z","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-04T15:22:00.976192Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-08-04T15:22:00.976279Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-08-04T15:22:00.976458Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-04T15:22:00.976535Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-04T15:22:01.550937Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-08-04T15:22:01.55101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-08-04T15:22:01.551053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-08-04T15:22:01.551112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-08-04T15:22:01.551132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-08-04T15:22:01.551148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-08-04T15:22:01.551196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-08-04T15:22:01.55222Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:22:01.553003Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-08-04T15:22:01.553014Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:22:01.553049Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-04T15:22:01.553484Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:22:01.5536Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:22:01.553641Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-04T15:22:01.553814Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-04T15:22:01.553855Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-04T15:22:01.556561Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-08-04T15:22:01.556664Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-04T15:22:26.96176Z","caller":"traceutil/trace.go:171","msg":"trace[1208854182] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"408.984161ms","start":"2026-08-04T15:22:26.552751Z","end":"2026-08-04T15:22:26.961735Z","steps":["trace[1208854182] 'process raft request' (duration: 408.820067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:26.962463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:26.552731Z","time spent":"409.137203ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:22:29.486403Z","caller":"traceutil/trace.go:171","msg":"trace[381127394] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"325.769004ms","start":"2026-08-04T15:22:29.160605Z","end":"2026-08-04T15:22:29.486374Z","steps":["trace[381127394] 'process raft request' (duration: 325.59312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:29.486574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:29.160588Z","time spent":"325.900136ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:22:29.500845Z","caller":"traceutil/trace.go:171","msg":"trace[1490336386] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"338.843574ms","start":"2026-08-04T15:22:29.161973Z","end":"2026-08-04T15:22:29.500816Z","steps":["trace[1490336386] 'process raft request' (duration: 338.683151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:29.501031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:29.161948Z","time spent":"338.993578ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:22:29.501073Z","caller":"traceutil/trace.go:171","msg":"trace[906064962] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"338.888435ms","start":"2026-08-04T15:22:29.162143Z","end":"2026-08-04T15:22:29.501032Z","steps":["trace[906064962] 'process raft request' (duration: 338.636469ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:29.501238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:29.162134Z","time spent":"339.015847ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:22:37.693492Z","caller":"traceutil/trace.go:171","msg":"trace[954600353] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"150.369267ms","start":"2026-08-04T15:22:37.543097Z","end":"2026-08-04T15:22:37.693467Z","steps":["trace[954600353] 'process raft request' (duration: 150.218344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:41.957298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.942587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:22:41.958082Z","caller":"traceutil/trace.go:171","msg":"trace[1243173413] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"795.50454ms","start":"2026-08-04T15:22:41.162559Z","end":"2026-08-04T15:22:41.958064Z","steps":["trace[1243173413] 'process raft request' (duration: 795.448558ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:22:41.958077Z","caller":"traceutil/trace.go:171","msg":"trace[163051436] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"816.013023ms","start":"2026-08-04T15:22:41.14203Z","end":"2026-08-04T15:22:41.958043Z","steps":["trace[163051436] 'process raft request' (duration: 815.470152ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:22:41.958167Z","caller":"traceutil/trace.go:171","msg":"trace[880152794] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"821.59746ms","start":"2026-08-04T15:22:41.136528Z","end":"2026-08-04T15:22:41.958125Z","steps":["trace[880152794] 'process raft request' (duration: 35.609952ms)","trace[880152794] 'compare' (duration: 784.756394ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:22:41.958231Z","caller":"traceutil/trace.go:171","msg":"trace[782362136] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"816.191946ms","start":"2026-08-04T15:22:41.142029Z","end":"2026-08-04T15:22:41.958221Z","steps":["trace[782362136] 'process raft request' (duration: 815.797808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:41.958297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:41.142007Z","time spent":"816.253507ms","remote":"127.0.0.1:34876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:22:41.958311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:41.136519Z","time spent":"821.710903ms","remote":"127.0.0.1:34876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-04T15:22:41.958396Z","caller":"traceutil/trace.go:171","msg":"trace[784700173] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"803.739037ms","start":"2026-08-04T15:22:41.154647Z","end":"2026-08-04T15:22:41.958386Z","steps":["trace[784700173] 'process raft request' (duration: 803.299519ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:41.958232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:41.142007Z","time spent":"816.139545ms","remote":"127.0.0.1:34876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:22:41.958355Z","caller":"traceutil/trace.go:171","msg":"trace[2142933177] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:569; }","duration":"818.748345ms","start":"2026-08-04T15:22:41.139511Z","end":"2026-08-04T15:22:41.958259Z","steps":["trace[2142933177] 'read index received' (duration: 966.468µs)","trace[2142933177] 'applied index is now lower than readState.Index' (duration: 817.776137ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:22:41.958454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:41.154638Z","time spent":"803.788038ms","remote":"127.0.0.1:34876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:22:41.958483Z","caller":"traceutil/trace.go:171","msg":"trace[645408209] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"808.55211ms","start":"2026-08-04T15:22:41.149922Z","end":"2026-08-04T15:22:41.958474Z","steps":["trace[645408209] 'process raft request' (duration: 807.966899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:41.958168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:41.162533Z","time spent":"795.601412ms","remote":"127.0.0.1:34876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-04T15:22:41.958569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:41.149902Z","time spent":"808.636281ms","remote":"127.0.0.1:34876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:22:41.958625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"819.076421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:1013"} {"level":"info","ts":"2026-08-04T15:22:41.958681Z","caller":"traceutil/trace.go:171","msg":"trace[503489046] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:551; }","duration":"819.194514ms","start":"2026-08-04T15:22:41.139469Z","end":"2026-08-04T15:22:41.958663Z","steps":["trace[503489046] 'agreement among raft nodes before linearized reading' (duration: 819.024ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:41.958727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:41.139449Z","time spent":"819.267156ms","remote":"127.0.0.1:35322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1035,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1alpha2.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-08-04T15:22:41.961809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.763914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:22:41.961928Z","caller":"traceutil/trace.go:171","msg":"trace[1092907661] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:552; }","duration":"188.906386ms","start":"2026-08-04T15:22:41.772979Z","end":"2026-08-04T15:22:41.961885Z","steps":["trace[1092907661] 'agreement among raft nodes before linearized reading' (duration: 188.729503ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:22:41.96249Z","caller":"traceutil/trace.go:171","msg":"trace[310501785] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"678.77451ms","start":"2026-08-04T15:22:41.283677Z","end":"2026-08-04T15:22:41.962452Z","steps":["trace[310501785] 'process raft request' (duration: 676.74639ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:41.962654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:41.283653Z","time spent":"678.869761ms","remote":"127.0.0.1:34876","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-04T15:22:41.962856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.699891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:22:41.962922Z","caller":"traceutil/trace.go:171","msg":"trace[713922752] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:552; }","duration":"189.767772ms","start":"2026-08-04T15:22:41.77311Z","end":"2026-08-04T15:22:41.962878Z","steps":["trace[713922752] 'agreement among raft nodes before linearized reading' (duration: 189.717352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:41.963138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.031607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:22:41.963167Z","caller":"traceutil/trace.go:171","msg":"trace[1056456835] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:552; }","duration":"190.088108ms","start":"2026-08-04T15:22:41.773072Z","end":"2026-08-04T15:22:41.96316Z","steps":["trace[1056456835] 'agreement among raft nodes before linearized reading' (duration: 190.008156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:41.963319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.8447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-04T15:22:41.963376Z","caller":"traceutil/trace.go:171","msg":"trace[306591303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:552; }","duration":"404.955102ms","start":"2026-08-04T15:22:41.558404Z","end":"2026-08-04T15:22:41.963359Z","steps":["trace[306591303] 'agreement among raft nodes before linearized reading' (duration: 404.789879ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:41.963407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:41.558372Z","time spent":"405.028694ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-04T15:22:41.963579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.290261ms","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-04T15:22:41.963605Z","caller":"traceutil/trace.go:171","msg":"trace[669628161] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:552; }","duration":"255.367023ms","start":"2026-08-04T15:22:41.708231Z","end":"2026-08-04T15:22:41.963598Z","steps":["trace[669628161] 'agreement among raft nodes before linearized reading' (duration: 255.295991ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:41.963854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.783473ms","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-04T15:22:41.963916Z","caller":"traceutil/trace.go:171","msg":"trace[633892469] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:552; }","duration":"413.874594ms","start":"2026-08-04T15:22:41.550029Z","end":"2026-08-04T15:22:41.963903Z","steps":["trace[633892469] 'agreement among raft nodes before linearized reading' (duration: 413.702851ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:41.963999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:41.550002Z","time spent":"413.988297ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-04T15:22:41.960577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.978606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:22:41.96739Z","caller":"traceutil/trace.go:171","msg":"trace[1454476804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"793.840378ms","start":"2026-08-04T15:22:41.173533Z","end":"2026-08-04T15:22:41.967373Z","steps":["trace[1454476804] 'agreement among raft nodes before linearized reading' (duration: 786.978876ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:41.96748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:41.173509Z","time spent":"793.953829ms","remote":"127.0.0.1:34792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-04T15:22:48.394259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.758853ms","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-04T15:22:48.394395Z","caller":"traceutil/trace.go:171","msg":"trace[109937183] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:634; }","duration":"225.913817ms","start":"2026-08-04T15:22:48.168463Z","end":"2026-08-04T15:22:48.394377Z","steps":["trace[109937183] 'range keys from in-memory index tree' (duration: 225.738993ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:48.394404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.801744ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:22:48.394639Z","caller":"traceutil/trace.go:171","msg":"trace[298921400] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"317.27211ms","start":"2026-08-04T15:22:48.077322Z","end":"2026-08-04T15:22:48.394594Z","steps":["trace[298921400] 'process raft request' (duration: 215.217941ms)","trace[298921400] 'compare' (duration: 101.699851ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:22:48.394714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.948751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:22:48.394654Z","caller":"traceutil/trace.go:171","msg":"trace[351134619] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"221.699397ms","start":"2026-08-04T15:22:48.172769Z","end":"2026-08-04T15:22:48.394468Z","steps":["trace[351134619] 'read index received' (duration: 119.676718ms)","trace[351134619] 'applied index is now lower than readState.Index' (duration: 102.021539ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:22:48.394742Z","caller":"traceutil/trace.go:171","msg":"trace[1556682574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"222.052353ms","start":"2026-08-04T15:22:48.17268Z","end":"2026-08-04T15:22:48.394733Z","steps":["trace[1556682574] 'agreement among raft nodes before linearized reading' (duration: 221.996021ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:48.394817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:48.077301Z","time spent":"317.430404ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:22:48.488105Z","caller":"traceutil/trace.go:171","msg":"trace[729757130] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"268.227438ms","start":"2026-08-04T15:22:48.21982Z","end":"2026-08-04T15:22:48.488048Z","steps":["trace[729757130] 'process raft request' (duration: 267.587866ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:48.488132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.375442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bdqdj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-04T15:22:48.488406Z","caller":"traceutil/trace.go:171","msg":"trace[674351195] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bdqdj; range_end:; response_count:1; response_revision:636; }","duration":"157.677687ms","start":"2026-08-04T15:22:48.330692Z","end":"2026-08-04T15:22:48.488369Z","steps":["trace[674351195] 'agreement among raft nodes before linearized reading' (duration: 157.278008ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:22:48.906573Z","caller":"traceutil/trace.go:171","msg":"trace[650583833] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"403.336032ms","start":"2026-08-04T15:22:48.503212Z","end":"2026-08-04T15:22:48.906548Z","steps":["trace[650583833] 'process raft request' (duration: 403.163609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:48.90679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:48.503193Z","time spent":"403.459154ms","remote":"127.0.0.1:35008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:22:48.97027Z","caller":"traceutil/trace.go:171","msg":"trace[221569656] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"424.416947ms","start":"2026-08-04T15:22:48.545832Z","end":"2026-08-04T15:22:48.970249Z","steps":["trace[221569656] 'process raft request' (duration: 424.257554ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:48.970424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:48.545772Z","time spent":"424.57475ms","remote":"127.0.0.1:35302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-04T15:22:50.484746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311263699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:22:50.484844Z","caller":"traceutil/trace.go:171","msg":"trace[426116158] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"1.311406342s","start":"2026-08-04T15:22:49.173414Z","end":"2026-08-04T15:22:50.484821Z","steps":["trace[426116158] 'range keys from in-memory index tree' (duration: 1.311127607s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:50.484882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:49.173395Z","time spent":"1.311479493s","remote":"127.0.0.1:34792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-04T15:22:50.485348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.298611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:22:50.485551Z","caller":"traceutil/trace.go:171","msg":"trace[1366494989] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.105144821s","start":"2026-08-04T15:22:49.380346Z","end":"2026-08-04T15:22:50.485491Z","steps":["trace[1366494989] 'process raft request' (duration: 167.625077ms)","trace[1366494989] 'compare' (duration: 936.959864ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:22:50.485672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:49.380318Z","time spent":"1.105305465s","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:22:51.082793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.320486ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-04T15:22:51.082898Z","caller":"traceutil/trace.go:171","msg":"trace[562376835] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:664; }","duration":"1.074696978s","start":"2026-08-04T15:22:50.008183Z","end":"2026-08-04T15:22:51.08288Z","steps":["trace[562376835] 'read index received' (duration: 127.172µs)","trace[562376835] 'applied index is now lower than readState.Index' (duration: 1.074568516s)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:22:51.083111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074932102s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-04T15:22:51.083143Z","caller":"traceutil/trace.go:171","msg":"trace[2140730669] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"1.075029065s","start":"2026-08-04T15:22:50.008104Z","end":"2026-08-04T15:22:51.083133Z","steps":["trace[2140730669] 'agreement among raft nodes before linearized reading' (duration: 1.074879942s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:51.083175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:50.008071Z","time spent":"1.075097286s","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-04T15:22:51.118425Z","caller":"traceutil/trace.go:171","msg":"trace[2108747353] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"576.032196ms","start":"2026-08-04T15:22:50.542367Z","end":"2026-08-04T15:22:51.118399Z","steps":["trace[2108747353] 'process raft request' (duration: 575.755551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:51.118603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:50.542342Z","time spent":"576.152228ms","remote":"127.0.0.1:35052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-04T15:22:51.118719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.389108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:22:51.118872Z","caller":"traceutil/trace.go:171","msg":"trace[452719487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"632.48444ms","start":"2026-08-04T15:22:50.486301Z","end":"2026-08-04T15:22:51.118786Z","steps":["trace[452719487] 'agreement among raft nodes before linearized reading' (duration: 632.263106ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:51.118867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.102649634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-04T15:22:51.118969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:50.486196Z","time spent":"632.720475ms","remote":"127.0.0.1:34800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-04T15:22:51.119078Z","caller":"traceutil/trace.go:171","msg":"trace[1250715765] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:640; }","duration":"1.102888319s","start":"2026-08-04T15:22:50.016113Z","end":"2026-08-04T15:22:51.119001Z","steps":["trace[1250715765] 'agreement among raft nodes before linearized reading' (duration: 1.102349038s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:51.119168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:50.016091Z","time spent":"1.103034322s","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-04T15:22:51.119098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.286936ms","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-04T15:22:51.119376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10397259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-04T15:22:51.119437Z","caller":"traceutil/trace.go:171","msg":"trace[1151864658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:640; }","duration":"1.104077422s","start":"2026-08-04T15:22:50.015337Z","end":"2026-08-04T15:22:51.119414Z","steps":["trace[1151864658] 'agreement among raft nodes before linearized reading' (duration: 1.102957591s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:51.11947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:50.015308Z","time spent":"1.104154523s","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-04T15:22:51.119374Z","caller":"traceutil/trace.go:171","msg":"trace[1579306538] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"719.611482ms","start":"2026-08-04T15:22:50.399734Z","end":"2026-08-04T15:22:51.119346Z","steps":["trace[1579306538] 'agreement among raft nodes before linearized reading' (duration: 718.698694ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:22:51.119628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:22:50.399713Z","time spent":"719.895918ms","remote":"127.0.0.1:35076","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-04T15:22:52.801739Z","caller":"traceutil/trace.go:171","msg":"trace[427341716] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"287.469997ms","start":"2026-08-04T15:22:52.514233Z","end":"2026-08-04T15:22:52.801703Z","steps":["trace[427341716] 'process raft request' (duration: 287.275394ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:06.683513Z","caller":"traceutil/trace.go:171","msg":"trace[1594357895] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"280.807204ms","start":"2026-08-04T15:23:06.402672Z","end":"2026-08-04T15:23:06.683479Z","steps":["trace[1594357895] 'process raft request' (duration: 279.796685ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:06.829633Z","caller":"traceutil/trace.go:171","msg":"trace[762138546] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"375.20497ms","start":"2026-08-04T15:23:06.454392Z","end":"2026-08-04T15:23:06.829597Z","steps":["trace[762138546] 'process raft request' (duration: 374.729342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:23:06.830394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:23:06.454363Z","time spent":"375.68466ms","remote":"127.0.0.1:35052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:23:07.557356Z","caller":"traceutil/trace.go:171","msg":"trace[852513785] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"102.120193ms","start":"2026-08-04T15:23:07.455206Z","end":"2026-08-04T15:23:07.557326Z","steps":["trace[852513785] 'process raft request' (duration: 101.9498ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:07.827094Z","caller":"traceutil/trace.go:171","msg":"trace[1617858098] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"106.65643ms","start":"2026-08-04T15:23:07.720371Z","end":"2026-08-04T15:23:07.827028Z","steps":["trace[1617858098] 'process raft request' (duration: 106.431896ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:07.993719Z","caller":"traceutil/trace.go:171","msg":"trace[1049485838] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"155.668892ms","start":"2026-08-04T15:23:07.83802Z","end":"2026-08-04T15:23:07.993689Z","steps":["trace[1049485838] 'process raft request' (duration: 155.458308ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:21.175842Z","caller":"traceutil/trace.go:171","msg":"trace[2107417289] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"167.252623ms","start":"2026-08-04T15:23:21.008564Z","end":"2026-08-04T15:23:21.175816Z","steps":["trace[2107417289] 'process raft request' (duration: 152.119885ms)","trace[2107417289] 'compare' (duration: 14.964755ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:23:21.233745Z","caller":"traceutil/trace.go:171","msg":"trace[85595960] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"222.905941ms","start":"2026-08-04T15:23:21.010804Z","end":"2026-08-04T15:23:21.23371Z","steps":["trace[85595960] 'process raft request' (duration: 222.8385ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:21.233795Z","caller":"traceutil/trace.go:171","msg":"trace[2110545715] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"224.809078ms","start":"2026-08-04T15:23:21.008938Z","end":"2026-08-04T15:23:21.233747Z","steps":["trace[2110545715] 'process raft request' (duration: 224.530853ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:26.947378Z","caller":"traceutil/trace.go:171","msg":"trace[514760068] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"114.240425ms","start":"2026-08-04T15:23:26.833112Z","end":"2026-08-04T15:23:26.947352Z","steps":["trace[514760068] 'process raft request' (duration: 114.108422ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:33.696426Z","caller":"traceutil/trace.go:171","msg":"trace[1120927180] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"131.89712ms","start":"2026-08-04T15:23:33.564504Z","end":"2026-08-04T15:23:33.696401Z","steps":["trace[1120927180] 'process raft request' (duration: 131.744007ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:43.401396Z","caller":"traceutil/trace.go:171","msg":"trace[1333771208] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"419.219377ms","start":"2026-08-04T15:23:42.982151Z","end":"2026-08-04T15:23:43.401371Z","steps":["trace[1333771208] 'process raft request' (duration: 419.056553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:23:43.401557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:23:42.982121Z","time spent":"419.34903ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:23:44.229224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.045236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:23:44.229316Z","caller":"traceutil/trace.go:171","msg":"trace[811639964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"984.206039ms","start":"2026-08-04T15:23:43.245099Z","end":"2026-08-04T15:23:44.229305Z","steps":["trace[811639964] 'agreement among raft nodes before linearized reading' (duration: 984.037986ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:23:44.229358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:23:43.245076Z","time spent":"984.27419ms","remote":"127.0.0.1:34792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-04T15:23:44.229113Z","caller":"traceutil/trace.go:171","msg":"trace[607250410] linearizableReadLoop","detail":"{readStateIndex:1060; appliedIndex:1059; }","duration":"983.853833ms","start":"2026-08-04T15:23:43.245155Z","end":"2026-08-04T15:23:44.229009Z","steps":["trace[607250410] 'read index received' (duration: 156.647841ms)","trace[607250410] 'applied index is now lower than readState.Index' (duration: 827.204892ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:23:44.229851Z","caller":"traceutil/trace.go:171","msg":"trace[1891456243] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"1.191180058s","start":"2026-08-04T15:23:43.037874Z","end":"2026-08-04T15:23:44.229054Z","steps":["trace[1891456243] 'process raft request' (duration: 1.190966934s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:23:44.230002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.608139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:23:44.230457Z","caller":"traceutil/trace.go:171","msg":"trace[1931320658] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1021; }","duration":"890.086458ms","start":"2026-08-04T15:23:43.340357Z","end":"2026-08-04T15:23:44.230443Z","steps":["trace[1931320658] 'agreement among raft nodes before linearized reading' (duration: 889.598148ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:23:44.230501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:23:43.340344Z","time spent":"890.147579ms","remote":"127.0.0.1:35008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-08-04T15:23:44.230089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:23:43.037846Z","time spent":"1.192127745s","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:23:44.230276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.839851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-04T15:23:44.230306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.138649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-04T15:23:44.231226Z","caller":"traceutil/trace.go:171","msg":"trace[64049514] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1021; }","duration":"747.053735ms","start":"2026-08-04T15:23:43.48411Z","end":"2026-08-04T15:23:44.231164Z","steps":["trace[64049514] 'agreement among raft nodes before linearized reading' (duration: 746.055706ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:23:44.231293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:23:43.484089Z","time spent":"747.188868ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-04T15:23:44.230389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.294207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:23:44.231436Z","caller":"traceutil/trace.go:171","msg":"trace[469925691] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1021; }","duration":"804.402867ms","start":"2026-08-04T15:23:43.427019Z","end":"2026-08-04T15:23:44.231422Z","steps":["trace[469925691] 'agreement among raft nodes before linearized reading' (duration: 803.324447ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:44.231355Z","caller":"traceutil/trace.go:171","msg":"trace[1437591144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1021; }","duration":"739.721417ms","start":"2026-08-04T15:23:43.491348Z","end":"2026-08-04T15:23:44.231069Z","steps":["trace[1437591144] 'agreement among raft nodes before linearized reading' (duration: 738.679017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:23:44.231624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:23:43.491323Z","time spent":"740.232336ms","remote":"127.0.0.1:35076","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-04T15:23:44.231499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:23:43.427001Z","time spent":"804.483418ms","remote":"127.0.0.1:34936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":27,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-08-04T15:23:44.230399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.862868ms","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-04T15:23:44.232009Z","caller":"traceutil/trace.go:171","msg":"trace[1057720859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1021; }","duration":"495.51183ms","start":"2026-08-04T15:23:43.736481Z","end":"2026-08-04T15:23:44.231993Z","steps":["trace[1057720859] 'agreement among raft nodes before linearized reading' (duration: 493.856228ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:23:44.232052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:23:43.736457Z","time spent":"495.58369ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-04T15:23:44.230397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.41275ms","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-04T15:23:44.232193Z","caller":"traceutil/trace.go:171","msg":"trace[1726622046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1021; }","duration":"741.283097ms","start":"2026-08-04T15:23:43.490899Z","end":"2026-08-04T15:23:44.232182Z","steps":["trace[1726622046] 'agreement among raft nodes before linearized reading' (duration: 739.440712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:23:44.232229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:23:43.490876Z","time spent":"741.343788ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-04T15:23:48.439813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.654027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:23:48.439984Z","caller":"traceutil/trace.go:171","msg":"trace[1795014531] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"168.736371ms","start":"2026-08-04T15:23:48.271212Z","end":"2026-08-04T15:23:48.439949Z","steps":["trace[1795014531] 'process raft request' (duration: 28.797278ms)","trace[1795014531] 'compare' (duration: 139.322571ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:23:48.455964Z","caller":"traceutil/trace.go:171","msg":"trace[916076126] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"184.46869ms","start":"2026-08-04T15:23:48.271466Z","end":"2026-08-04T15:23:48.455935Z","steps":["trace[916076126] 'process raft request' (duration: 184.255176ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:48.456237Z","caller":"traceutil/trace.go:171","msg":"trace[812431561] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"182.674107ms","start":"2026-08-04T15:23:48.273539Z","end":"2026-08-04T15:23:48.456213Z","steps":["trace[812431561] 'process raft request' (duration: 182.579805ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:48.456289Z","caller":"traceutil/trace.go:171","msg":"trace[1291651918] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"183.795138ms","start":"2026-08-04T15:23:48.272469Z","end":"2026-08-04T15:23:48.456264Z","steps":["trace[1291651918] 'process raft request' (duration: 183.419191ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:23:48.461251Z","caller":"traceutil/trace.go:171","msg":"trace[2134775190] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"157.480036ms","start":"2026-08-04T15:23:48.303755Z","end":"2026-08-04T15:23:48.461235Z","steps":["trace[2134775190] 'process raft request' (duration: 157.376685ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:08.587078Z","caller":"traceutil/trace.go:171","msg":"trace[1393289177] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"195.171364ms","start":"2026-08-04T15:24:08.391859Z","end":"2026-08-04T15:24:08.58703Z","steps":["trace[1393289177] 'process raft request' (duration: 194.853057ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:09.153199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276104165459066740,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-04T15:24:09.296511Z","caller":"traceutil/trace.go:171","msg":"trace[1230966479] transaction","detail":"{read_only:false; response_revision:1102; number_of_response:1; }","duration":"704.316796ms","start":"2026-08-04T15:24:08.592155Z","end":"2026-08-04T15:24:09.296471Z","steps":["trace[1230966479] 'process raft request' (duration: 704.080342ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:09.296743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:08.592126Z","time spent":"704.511549ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:24:09.308368Z","caller":"traceutil/trace.go:171","msg":"trace[400859602] linearizableReadLoop","detail":"{readStateIndex:1147; appliedIndex:1145; }","duration":"655.459159ms","start":"2026-08-04T15:24:08.652877Z","end":"2026-08-04T15:24:09.308336Z","steps":["trace[400859602] 'read index received' (duration: 643.350444ms)","trace[400859602] 'applied index is now lower than readState.Index' (duration: 12.105635ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:24:09.308514Z","caller":"traceutil/trace.go:171","msg":"trace[667259391] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"505.4001ms","start":"2026-08-04T15:24:08.803092Z","end":"2026-08-04T15:24:09.308492Z","steps":["trace[667259391] 'process raft request' (duration: 505.067693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:09.308635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:08.803016Z","time spent":"505.576042ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:24:09.308932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.18258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-04T15:24:09.308948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.017049ms","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-04T15:24:09.309051Z","caller":"traceutil/trace.go:171","msg":"trace[1807426364] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1103; }","duration":"610.357714ms","start":"2026-08-04T15:24:08.698677Z","end":"2026-08-04T15:24:09.309035Z","steps":["trace[1807426364] 'agreement among raft nodes before linearized reading' (duration: 610.202371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:09.309092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:08.698666Z","time spent":"610.413526ms","remote":"127.0.0.1:35050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"info","ts":"2026-08-04T15:24:09.309096Z","caller":"traceutil/trace.go:171","msg":"trace[147367693] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1103; }","duration":"656.246043ms","start":"2026-08-04T15:24:08.652817Z","end":"2026-08-04T15:24:09.309063Z","steps":["trace[147367693] 'agreement among raft nodes before linearized reading' (duration: 655.845916ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:09.30899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.437282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-04T15:24:09.309152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:08.652798Z","time spent":"656.341545ms","remote":"127.0.0.1:35076","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-04T15:24:09.309202Z","caller":"traceutil/trace.go:171","msg":"trace[1671473303] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1103; }","duration":"599.684836ms","start":"2026-08-04T15:24:08.709497Z","end":"2026-08-04T15:24:09.309182Z","steps":["trace[1671473303] 'agreement among raft nodes before linearized reading' (duration: 599.416571ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:09.309256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:08.709487Z","time spent":"599.750328ms","remote":"127.0.0.1:35216","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-04T15:24:09.309986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.316658ms","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-04T15:24:09.310086Z","caller":"traceutil/trace.go:171","msg":"trace[1996619865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1103; }","duration":"651.4224ms","start":"2026-08-04T15:24:08.658648Z","end":"2026-08-04T15:24:09.310071Z","steps":["trace[1996619865] 'agreement among raft nodes before linearized reading' (duration: 650.245827ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:09.310133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.740896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-04T15:24:09.310191Z","caller":"traceutil/trace.go:171","msg":"trace[464369204] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1103; }","duration":"652.823197ms","start":"2026-08-04T15:24:08.657352Z","end":"2026-08-04T15:24:09.310175Z","steps":["trace[464369204] 'agreement among raft nodes before linearized reading' (duration: 651.481741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:09.310163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:08.65859Z","time spent":"651.555812ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-04T15:24:09.310254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:08.657338Z","time spent":"652.906649ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-04T15:24:11.095314Z","caller":"traceutil/trace.go:171","msg":"trace[2031628681] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"502.282749ms","start":"2026-08-04T15:24:10.593009Z","end":"2026-08-04T15:24:11.095292Z","steps":["trace[2031628681] 'process raft request' (duration: 502.104445ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:11.095461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:10.592971Z","time spent":"502.407861ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:24:13.388792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.271058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:24:13.388878Z","caller":"traceutil/trace.go:171","msg":"trace[2081856390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1114; }","duration":"143.41636ms","start":"2026-08-04T15:24:13.245443Z","end":"2026-08-04T15:24:13.38886Z","steps":["trace[2081856390] 'range keys from in-memory index tree' (duration: 143.164046ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:31.38557Z","caller":"traceutil/trace.go:171","msg":"trace[1762348779] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"151.963666ms","start":"2026-08-04T15:24:31.233578Z","end":"2026-08-04T15:24:31.385541Z","steps":["trace[1762348779] 'process raft request' (duration: 151.786873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:31.887352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.215357ms","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-04T15:24:31.887358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.250301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-04T15:24:31.887418Z","caller":"traceutil/trace.go:171","msg":"trace[2071284169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1229; }","duration":"306.348119ms","start":"2026-08-04T15:24:31.581053Z","end":"2026-08-04T15:24:31.887401Z","steps":["trace[2071284169] 'range keys from in-memory index tree' (duration: 306.063544ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:31.887447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:31.581032Z","time spent":"306.409131ms","remote":"127.0.0.1:35076","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-04T15:24:31.887451Z","caller":"traceutil/trace.go:171","msg":"trace[114072509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1229; }","duration":"300.370563ms","start":"2026-08-04T15:24:31.587057Z","end":"2026-08-04T15:24:31.887427Z","steps":["trace[114072509] 'range keys from in-memory index tree' (duration: 300.108489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:31.887472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.277092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-04T15:24:31.887495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:31.587051Z","time spent":"300.434214ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-04T15:24:31.887523Z","caller":"traceutil/trace.go:171","msg":"trace[1409996337] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1229; }","duration":"300.346642ms","start":"2026-08-04T15:24:31.587161Z","end":"2026-08-04T15:24:31.887507Z","steps":["trace[1409996337] 'range keys from in-memory index tree' (duration: 300.162809ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:31.887557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:31.587151Z","time spent":"300.398764ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-04T15:24:31.887554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.408504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-04T15:24:31.887617Z","caller":"traceutil/trace.go:171","msg":"trace[1056640186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1229; }","duration":"300.499595ms","start":"2026-08-04T15:24:31.587102Z","end":"2026-08-04T15:24:31.887602Z","steps":["trace[1056640186] 'range keys from in-memory index tree' (duration: 300.307352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:31.88765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:31.587099Z","time spent":"300.544916ms","remote":"127.0.0.1:35076","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-04T15:24:31.887871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.364288ms","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-04T15:24:31.887988Z","caller":"traceutil/trace.go:171","msg":"trace[1363610051] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1229; }","duration":"210.556303ms","start":"2026-08-04T15:24:31.677406Z","end":"2026-08-04T15:24:31.887962Z","steps":["trace[1363610051] 'range keys from in-memory index tree' (duration: 210.108514ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:44.159617Z","caller":"traceutil/trace.go:171","msg":"trace[656352908] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"199.662111ms","start":"2026-08-04T15:24:43.959931Z","end":"2026-08-04T15:24:44.159594Z","steps":["trace[656352908] 'process raft request' (duration: 199.542689ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:44.159717Z","caller":"traceutil/trace.go:171","msg":"trace[1973694119] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1322; }","duration":"199.517218ms","start":"2026-08-04T15:24:43.960148Z","end":"2026-08-04T15:24:44.159665Z","steps":["trace[1973694119] 'read index received' (duration: 199.489848ms)","trace[1973694119] 'applied index is now lower than readState.Index' (duration: 22.5µs)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:24:44.160177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.980518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-04T15:24:44.160235Z","caller":"traceutil/trace.go:171","msg":"trace[1928345003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1269; }","duration":"200.09669ms","start":"2026-08-04T15:24:43.960123Z","end":"2026-08-04T15:24:44.160219Z","steps":["trace[1928345003] 'agreement among raft nodes before linearized reading' (duration: 199.773343ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:44.182179Z","caller":"traceutil/trace.go:171","msg":"trace[602201781] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"218.632989ms","start":"2026-08-04T15:24:43.963509Z","end":"2026-08-04T15:24:44.182142Z","steps":["trace[602201781] 'process raft request' (duration: 218.552468ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:44.184174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.954572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-04T15:24:44.184332Z","caller":"traceutil/trace.go:171","msg":"trace[1873629576] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1270; }","duration":"224.037524ms","start":"2026-08-04T15:24:43.960177Z","end":"2026-08-04T15:24:44.184215Z","steps":["trace[1873629576] 'agreement among raft nodes before linearized reading' (duration: 222.306659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:44.184558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.087975ms","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-04T15:24:44.184716Z","caller":"traceutil/trace.go:171","msg":"trace[832935368] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1270; }","duration":"224.146486ms","start":"2026-08-04T15:24:43.960439Z","end":"2026-08-04T15:24:44.184586Z","steps":["trace[832935368] 'agreement among raft nodes before linearized reading' (duration: 222.026874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:46.67484Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276104165459067153,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-04T15:24:46.987191Z","caller":"traceutil/trace.go:171","msg":"trace[1160826824] linearizableReadLoop","detail":"{readStateIndex:1330; appliedIndex:1329; }","duration":"812.730828ms","start":"2026-08-04T15:24:46.174436Z","end":"2026-08-04T15:24:46.987167Z","steps":["trace[1160826824] 'read index received' (duration: 812.548665ms)","trace[1160826824] 'applied index is now lower than readState.Index' (duration: 180.633µs)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:24:46.98754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.078865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:24:46.98761Z","caller":"traceutil/trace.go:171","msg":"trace[722889726] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1276; }","duration":"813.213457ms","start":"2026-08-04T15:24:46.174384Z","end":"2026-08-04T15:24:46.987598Z","steps":["trace[722889726] 'agreement among raft nodes before linearized reading' (duration: 813.080985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:46.987647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:46.174375Z","time spent":"813.262738ms","remote":"127.0.0.1:34792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-04T15:24:46.987291Z","caller":"traceutil/trace.go:171","msg":"trace[2106509247] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"819.776065ms","start":"2026-08-04T15:24:46.167465Z","end":"2026-08-04T15:24:46.987241Z","steps":["trace[2106509247] 'process raft request' (duration: 819.562801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:46.987915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:46.167444Z","time spent":"820.380277ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:24:47.48831Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276104165459067156,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-04T15:24:47.541217Z","caller":"traceutil/trace.go:171","msg":"trace[708001516] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"787.981458ms","start":"2026-08-04T15:24:46.753208Z","end":"2026-08-04T15:24:47.54119Z","steps":["trace[708001516] 'process raft request' (duration: 787.259444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:47.54135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:46.753183Z","time spent":"788.09798ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:24:47.62298Z","caller":"traceutil/trace.go:171","msg":"trace[1009532023] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1330; }","duration":"635.385909ms","start":"2026-08-04T15:24:46.98755Z","end":"2026-08-04T15:24:47.622935Z","steps":["trace[1009532023] 'read index received' (duration: 552.931071ms)","trace[1009532023] 'applied index is now lower than readState.Index' (duration: 82.453828ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:24:47.623257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.430880084s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-04T15:24:47.623286Z","caller":"traceutil/trace.go:171","msg":"trace[938603898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1278; }","duration":"1.430954905s","start":"2026-08-04T15:24:46.192323Z","end":"2026-08-04T15:24:47.623278Z","steps":["trace[938603898] 'agreement among raft nodes before linearized reading' (duration: 1.430818742s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:47.623284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.428485336s","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-04T15:24:47.623399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.436510533s","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":"warn","ts":"2026-08-04T15:24:47.623356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.427820544s","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-04T15:24:47.623422Z","caller":"traceutil/trace.go:171","msg":"trace[812038177] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1278; }","duration":"1.436578074s","start":"2026-08-04T15:24:46.186838Z","end":"2026-08-04T15:24:47.623417Z","steps":["trace[812038177] 'agreement among raft nodes before linearized reading' (duration: 1.436502313s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:47.623449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:46.186815Z","time spent":"1.436630475s","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-04T15:24:47.623415Z","caller":"traceutil/trace.go:171","msg":"trace[441021991] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"189.632217ms","start":"2026-08-04T15:24:47.43375Z","end":"2026-08-04T15:24:47.623382Z","steps":["trace[441021991] 'process raft request' (duration: 189.049326ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:47.623466Z","caller":"traceutil/trace.go:171","msg":"trace[1117649273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1278; }","duration":"1.427977457s","start":"2026-08-04T15:24:46.195465Z","end":"2026-08-04T15:24:47.623443Z","steps":["trace[1117649273] 'agreement among raft nodes before linearized reading' (duration: 1.427556109s)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:47.62336Z","caller":"traceutil/trace.go:171","msg":"trace[1086351486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1278; }","duration":"1.428599699s","start":"2026-08-04T15:24:46.194741Z","end":"2026-08-04T15:24:47.62334Z","steps":["trace[1086351486] 'agreement among raft nodes before linearized reading' (duration: 1.428336044s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:47.623529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:46.195457Z","time spent":"1.428061998s","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-04T15:24:47.623546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:46.194709Z","time spent":"1.428830913s","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-04T15:24:47.62338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.740528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-04T15:24:47.623316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:46.19231Z","time spent":"1.430999955s","remote":"127.0.0.1:35076","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-04T15:24:47.623718Z","caller":"traceutil/trace.go:171","msg":"trace[248405912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1278; }","duration":"634.117195ms","start":"2026-08-04T15:24:46.989565Z","end":"2026-08-04T15:24:47.623682Z","steps":["trace[248405912] 'agreement among raft nodes before linearized reading' (duration: 633.719088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:47.623804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:46.989547Z","time spent":"634.230767ms","remote":"127.0.0.1:34800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-04T15:24:47.938375Z","caller":"traceutil/trace.go:171","msg":"trace[1318337207] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"243.517762ms","start":"2026-08-04T15:24:47.694832Z","end":"2026-08-04T15:24:47.93835Z","steps":["trace[1318337207] 'process raft request' (duration: 166.396147ms)","trace[1318337207] 'compare' (duration: 76.838409ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:24:49.483394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.361368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:24:49.483478Z","caller":"traceutil/trace.go:171","msg":"trace[766917932] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1284; }","duration":"309.496772ms","start":"2026-08-04T15:24:49.173963Z","end":"2026-08-04T15:24:49.483459Z","steps":["trace[766917932] 'range keys from in-memory index tree' (duration: 309.256545ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:49.483529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:49.173941Z","time spent":"309.578584ms","remote":"127.0.0.1:34792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-04T15:24:49.824334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.44146ms","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-04T15:24:49.824376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.413229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-08-04T15:24:49.824417Z","caller":"traceutil/trace.go:171","msg":"trace[1865169993] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1285; }","duration":"114.568042ms","start":"2026-08-04T15:24:49.709832Z","end":"2026-08-04T15:24:49.8244Z","steps":["trace[1865169993] 'range keys from in-memory index tree' (duration: 114.244466ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:49.824456Z","caller":"traceutil/trace.go:171","msg":"trace[201672189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1285; }","duration":"114.526771ms","start":"2026-08-04T15:24:49.709909Z","end":"2026-08-04T15:24:49.824435Z","steps":["trace[201672189] 'range keys from in-memory index tree' (duration: 114.270076ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:49.824682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.127002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-08-04T15:24:49.824733Z","caller":"traceutil/trace.go:171","msg":"trace[671291828] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1285; }","duration":"115.222634ms","start":"2026-08-04T15:24:49.709502Z","end":"2026-08-04T15:24:49.824724Z","steps":["trace[671291828] 'range keys from in-memory index tree' (duration: 114.957299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:49.824838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.743925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-04T15:24:49.824898Z","caller":"traceutil/trace.go:171","msg":"trace[1089425444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1285; }","duration":"114.805107ms","start":"2026-08-04T15:24:49.710077Z","end":"2026-08-04T15:24:49.824882Z","steps":["trace[1089425444] 'range keys from in-memory index tree' (duration: 114.534151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:49.994179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.277689ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-04T15:24:49.994617Z","caller":"traceutil/trace.go:171","msg":"trace[553712472] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"166.093071ms","start":"2026-08-04T15:24:49.828501Z","end":"2026-08-04T15:24:49.994594Z","steps":["trace[553712472] 'process raft request' (duration: 165.814145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:50.872271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.70188ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:24:50.872572Z","caller":"traceutil/trace.go:171","msg":"trace[899023512] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1341; }","duration":"684.574394ms","start":"2026-08-04T15:24:50.187982Z","end":"2026-08-04T15:24:50.872556Z","steps":["trace[899023512] 'read index received' (duration: 306.095575ms)","trace[899023512] 'applied index is now lower than readState.Index' (duration: 378.477639ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:24:50.872751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"684.795517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-08-04T15:24:50.872788Z","caller":"traceutil/trace.go:171","msg":"trace[753188100] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1291; }","duration":"684.863819ms","start":"2026-08-04T15:24:50.187915Z","end":"2026-08-04T15:24:50.872779Z","steps":["trace[753188100] 'agreement among raft nodes before linearized reading' (duration: 684.689536ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:50.87282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:50.187898Z","time spent":"684.914699ms","remote":"127.0.0.1:34992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5353,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-04T15:24:50.873005Z","caller":"traceutil/trace.go:171","msg":"trace[1222885114] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"1.04383567s","start":"2026-08-04T15:24:49.829157Z","end":"2026-08-04T15:24:50.872993Z","steps":["trace[1222885114] 'process raft request' (duration: 1.043282479s)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:50.872999Z","caller":"traceutil/trace.go:171","msg":"trace[834153238] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"1.04388857s","start":"2026-08-04T15:24:49.829086Z","end":"2026-08-04T15:24:50.872974Z","steps":["trace[834153238] 'process raft request' (duration: 1.043279799s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:50.873069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:49.82915Z","time spent":"1.043886361s","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:24:50.873127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:49.829072Z","time spent":"1.043981052s","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:24:50.87315Z","caller":"traceutil/trace.go:171","msg":"trace[355021421] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"794.396743ms","start":"2026-08-04T15:24:50.078745Z","end":"2026-08-04T15:24:50.873141Z","steps":["trace[355021421] 'process raft request' (duration: 793.73472ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:50.873189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:50.078704Z","time spent":"794.466293ms","remote":"127.0.0.1:34900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-04T15:24:50.87321Z","caller":"traceutil/trace.go:171","msg":"trace[1431047967] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"1.044603003s","start":"2026-08-04T15:24:49.828583Z","end":"2026-08-04T15:24:50.873186Z","steps":["trace[1431047967] 'process raft request' (duration: 299.910034ms)","trace[1431047967] 'compare' (duration: 743.15423ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:24:50.873243Z","caller":"traceutil/trace.go:171","msg":"trace[700326757] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"747.595225ms","start":"2026-08-04T15:24:50.125639Z","end":"2026-08-04T15:24:50.873235Z","steps":["trace[700326757] 'process raft request' (duration: 746.883512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:50.873301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:49.828557Z","time spent":"1.044695786s","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:24:50.873524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:24:50.125615Z","time spent":"747.645406ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:24:51.283253Z","caller":"traceutil/trace.go:171","msg":"trace[2131584662] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"201.791363ms","start":"2026-08-04T15:24:51.081437Z","end":"2026-08-04T15:24:51.283229Z","steps":["trace[2131584662] 'process raft request' (duration: 201.66151ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:24:51.315189Z","caller":"traceutil/trace.go:171","msg":"trace[543640646] linearizableReadLoop","detail":"{readStateIndex:1348; appliedIndex:1347; }","duration":"142.33235ms","start":"2026-08-04T15:24:51.172826Z","end":"2026-08-04T15:24:51.315158Z","steps":["trace[543640646] 'read index received' (duration: 110.533143ms)","trace[543640646] 'applied index is now lower than readState.Index' (duration: 31.797967ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:24:51.315324Z","caller":"traceutil/trace.go:171","msg":"trace[1647657745] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"232.575679ms","start":"2026-08-04T15:24:51.082732Z","end":"2026-08-04T15:24:51.315308Z","steps":["trace[1647657745] 'process raft request' (duration: 232.074279ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:24:51.315558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.703417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:24:51.315587Z","caller":"traceutil/trace.go:171","msg":"trace[572977997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1293; }","duration":"142.767268ms","start":"2026-08-04T15:24:51.17281Z","end":"2026-08-04T15:24:51.315577Z","steps":["trace[572977997] 'agreement among raft nodes before linearized reading' (duration: 142.677976ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:01.432563Z","caller":"traceutil/trace.go:171","msg":"trace[1979061946] linearizableReadLoop","detail":"{readStateIndex:1435; appliedIndex:1434; }","duration":"422.38669ms","start":"2026-08-04T15:25:01.010145Z","end":"2026-08-04T15:25:01.432532Z","steps":["trace[1979061946] 'read index received' (duration: 422.261978ms)","trace[1979061946] 'applied index is now lower than readState.Index' (duration: 123.052µs)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:01.432705Z","caller":"traceutil/trace.go:171","msg":"trace[77514242] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"467.891571ms","start":"2026-08-04T15:25:00.964778Z","end":"2026-08-04T15:25:01.432669Z","steps":["trace[77514242] 'process raft request' (duration: 467.554705ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:01.432921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:00.96475Z","time spent":"468.059745ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:25:01.432947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.782877ms","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-04T15:25:01.43304Z","caller":"traceutil/trace.go:171","msg":"trace[280842510] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1378; }","duration":"422.9359ms","start":"2026-08-04T15:25:01.010086Z","end":"2026-08-04T15:25:01.433022Z","steps":["trace[280842510] 'agreement among raft nodes before linearized reading' (duration: 422.585803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:01.433187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:01.010059Z","time spent":"423.113674ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-04T15:25:01.933381Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276104165459067346,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-04T15:25:02.314246Z","caller":"traceutil/trace.go:171","msg":"trace[1996094315] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"1.178568431s","start":"2026-08-04T15:25:01.135639Z","end":"2026-08-04T15:25:02.314208Z","steps":["trace[1996094315] 'process raft request' (duration: 1.178349257s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:02.314529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:01.135619Z","time spent":"1.178717635s","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-04T15:25:02.315354Z","caller":"traceutil/trace.go:171","msg":"trace[556279674] linearizableReadLoop","detail":"{readStateIndex:1438; appliedIndex:1435; }","duration":"882.686534ms","start":"2026-08-04T15:25:01.432639Z","end":"2026-08-04T15:25:02.315326Z","steps":["trace[556279674] 'read index received' (duration: 881.296667ms)","trace[556279674] 'applied index is now lower than readState.Index' (duration: 1.388507ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:25:02.315771Z","caller":"traceutil/trace.go:171","msg":"trace[1442845076] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"877.387992ms","start":"2026-08-04T15:25:01.438365Z","end":"2026-08-04T15:25:02.315753Z","steps":["trace[1442845076] 'process raft request' (duration: 876.766749ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:02.315994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:01.438342Z","time spent":"877.471884ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:25:02.316306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.30538725s","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-04T15:25:02.316378Z","caller":"traceutil/trace.go:171","msg":"trace[1889266671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1381; }","duration":"1.305481172s","start":"2026-08-04T15:25:01.010855Z","end":"2026-08-04T15:25:02.316337Z","steps":["trace[1889266671] 'agreement among raft nodes before linearized reading' (duration: 1.305351079s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:02.316412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:01.010835Z","time spent":"1.305566483s","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-04T15:25:02.316394Z","caller":"traceutil/trace.go:171","msg":"trace[1984462001] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"603.620023ms","start":"2026-08-04T15:25:01.712679Z","end":"2026-08-04T15:25:02.316299Z","steps":["trace[1984462001] 'process raft request' (duration: 602.599433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:02.316569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:01.712616Z","time spent":"603.874439ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-04T15:25:02.316709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.144302317s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-04T15:25:02.316777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"952.120801ms","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-04T15:25:02.316792Z","caller":"traceutil/trace.go:171","msg":"trace[980243382] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1381; }","duration":"1.144413039s","start":"2026-08-04T15:25:01.172359Z","end":"2026-08-04T15:25:02.316772Z","steps":["trace[980243382] 'agreement among raft nodes before linearized reading' (duration: 1.144201585s)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:25:02.317001Z","caller":"traceutil/trace.go:171","msg":"trace[887432153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1381; }","duration":"952.367955ms","start":"2026-08-04T15:25:01.364621Z","end":"2026-08-04T15:25:02.316989Z","steps":["trace[887432153] 'agreement among raft nodes before linearized reading' (duration: 951.605241ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:02.317016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:01.172351Z","time spent":"1.144656593s","remote":"127.0.0.1:34792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-04T15:25:02.316787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.406592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-04T15:25:02.317087Z","caller":"traceutil/trace.go:171","msg":"trace[325184219] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1381; }","duration":"501.753639ms","start":"2026-08-04T15:25:01.815319Z","end":"2026-08-04T15:25:02.317072Z","steps":["trace[325184219] 'agreement among raft nodes before linearized reading' (duration: 501.400582ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:02.317033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:01.364609Z","time spent":"952.417676ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-04T15:25:02.31714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:01.815304Z","time spent":"501.82023ms","remote":"127.0.0.1:34910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":11,"response size":29,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-08-04T15:25:02.317319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.691612ms","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-04T15:25:02.317349Z","caller":"traceutil/trace.go:171","msg":"trace[2116189079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1381; }","duration":"266.770832ms","start":"2026-08-04T15:25:02.050569Z","end":"2026-08-04T15:25:02.31734Z","steps":["trace[2116189079] 'agreement among raft nodes before linearized reading' (duration: 266.685541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:02.317964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"981.791086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-08-04T15:25:02.318013Z","caller":"traceutil/trace.go:171","msg":"trace[824846358] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1381; }","duration":"981.890148ms","start":"2026-08-04T15:25:01.336111Z","end":"2026-08-04T15:25:02.318002Z","steps":["trace[824846358] 'agreement among raft nodes before linearized reading' (duration: 980.354968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:02.318039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:01.336082Z","time spent":"981.951538ms","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-08-04T15:25:02.318274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280589029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-04T15:25:02.31831Z","caller":"traceutil/trace.go:171","msg":"trace[144426983] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1381; }","duration":"1.280642699s","start":"2026-08-04T15:25:01.037656Z","end":"2026-08-04T15:25:02.318299Z","steps":["trace[144426983] 'agreement among raft nodes before linearized reading' (duration: 1.278554629s)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:25:02.318329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-04T15:25:01.037641Z","time spent":"1.28068387s","remote":"127.0.0.1:35076","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-08-04T15:27:51.24519Z","caller":"traceutil/trace.go:171","msg":"trace[128269657] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"147.131605ms","start":"2026-08-04T15:27:51.098023Z","end":"2026-08-04T15:27:51.245155Z","steps":["trace[128269657] 'process raft request' (duration: 49.818593ms)","trace[128269657] 'compare' (duration: 97.171279ms)"],"step_count":2} {"level":"warn","ts":"2026-08-04T15:28:05.820364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.267722ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-04T15:28:05.820496Z","caller":"traceutil/trace.go:171","msg":"trace[1730316499] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"293.061066ms","start":"2026-08-04T15:28:05.527415Z","end":"2026-08-04T15:28:05.820476Z","steps":["trace[1730316499] 'process raft request' (duration: 133.578709ms)","trace[1730316499] 'compare' (duration: 159.168041ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:28:14.030828Z","caller":"traceutil/trace.go:171","msg":"trace[222886221] transaction","detail":"{read_only:false; response_revision:2262; number_of_response:1; }","duration":"122.154217ms","start":"2026-08-04T15:28:13.908641Z","end":"2026-08-04T15:28:14.030795Z","steps":["trace[222886221] 'process raft request' (duration: 121.933383ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:28:15.521982Z","caller":"traceutil/trace.go:171","msg":"trace[637437164] transaction","detail":"{read_only:false; response_revision:2268; number_of_response:1; }","duration":"100.340651ms","start":"2026-08-04T15:28:15.421623Z","end":"2026-08-04T15:28:15.521963Z","steps":["trace[637437164] 'process raft request' (duration: 100.096166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:28:26.452187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.576735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-04T15:28:26.452281Z","caller":"traceutil/trace.go:171","msg":"trace[1951634981] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2318; }","duration":"106.724548ms","start":"2026-08-04T15:28:26.345534Z","end":"2026-08-04T15:28:26.452258Z","steps":["trace[1951634981] 'count revisions from in-memory index tree' (duration: 106.508224ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:29:26.139827Z","caller":"traceutil/trace.go:171","msg":"trace[1532268656] transaction","detail":"{read_only:false; response_revision:2618; number_of_response:1; }","duration":"247.545483ms","start":"2026-08-04T15:29:25.892228Z","end":"2026-08-04T15:29:26.139773Z","steps":["trace[1532268656] 'process raft request' (duration: 247.313809ms)"],"step_count":1} {"level":"info","ts":"2026-08-04T15:29:26.156731Z","caller":"traceutil/trace.go:171","msg":"trace[801976148] linearizableReadLoop","detail":"{readStateIndex:2730; appliedIndex:2728; }","duration":"159.927979ms","start":"2026-08-04T15:29:25.996759Z","end":"2026-08-04T15:29:26.156687Z","steps":["trace[801976148] 'read index received' (duration: 142.818825ms)","trace[801976148] 'applied index is now lower than readState.Index' (duration: 17.107184ms)"],"step_count":2} {"level":"info","ts":"2026-08-04T15:29:26.156811Z","caller":"traceutil/trace.go:171","msg":"trace[1441476784] transaction","detail":"{read_only:false; response_revision:2619; number_of_response:1; }","duration":"264.156008ms","start":"2026-08-04T15:29:25.892614Z","end":"2026-08-04T15:29:26.15677Z","steps":["trace[1441476784] 'process raft request' (duration: 263.770641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:29:26.157039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.265866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-04T15:29:26.157163Z","caller":"traceutil/trace.go:171","msg":"trace[57179220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2619; }","duration":"160.427229ms","start":"2026-08-04T15:29:25.996671Z","end":"2026-08-04T15:29:26.157098Z","steps":["trace[57179220] 'agreement among raft nodes before linearized reading' (duration: 160.180113ms)"],"step_count":1} {"level":"warn","ts":"2026-08-04T15:29:26.158117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.126952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-04T15:29:26.15817Z","caller":"traceutil/trace.go:171","msg":"trace[1939682895] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2619; }","duration":"161.208954ms","start":"2026-08-04T15:29:25.99695Z","end":"2026-08-04T15:29:26.158159Z","steps":["trace[1939682895] 'agreement among raft nodes before linearized reading' (duration: 159.95872ms)"],"step_count":1}