{"level":"warn","ts":"2026-07-17T14:29:10.018898Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T14:29:10.019016Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.139:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.139:2380","--initial-cluster=instance=https://199.19.213.139:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.139:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.139:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-17T14:29:10.019234Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-17T14:29:10.019251Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.139:2380"]} {"level":"info","ts":"2026-07-17T14:29:10.019552Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T14:29:10.020285Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.139:2379"]} {"level":"info","ts":"2026-07-17T14:29:10.020393Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.139:2380"],"listen-peer-urls":["https://199.19.213.139:2380"],"advertise-client-urls":["https://199.19.213.139:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.139:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.139:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-17T14:29:10.023923Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.246237ms"} {"level":"info","ts":"2026-07-17T14:29:10.031132Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"cb257ce137899862","cluster-id":"9913588efaa998c1"} {"level":"info","ts":"2026-07-17T14:29:10.031332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T14:29:10.031385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 became follower at term 0"} {"level":"info","ts":"2026-07-17T14:29:10.031409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft cb257ce137899862 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T14:29:10.032087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 became follower at term 1"} {"level":"info","ts":"2026-07-17T14:29:10.032162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 switched to configuration voters=(14638243470578915426)"} {"level":"warn","ts":"2026-07-17T14:29:10.037305Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T14:29:10.040074Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T14:29:10.04105Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-17T14:29:10.042963Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"cb257ce137899862","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T14:29:10.043174Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"cb257ce137899862","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T14:29:10.043315Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T14:29:10.043371Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T14:29:10.043378Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-17T14:29:10.044066Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 switched to configuration voters=(14638243470578915426)"} {"level":"info","ts":"2026-07-17T14:29:10.045432Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9913588efaa998c1","local-member-id":"cb257ce137899862","added-peer-id":"cb257ce137899862","added-peer-peer-urls":["https://199.19.213.139:2380"]} {"level":"info","ts":"2026-07-17T14:29:10.049116Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-17T14:29:10.049336Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.139:2380"} {"level":"info","ts":"2026-07-17T14:29:10.049383Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.139:2380"} {"level":"info","ts":"2026-07-17T14:29:10.049427Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"cb257ce137899862","initial-advertise-peer-urls":["https://199.19.213.139:2380"],"listen-peer-urls":["https://199.19.213.139:2380"],"advertise-client-urls":["https://199.19.213.139:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.139:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T14:29:10.049514Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T14:29:10.132722Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T14:29:10.132837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T14:29:10.132883Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 received MsgPreVoteResp from cb257ce137899862 at term 1"} {"level":"info","ts":"2026-07-17T14:29:10.132909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 became candidate at term 2"} {"level":"info","ts":"2026-07-17T14:29:10.132921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 received MsgVoteResp from cb257ce137899862 at term 2"} {"level":"info","ts":"2026-07-17T14:29:10.13295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"cb257ce137899862 became leader at term 2"} {"level":"info","ts":"2026-07-17T14:29:10.13297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: cb257ce137899862 elected leader cb257ce137899862 at term 2"} {"level":"info","ts":"2026-07-17T14:29:10.134059Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"cb257ce137899862","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.139:2379]}","request-path":"/0/members/cb257ce137899862/attributes","cluster-id":"9913588efaa998c1","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T14:29:10.13407Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T14:29:10.134044Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T14:29:10.134187Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T14:29:10.134651Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T14:29:10.134721Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T14:29:10.135826Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9913588efaa998c1","local-member-id":"cb257ce137899862","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T14:29:10.135977Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T14:29:10.136024Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T14:29:10.137817Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T14:29:10.138579Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.139:2379"} {"level":"info","ts":"2026-07-17T14:29:38.774198Z","caller":"traceutil/trace.go:171","msg":"trace[1928366796] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"328.319685ms","start":"2026-07-17T14:29:38.445857Z","end":"2026-07-17T14:29:38.774177Z","steps":["trace[1928366796] 'process raft request' (duration: 328.200591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:38.774647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:38.445843Z","time spent":"328.440008ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:29:41.721613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.759649ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:29:41.721752Z","caller":"traceutil/trace.go:171","msg":"trace[983983177] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:438; }","duration":"154.923404ms","start":"2026-07-17T14:29:41.566813Z","end":"2026-07-17T14:29:41.721737Z","steps":["trace[983983177] 'range keys from in-memory index tree' (duration: 154.740088ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:29:44.035848Z","caller":"traceutil/trace.go:171","msg":"trace[123104713] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"206.090441ms","start":"2026-07-17T14:29:43.829739Z","end":"2026-07-17T14:29:44.03583Z","steps":["trace[123104713] 'process raft request' (duration: 205.990038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:52.567686Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10980514146934789225,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T14:29:52.880708Z","caller":"traceutil/trace.go:171","msg":"trace[618293859] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"865.522491ms","start":"2026-07-17T14:29:52.015165Z","end":"2026-07-17T14:29:52.880688Z","steps":["trace[618293859] 'process raft request' (duration: 865.437708ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:52.880889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:52.015142Z","time spent":"865.685226ms","remote":"127.0.0.1:37566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T14:29:52.887586Z","caller":"traceutil/trace.go:171","msg":"trace[2032766741] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"855.588966ms","start":"2026-07-17T14:29:52.031952Z","end":"2026-07-17T14:29:52.887541Z","steps":["trace[2032766741] 'process raft request' (duration: 855.149312ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:29:52.887616Z","caller":"traceutil/trace.go:171","msg":"trace[155254497] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"863.520636ms","start":"2026-07-17T14:29:52.024077Z","end":"2026-07-17T14:29:52.887597Z","steps":["trace[155254497] 'process raft request' (duration: 862.811513ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:29:52.887679Z","caller":"traceutil/trace.go:171","msg":"trace[1616105026] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"807.547922ms","start":"2026-07-17T14:29:52.080123Z","end":"2026-07-17T14:29:52.887671Z","steps":["trace[1616105026] 'process raft request' (duration: 807.254193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:52.887699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:52.031946Z","time spent":"855.6964ms","remote":"127.0.0.1:37566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T14:29:52.887741Z","caller":"traceutil/trace.go:171","msg":"trace[1911746115] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"845.062831ms","start":"2026-07-17T14:29:52.04267Z","end":"2026-07-17T14:29:52.887733Z","steps":["trace[1911746115] 'process raft request' (duration: 844.585176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:52.887744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:52.024052Z","time spent":"863.62867ms","remote":"127.0.0.1:37566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T14:29:52.887798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:52.042661Z","time spent":"845.109443ms","remote":"127.0.0.1:37566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T14:29:52.887844Z","caller":"traceutil/trace.go:171","msg":"trace[1937079189] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"863.64767ms","start":"2026-07-17T14:29:52.024164Z","end":"2026-07-17T14:29:52.887811Z","steps":["trace[1937079189] 'process raft request' (duration: 862.824873ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:29:52.887879Z","caller":"traceutil/trace.go:171","msg":"trace[1661579828] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"836.961776ms","start":"2026-07-17T14:29:52.050909Z","end":"2026-07-17T14:29:52.887871Z","steps":["trace[1661579828] 'process raft request' (duration: 836.394597ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:29:52.887863Z","caller":"traceutil/trace.go:171","msg":"trace[2139686653] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"711.00167ms","start":"2026-07-17T14:29:52.176836Z","end":"2026-07-17T14:29:52.887837Z","steps":["trace[2139686653] 'process raft request' (duration: 710.636928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:52.887918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:52.050894Z","time spent":"837.005337ms","remote":"127.0.0.1:37566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T14:29:52.887922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:52.080106Z","time spent":"807.594773ms","remote":"127.0.0.1:37566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T14:29:52.887936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:52.024157Z","time spent":"863.733893ms","remote":"127.0.0.1:37566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T14:29:52.887916Z","caller":"traceutil/trace.go:171","msg":"trace[1927463055] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:517; }","duration":"821.128497ms","start":"2026-07-17T14:29:52.066767Z","end":"2026-07-17T14:29:52.887896Z","steps":["trace[1927463055] 'read index received' (duration: 813.90532ms)","trace[1927463055] 'applied index is now lower than readState.Index' (duration: 7.221207ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:29:52.887965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:52.176799Z","time spent":"711.119754ms","remote":"127.0.0.1:37566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T14:29:52.887999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"821.229991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T14:29:52.887631Z","caller":"traceutil/trace.go:171","msg":"trace[1499231625] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"853.644372ms","start":"2026-07-17T14:29:52.033965Z","end":"2026-07-17T14:29:52.887609Z","steps":["trace[1499231625] 'process raft request' (duration: 853.183377ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:29:52.888024Z","caller":"traceutil/trace.go:171","msg":"trace[1286200858] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"821.287473ms","start":"2026-07-17T14:29:52.06673Z","end":"2026-07-17T14:29:52.888017Z","steps":["trace[1286200858] 'agreement among raft nodes before linearized reading' (duration: 821.20889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:52.88804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:52.066714Z","time spent":"821.322863ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T14:29:52.888049Z","caller":"traceutil/trace.go:171","msg":"trace[1547402618] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"856.301399ms","start":"2026-07-17T14:29:52.031736Z","end":"2026-07-17T14:29:52.888037Z","steps":["trace[1547402618] 'process raft request' (duration: 855.331028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:52.888048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:52.033955Z","time spent":"854.068756ms","remote":"127.0.0.1:37566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T14:29:52.888096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:52.031714Z","time spent":"856.360091ms","remote":"127.0.0.1:37566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T14:29:52.888128Z","caller":"traceutil/trace.go:171","msg":"trace[1897827420] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"853.991113ms","start":"2026-07-17T14:29:52.034127Z","end":"2026-07-17T14:29:52.888118Z","steps":["trace[1897827420] 'process raft request' (duration: 853.052622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:52.888167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:52.034122Z","time spent":"854.026385ms","remote":"127.0.0.1:37566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T14:29:52.887577Z","caller":"traceutil/trace.go:171","msg":"trace[1913457310] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"852.108102ms","start":"2026-07-17T14:29:52.035416Z","end":"2026-07-17T14:29:52.887524Z","steps":["trace[1913457310] 'process raft request' (duration: 851.799742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:52.888281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:52.035409Z","time spent":"852.845796ms","remote":"127.0.0.1:37566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T14:29:52.892857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.103974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-17T14:29:52.892919Z","caller":"traceutil/trace.go:171","msg":"trace[807642882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"813.193617ms","start":"2026-07-17T14:29:52.079711Z","end":"2026-07-17T14:29:52.892905Z","steps":["trace[807642882] 'agreement among raft nodes before linearized reading' (duration: 813.041742ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:52.892951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:52.079703Z","time spent":"813.239618ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T14:29:52.893718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.755914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:29:52.893903Z","caller":"traceutil/trace.go:171","msg":"trace[158516083] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:509; }","duration":"519.922371ms","start":"2026-07-17T14:29:52.373885Z","end":"2026-07-17T14:29:52.893807Z","steps":["trace[158516083] 'agreement among raft nodes before linearized reading' (duration: 519.753245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:52.89399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:52.373867Z","time spent":"520.114116ms","remote":"127.0.0.1:37524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T14:29:57.199439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.622485ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T14:29:57.199541Z","caller":"traceutil/trace.go:171","msg":"trace[180341624] linearizableReadLoop","detail":"{readStateIndex:613; appliedIndex:612; }","duration":"284.439248ms","start":"2026-07-17T14:29:56.915085Z","end":"2026-07-17T14:29:57.199524Z","steps":["trace[180341624] 'read index received' (duration: 49.142µs)","trace[180341624] 'applied index is now lower than readState.Index' (duration: 284.388956ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:29:57.199643Z","caller":"traceutil/trace.go:171","msg":"trace[664050012] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"335.21286ms","start":"2026-07-17T14:29:56.864419Z","end":"2026-07-17T14:29:57.199632Z","steps":["trace[664050012] 'process raft request' (duration: 28.178933ms)","trace[664050012] 'compare' (duration: 306.5089ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:29:57.199694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:56.864397Z","time spent":"335.270643ms","remote":"127.0.0.1:37838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T14:29:57.199899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.538256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T14:29:57.199929Z","caller":"traceutil/trace.go:171","msg":"trace[760836795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:589; }","duration":"268.604389ms","start":"2026-07-17T14:29:56.931315Z","end":"2026-07-17T14:29:57.199919Z","steps":["trace[760836795] 'agreement among raft nodes before linearized reading' (duration: 268.487585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:57.199949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.596489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-17T14:29:57.199975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.878632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T14:29:57.200031Z","caller":"traceutil/trace.go:171","msg":"trace[1041501461] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:589; }","duration":"268.714953ms","start":"2026-07-17T14:29:56.931291Z","end":"2026-07-17T14:29:57.200006Z","steps":["trace[1041501461] 'agreement among raft nodes before linearized reading' (duration: 268.507096ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:29:57.200048Z","caller":"traceutil/trace.go:171","msg":"trace[240618421] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:589; }","duration":"284.947464ms","start":"2026-07-17T14:29:56.915076Z","end":"2026-07-17T14:29:57.200023Z","steps":["trace[240618421] 'agreement among raft nodes before linearized reading' (duration: 284.716207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:59.043922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.957118ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T14:29:59.044163Z","caller":"traceutil/trace.go:171","msg":"trace[96396633] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"374.092085ms","start":"2026-07-17T14:29:58.670008Z","end":"2026-07-17T14:29:59.0441Z","steps":["trace[96396633] 'process raft request' (duration: 36.816086ms)","trace[96396633] 'compare' (duration: 335.45958ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:29:59.044283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:58.669977Z","time spent":"374.23929ms","remote":"127.0.0.1:37604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T14:29:59.675487Z","caller":"traceutil/trace.go:171","msg":"trace[1611044875] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"459.937066ms","start":"2026-07-17T14:29:59.215522Z","end":"2026-07-17T14:29:59.675459Z","steps":["trace[1611044875] 'process raft request' (duration: 459.893944ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:29:59.675542Z","caller":"traceutil/trace.go:171","msg":"trace[442592590] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"459.994737ms","start":"2026-07-17T14:29:59.215513Z","end":"2026-07-17T14:29:59.675507Z","steps":["trace[442592590] 'process raft request' (duration: 459.7796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:59.675679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:59.215505Z","time spent":"460.10199ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:29:59.675734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:59.215497Z","time spent":"460.152273ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T14:29:59.819509Z","caller":"traceutil/trace.go:171","msg":"trace[578502858] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"603.86131ms","start":"2026-07-17T14:29:59.215613Z","end":"2026-07-17T14:29:59.819474Z","steps":["trace[578502858] 'process raft request' (duration: 602.998572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:59.819754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:59.215588Z","time spent":"604.070487ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T14:29:59.820119Z","caller":"traceutil/trace.go:171","msg":"trace[350182142] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"231.3761ms","start":"2026-07-17T14:29:59.588728Z","end":"2026-07-17T14:29:59.820105Z","steps":["trace[350182142] 'process raft request' (duration: 230.571234ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:29:59.82012Z","caller":"traceutil/trace.go:171","msg":"trace[40579199] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:629; }","duration":"372.966887ms","start":"2026-07-17T14:29:59.447126Z","end":"2026-07-17T14:29:59.820093Z","steps":["trace[40579199] 'read index received' (duration: 228.344389ms)","trace[40579199] 'applied index is now lower than readState.Index' (duration: 144.621488ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:29:59.820322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.160383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T14:29:59.820445Z","caller":"traceutil/trace.go:171","msg":"trace[191726541] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"373.308658ms","start":"2026-07-17T14:29:59.447096Z","end":"2026-07-17T14:29:59.820405Z","steps":["trace[191726541] 'agreement among raft nodes before linearized reading' (duration: 373.090441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:59.820362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.817405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zzdvd\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-17T14:29:59.820517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:59.447081Z","time spent":"373.420872ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T14:29:59.820576Z","caller":"traceutil/trace.go:171","msg":"trace[1779180106] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zzdvd; range_end:; response_count:1; response_revision:606; }","duration":"360.029563ms","start":"2026-07-17T14:29:59.460474Z","end":"2026-07-17T14:29:59.820504Z","steps":["trace[1779180106] 'agreement among raft nodes before linearized reading' (duration: 359.752994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:29:59.820672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:29:59.460456Z","time spent":"360.196478ms","remote":"127.0.0.1:37704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-zzdvd\" "} {"level":"info","ts":"2026-07-17T14:30:00.023234Z","caller":"traceutil/trace.go:171","msg":"trace[593898578] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"197.491459ms","start":"2026-07-17T14:29:59.825717Z","end":"2026-07-17T14:30:00.023209Z","steps":["trace[593898578] 'process raft request' (duration: 138.397973ms)","trace[593898578] 'compare' (duration: 58.941271ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:30:00.037356Z","caller":"traceutil/trace.go:171","msg":"trace[19115460] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"202.940788ms","start":"2026-07-17T14:29:59.83439Z","end":"2026-07-17T14:30:00.03733Z","steps":["trace[19115460] 'process raft request' (duration: 202.754212ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:30:00.037628Z","caller":"traceutil/trace.go:171","msg":"trace[1655284885] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"165.101268ms","start":"2026-07-17T14:29:59.872513Z","end":"2026-07-17T14:30:00.037614Z","steps":["trace[1655284885] 'process raft request' (duration: 164.743226ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:01.739123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.365920895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:30:01.739196Z","caller":"traceutil/trace.go:171","msg":"trace[502609515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"1.366027409s","start":"2026-07-17T14:30:00.37315Z","end":"2026-07-17T14:30:01.739178Z","steps":["trace[502609515] 'range keys from in-memory index tree' (duration: 1.365848773s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:01.739225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:30:00.373135Z","time spent":"1.36608311s","remote":"127.0.0.1:37524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T14:30:01.739206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.236143ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:30:01.739299Z","caller":"traceutil/trace.go:171","msg":"trace[429482044] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:609; }","duration":"172.337456ms","start":"2026-07-17T14:30:01.566945Z","end":"2026-07-17T14:30:01.739283Z","steps":["trace[429482044] 'range keys from in-memory index tree' (duration: 172.221641ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:01.752239Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.306465687s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T14:30:01.753417Z","caller":"traceutil/trace.go:171","msg":"trace[1498800054] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.312699331s","start":"2026-07-17T14:30:00.440689Z","end":"2026-07-17T14:30:01.753389Z","steps":["trace[1498800054] 'process raft request' (duration: 1.312084791s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:01.753548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:30:00.440656Z","time spent":"1.312813255s","remote":"127.0.0.1:37604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T14:30:03.901845Z","caller":"traceutil/trace.go:171","msg":"trace[455247515] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"205.921586ms","start":"2026-07-17T14:30:03.695882Z","end":"2026-07-17T14:30:03.901803Z","steps":["trace[455247515] 'process raft request' (duration: 205.694789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:03.902094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.202448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T14:30:03.902186Z","caller":"traceutil/trace.go:171","msg":"trace[61244025] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:656; }","duration":"133.365804ms","start":"2026-07-17T14:30:03.768801Z","end":"2026-07-17T14:30:03.902166Z","steps":["trace[61244025] 'agreement among raft nodes before linearized reading' (duration: 133.049354ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:30:03.901719Z","caller":"traceutil/trace.go:171","msg":"trace[426622273] linearizableReadLoop","detail":"{readStateIndex:682; appliedIndex:681; }","duration":"132.818856ms","start":"2026-07-17T14:30:03.768884Z","end":"2026-07-17T14:30:03.901703Z","steps":["trace[426622273] 'read index received' (duration: 132.686782ms)","trace[426622273] 'applied index is now lower than readState.Index' (duration: 131.174µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:30:03.902602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.701927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T14:30:03.902639Z","caller":"traceutil/trace.go:171","msg":"trace[442593451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:656; }","duration":"130.802961ms","start":"2026-07-17T14:30:03.771825Z","end":"2026-07-17T14:30:03.902628Z","steps":["trace[442593451] 'agreement among raft nodes before linearized reading' (duration: 130.557783ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:30:06.811623Z","caller":"traceutil/trace.go:171","msg":"trace[496026580] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"190.155444ms","start":"2026-07-17T14:30:06.621444Z","end":"2026-07-17T14:30:06.8116Z","steps":["trace[496026580] 'process raft request' (duration: 188.093207ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:30:14.92378Z","caller":"traceutil/trace.go:171","msg":"trace[210029588] transaction","detail":"{read_only:false; response_revision:752; number_of_response:1; }","duration":"361.757419ms","start":"2026-07-17T14:30:14.561998Z","end":"2026-07-17T14:30:14.923756Z","steps":["trace[210029588] 'process raft request' (duration: 361.539022ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:14.923949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:30:14.561977Z","time spent":"361.903823ms","remote":"127.0.0.1:37704","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5032,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:30:18.403184Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10980514146934789735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T14:30:18.904111Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10980514146934789735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T14:30:19.075785Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.496159629s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T14:30:19.076335Z","caller":"traceutil/trace.go:171","msg":"trace[1750192191] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"1.496869932s","start":"2026-07-17T14:30:17.579447Z","end":"2026-07-17T14:30:19.076317Z","steps":["trace[1750192191] 'process raft request' (duration: 1.496650135s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:19.076555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:30:17.579422Z","time spent":"1.496976605s","remote":"127.0.0.1:37740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T14:30:19.084069Z","caller":"traceutil/trace.go:171","msg":"trace[82945875] linearizableReadLoop","detail":"{readStateIndex:803; appliedIndex:801; }","duration":"1.180922058s","start":"2026-07-17T14:30:17.903126Z","end":"2026-07-17T14:30:19.084048Z","steps":["trace[82945875] 'read index received' (duration: 1.172915439s)","trace[82945875] 'applied index is now lower than readState.Index' (duration: 8.005549ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:30:19.084241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.181092734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T14:30:19.084272Z","caller":"traceutil/trace.go:171","msg":"trace[960381203] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:772; }","duration":"1.181184696s","start":"2026-07-17T14:30:17.903079Z","end":"2026-07-17T14:30:19.084264Z","steps":["trace[960381203] 'agreement among raft nodes before linearized reading' (duration: 1.181089743s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:19.0843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:30:17.90306Z","time spent":"1.181233038s","remote":"127.0.0.1:37952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-07-17T14:30:19.084533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107425035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T14:30:19.084559Z","caller":"traceutil/trace.go:171","msg":"trace[749739141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:772; }","duration":"1.107485437s","start":"2026-07-17T14:30:17.977068Z","end":"2026-07-17T14:30:19.084553Z","steps":["trace[749739141] 'agreement among raft nodes before linearized reading' (duration: 1.107394194s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:19.084577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:30:17.97705Z","time spent":"1.107523288s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T14:30:19.084672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"712.068677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:30:19.084743Z","caller":"traceutil/trace.go:171","msg":"trace[407069635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:772; }","duration":"712.193501ms","start":"2026-07-17T14:30:18.372533Z","end":"2026-07-17T14:30:19.084726Z","steps":["trace[407069635] 'agreement among raft nodes before linearized reading' (duration: 712.052237ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:19.084789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:30:18.372515Z","time spent":"712.264683ms","remote":"127.0.0.1:37524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T14:30:19.08485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.106486774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T14:30:19.084881Z","caller":"traceutil/trace.go:171","msg":"trace[1930095860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:772; }","duration":"1.106542696s","start":"2026-07-17T14:30:17.978326Z","end":"2026-07-17T14:30:19.084868Z","steps":["trace[1930095860] 'agreement among raft nodes before linearized reading' (duration: 1.106478544s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:19.084906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:30:17.97831Z","time spent":"1.106591478s","remote":"127.0.0.1:37768","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-07-17T14:30:19.085027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"990.596027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-17T14:30:19.085167Z","caller":"traceutil/trace.go:171","msg":"trace[150225616] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:772; }","duration":"990.784823ms","start":"2026-07-17T14:30:18.094365Z","end":"2026-07-17T14:30:19.08515Z","steps":["trace[150225616] 'agreement among raft nodes before linearized reading' (duration: 990.437272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:19.085229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:30:18.094344Z","time spent":"990.876206ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T14:30:19.085642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.103820348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T14:30:19.08578Z","caller":"traceutil/trace.go:171","msg":"trace[102532225] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:772; }","duration":"1.104110607s","start":"2026-07-17T14:30:17.981656Z","end":"2026-07-17T14:30:19.085766Z","steps":["trace[102532225] 'agreement among raft nodes before linearized reading' (duration: 1.10297689s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:19.086018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:30:17.981642Z","time spent":"1.104365596s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-17T14:30:47.996026Z","caller":"traceutil/trace.go:171","msg":"trace[1628973743] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"149.638082ms","start":"2026-07-17T14:30:47.84636Z","end":"2026-07-17T14:30:47.995998Z","steps":["trace[1628973743] 'process raft request' (duration: 149.439216ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:30:53.487073Z","caller":"traceutil/trace.go:171","msg":"trace[394683246] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"219.641381ms","start":"2026-07-17T14:30:53.267407Z","end":"2026-07-17T14:30:53.487049Z","steps":["trace[394683246] 'read index received' (duration: 219.475456ms)","trace[394683246] 'applied index is now lower than readState.Index' (duration: 164.885µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:30:53.487235Z","caller":"traceutil/trace.go:171","msg":"trace[1626163452] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"241.455009ms","start":"2026-07-17T14:30:53.245765Z","end":"2026-07-17T14:30:53.48722Z","steps":["trace[1626163452] 'process raft request' (duration: 241.125158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:53.487472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.054535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T14:30:53.487514Z","caller":"traceutil/trace.go:171","msg":"trace[1070697165] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1002; }","duration":"220.106887ms","start":"2026-07-17T14:30:53.267397Z","end":"2026-07-17T14:30:53.487504Z","steps":["trace[1070697165] 'agreement among raft nodes before linearized reading' (duration: 219.972853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:53.487634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.206843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:30:53.48766Z","caller":"traceutil/trace.go:171","msg":"trace[218588584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"114.267165ms","start":"2026-07-17T14:30:53.373385Z","end":"2026-07-17T14:30:53.487652Z","steps":["trace[218588584] 'agreement among raft nodes before linearized reading' (duration: 114.228884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:53.487682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.667063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-17T14:30:53.487731Z","caller":"traceutil/trace.go:171","msg":"trace[1890762199] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"211.746896ms","start":"2026-07-17T14:30:53.275971Z","end":"2026-07-17T14:30:53.487718Z","steps":["trace[1890762199] 'agreement among raft nodes before linearized reading' (duration: 211.648443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:53.48774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.935794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-17T14:30:53.48774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.801235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.139\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T14:30:53.487804Z","caller":"traceutil/trace.go:171","msg":"trace[363345535] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"159.037566ms","start":"2026-07-17T14:30:53.328749Z","end":"2026-07-17T14:30:53.487787Z","steps":["trace[363345535] 'agreement among raft nodes before linearized reading' (duration: 158.85296ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:30:53.487813Z","caller":"traceutil/trace.go:171","msg":"trace[1058657349] range","detail":"{range_begin:/registry/masterleases/199.19.213.139; range_end:; response_count:1; response_revision:1002; }","duration":"115.916269ms","start":"2026-07-17T14:30:53.37188Z","end":"2026-07-17T14:30:53.487796Z","steps":["trace[1058657349] 'agreement among raft nodes before linearized reading' (duration: 115.704042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:53.487975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.828212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T14:30:53.488026Z","caller":"traceutil/trace.go:171","msg":"trace[1768708948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1002; }","duration":"218.248017ms","start":"2026-07-17T14:30:53.269764Z","end":"2026-07-17T14:30:53.488012Z","steps":["trace[1768708948] 'agreement among raft nodes before linearized reading' (duration: 217.696789ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:30:54.220661Z","caller":"traceutil/trace.go:171","msg":"trace[681393571] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"480.987764ms","start":"2026-07-17T14:30:53.739643Z","end":"2026-07-17T14:30:54.220631Z","steps":["trace[681393571] 'process raft request' (duration: 480.791478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:54.220847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:30:53.739623Z","time spent":"481.131989ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T14:30:54.919691Z","caller":"traceutil/trace.go:171","msg":"trace[1906503312] transaction","detail":"{read_only:false; response_revision:1009; number_of_response:1; }","duration":"1.121131679s","start":"2026-07-17T14:30:53.798536Z","end":"2026-07-17T14:30:54.919668Z","steps":["trace[1906503312] 'process raft request' (duration: 1.038309954s)","trace[1906503312] 'compare' (duration: 82.518866ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:30:54.919833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:30:53.79851Z","time spent":"1.121238833s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:30:55.846529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.921414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T14:30:55.846615Z","caller":"traceutil/trace.go:171","msg":"trace[135904227] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1009; }","duration":"292.05853ms","start":"2026-07-17T14:30:55.554537Z","end":"2026-07-17T14:30:55.846596Z","steps":["trace[135904227] 'range keys from in-memory index tree' (duration: 291.706548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:55.846669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.786143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-17T14:30:55.846735Z","caller":"traceutil/trace.go:171","msg":"trace[68856196] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1009; }","duration":"354.900937ms","start":"2026-07-17T14:30:55.491819Z","end":"2026-07-17T14:30:55.84672Z","steps":["trace[68856196] 'range keys from in-memory index tree' (duration: 354.588847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:55.846772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:30:55.491795Z","time spent":"354.971199ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T14:30:55.846966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.346197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:30:55.847858Z","caller":"traceutil/trace.go:171","msg":"trace[1718227127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1009; }","duration":"474.270777ms","start":"2026-07-17T14:30:55.373569Z","end":"2026-07-17T14:30:55.84784Z","steps":["trace[1718227127] 'range keys from in-memory index tree' (duration: 473.281425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:55.847969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:30:55.373551Z","time spent":"474.407652ms","remote":"127.0.0.1:37524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T14:30:55.847044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.844465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-17T14:30:55.848202Z","caller":"traceutil/trace.go:171","msg":"trace[797127361] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1009; }","duration":"294.041553ms","start":"2026-07-17T14:30:55.554151Z","end":"2026-07-17T14:30:55.848193Z","steps":["trace[797127361] 'range keys from in-memory index tree' (duration: 292.727681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:55.847092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.423178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-17T14:30:55.84861Z","caller":"traceutil/trace.go:171","msg":"trace[653950893] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1009; }","duration":"292.948068ms","start":"2026-07-17T14:30:55.555638Z","end":"2026-07-17T14:30:55.848587Z","steps":["trace[653950893] 'range keys from in-memory index tree' (duration: 291.326265ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:30:55.847084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.480553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T14:30:55.848793Z","caller":"traceutil/trace.go:171","msg":"trace[346207370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1009; }","duration":"294.22733ms","start":"2026-07-17T14:30:55.554556Z","end":"2026-07-17T14:30:55.848783Z","steps":["trace[346207370] 'range keys from in-memory index tree' (duration: 292.311577ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:30:56.086572Z","caller":"traceutil/trace.go:171","msg":"trace[997744208] transaction","detail":"{read_only:false; response_revision:1010; number_of_response:1; }","duration":"235.503515ms","start":"2026-07-17T14:30:55.851044Z","end":"2026-07-17T14:30:56.086548Z","steps":["trace[997744208] 'process raft request' (duration: 235.35286ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:30:56.087764Z","caller":"traceutil/trace.go:171","msg":"trace[1803269550] transaction","detail":"{read_only:false; response_revision:1014; number_of_response:1; }","duration":"235.02403ms","start":"2026-07-17T14:30:55.852721Z","end":"2026-07-17T14:30:56.087745Z","steps":["trace[1803269550] 'process raft request' (duration: 234.994869ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:30:56.087891Z","caller":"traceutil/trace.go:171","msg":"trace[1647754585] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"236.58515ms","start":"2026-07-17T14:30:55.85128Z","end":"2026-07-17T14:30:56.087865Z","steps":["trace[1647754585] 'process raft request' (duration: 235.630729ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:30:56.087949Z","caller":"traceutil/trace.go:171","msg":"trace[2025380952] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"235.710813ms","start":"2026-07-17T14:30:55.852232Z","end":"2026-07-17T14:30:56.087942Z","steps":["trace[2025380952] 'process raft request' (duration: 235.462345ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:30:56.087894Z","caller":"traceutil/trace.go:171","msg":"trace[217817441] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"235.770314ms","start":"2026-07-17T14:30:55.852106Z","end":"2026-07-17T14:30:56.087876Z","steps":["trace[217817441] 'process raft request' (duration: 235.563098ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:31:16.294015Z","caller":"traceutil/trace.go:171","msg":"trace[1315692403] linearizableReadLoop","detail":"{readStateIndex:1121; appliedIndex:1119; }","duration":"106.340669ms","start":"2026-07-17T14:31:16.187655Z","end":"2026-07-17T14:31:16.293996Z","steps":["trace[1315692403] 'read index received' (duration: 90.398876ms)","trace[1315692403] 'applied index is now lower than readState.Index' (duration: 15.940803ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:31:16.294113Z","caller":"traceutil/trace.go:171","msg":"trace[574081296] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"106.501794ms","start":"2026-07-17T14:31:16.187603Z","end":"2026-07-17T14:31:16.294105Z","steps":["trace[574081296] 'process raft request' (duration: 106.282407ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:16.294367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.38921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-07-17T14:31:16.294438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.767502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-17T14:31:16.294451Z","caller":"traceutil/trace.go:171","msg":"trace[1977193705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1077; }","duration":"106.550215ms","start":"2026-07-17T14:31:16.187871Z","end":"2026-07-17T14:31:16.294421Z","steps":["trace[1977193705] 'agreement among raft nodes before linearized reading' (duration: 106.329718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:16.294518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.601987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T14:31:16.294544Z","caller":"traceutil/trace.go:171","msg":"trace[250471586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1077; }","duration":"106.655729ms","start":"2026-07-17T14:31:16.187884Z","end":"2026-07-17T14:31:16.294539Z","steps":["trace[250471586] 'agreement among raft nodes before linearized reading' (duration: 106.598427ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:31:16.294463Z","caller":"traceutil/trace.go:171","msg":"trace[547379545] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1077; }","duration":"106.841595ms","start":"2026-07-17T14:31:16.187615Z","end":"2026-07-17T14:31:16.294456Z","steps":["trace[547379545] 'agreement among raft nodes before linearized reading' (duration: 106.732102ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:17.643409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:16.606865Z","time spent":"1.036539343s","remote":"127.0.0.1:35382","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-17T14:31:17.64399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19197313s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T14:31:17.644083Z","caller":"traceutil/trace.go:171","msg":"trace[1627441791] linearizableReadLoop","detail":"{readStateIndex:1125; appliedIndex:1124; }","duration":"1.27097158s","start":"2026-07-17T14:31:16.373097Z","end":"2026-07-17T14:31:17.644068Z","steps":["trace[1627441791] 'read index received' (duration: 78.825474ms)","trace[1627441791] 'applied index is now lower than readState.Index' (duration: 1.192144536s)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:31:17.644155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271062553s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:31:17.644179Z","caller":"traceutil/trace.go:171","msg":"trace[757401876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1081; }","duration":"1.271118894s","start":"2026-07-17T14:31:16.373052Z","end":"2026-07-17T14:31:17.644171Z","steps":["trace[757401876] 'agreement among raft nodes before linearized reading' (duration: 1.271056832s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:17.644209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:16.373034Z","time spent":"1.271167066s","remote":"127.0.0.1:37524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T14:31:17.644412Z","caller":"traceutil/trace.go:171","msg":"trace[1884232973] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"1.275870388s","start":"2026-07-17T14:31:16.368524Z","end":"2026-07-17T14:31:17.644394Z","steps":["trace[1884232973] 'process raft request' (duration: 83.420202ms)","trace[1884232973] 'compare' (duration: 1.191408732s)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:31:17.644514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:16.3685Z","time spent":"1.27595706s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:31:18.937613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.406311ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-17T14:31:19.780463Z","caller":"traceutil/trace.go:171","msg":"trace[1530667290] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"130.700562ms","start":"2026-07-17T14:31:19.649746Z","end":"2026-07-17T14:31:19.780446Z","steps":["trace[1530667290] 'process raft request' (duration: 130.544167ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:31:19.780952Z","caller":"traceutil/trace.go:171","msg":"trace[1069736492] linearizableReadLoop","detail":"{readStateIndex:1133; appliedIndex:1133; }","duration":"104.251791ms","start":"2026-07-17T14:31:19.676639Z","end":"2026-07-17T14:31:19.780891Z","steps":["trace[1069736492] 'read index received' (duration: 104.242281ms)","trace[1069736492] 'applied index is now lower than readState.Index' (duration: 7.35µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:31:19.781021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.376446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:31:19.781105Z","caller":"traceutil/trace.go:171","msg":"trace[1366019877] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1088; }","duration":"104.494759ms","start":"2026-07-17T14:31:19.67659Z","end":"2026-07-17T14:31:19.781085Z","steps":["trace[1366019877] 'agreement among raft nodes before linearized reading' (duration: 104.395356ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:31:22.750059Z","caller":"traceutil/trace.go:171","msg":"trace[1485068657] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"303.228369ms","start":"2026-07-17T14:31:22.446799Z","end":"2026-07-17T14:31:22.750027Z","steps":["trace[1485068657] 'process raft request' (duration: 303.066833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:22.750242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:22.446777Z","time spent":"303.380873ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:31:22.750337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.953677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T14:31:22.750393Z","caller":"traceutil/trace.go:171","msg":"trace[574408023] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1097; }","duration":"288.04733ms","start":"2026-07-17T14:31:22.462333Z","end":"2026-07-17T14:31:22.75038Z","steps":["trace[574408023] 'agreement among raft nodes before linearized reading' (duration: 287.842323ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:31:22.750638Z","caller":"traceutil/trace.go:171","msg":"trace[2146958294] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"287.623947ms","start":"2026-07-17T14:31:22.462372Z","end":"2026-07-17T14:31:22.749996Z","steps":["trace[2146958294] 'read index received' (duration: 287.445051ms)","trace[2146958294] 'applied index is now lower than readState.Index' (duration: 178.026µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:31:22.750867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.103209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T14:31:22.750919Z","caller":"traceutil/trace.go:171","msg":"trace[119170016] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1097; }","duration":"282.213883ms","start":"2026-07-17T14:31:22.468692Z","end":"2026-07-17T14:31:22.750906Z","steps":["trace[119170016] 'agreement among raft nodes before linearized reading' (duration: 282.082079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:22.751028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.643119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T14:31:22.75116Z","caller":"traceutil/trace.go:171","msg":"trace[986895151] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1097; }","duration":"148.820505ms","start":"2026-07-17T14:31:22.602328Z","end":"2026-07-17T14:31:22.751148Z","steps":["trace[986895151] 'agreement among raft nodes before linearized reading' (duration: 148.599468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:22.957257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.627631ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T14:31:22.957441Z","caller":"traceutil/trace.go:171","msg":"trace[2124457716] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"203.099829ms","start":"2026-07-17T14:31:22.754326Z","end":"2026-07-17T14:31:22.957426Z","steps":["trace[2124457716] 'process raft request' (duration: 203.040577ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:31:22.957446Z","caller":"traceutil/trace.go:171","msg":"trace[873997897] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"203.311906ms","start":"2026-07-17T14:31:22.754116Z","end":"2026-07-17T14:31:22.957428Z","steps":["trace[873997897] 'process raft request' (duration: 53.433128ms)","trace[873997897] 'compare' (duration: 149.458935ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:31:36.145514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.165051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T14:31:36.145632Z","caller":"traceutil/trace.go:171","msg":"trace[176719600] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"272.7936ms","start":"2026-07-17T14:31:35.872818Z","end":"2026-07-17T14:31:36.145612Z","steps":["trace[176719600] 'process raft request' (duration: 83.442923ms)","trace[176719600] 'compare' (duration: 189.051478ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:31:36.396262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.135636ms","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-07-17T14:31:36.396329Z","caller":"traceutil/trace.go:171","msg":"trace[1517581694] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1192; }","duration":"145.25779ms","start":"2026-07-17T14:31:36.251056Z","end":"2026-07-17T14:31:36.396314Z","steps":["trace[1517581694] 'count revisions from in-memory index tree' (duration: 145.046032ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:31:36.731727Z","caller":"traceutil/trace.go:171","msg":"trace[664674477] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1242; }","duration":"302.723012ms","start":"2026-07-17T14:31:36.428986Z","end":"2026-07-17T14:31:36.731709Z","steps":["trace[664674477] 'read index received' (duration: 302.595438ms)","trace[664674477] 'applied index is now lower than readState.Index' (duration: 126.944µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:31:36.731819Z","caller":"traceutil/trace.go:171","msg":"trace[858309880] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"320.577446ms","start":"2026-07-17T14:31:36.411233Z","end":"2026-07-17T14:31:36.731811Z","steps":["trace[858309880] 'process raft request' (duration: 320.355829ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:36.731895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:36.411217Z","time spent":"320.621677ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:31:36.732069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.072263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-17T14:31:36.732128Z","caller":"traceutil/trace.go:171","msg":"trace[1021770230] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1193; }","duration":"303.161476ms","start":"2026-07-17T14:31:36.428954Z","end":"2026-07-17T14:31:36.732116Z","steps":["trace[1021770230] 'agreement among raft nodes before linearized reading' (duration: 303.005871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:36.732177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:36.42894Z","time spent":"303.228738ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T14:31:36.732054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.984249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:31:36.732332Z","caller":"traceutil/trace.go:171","msg":"trace[964149702] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1193; }","duration":"130.287289ms","start":"2026-07-17T14:31:36.60203Z","end":"2026-07-17T14:31:36.732317Z","steps":["trace[964149702] 'agreement among raft nodes before linearized reading' (duration: 129.935747ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:38.870826Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10980514146934790746,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T14:31:39.371141Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10980514146934790746,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T14:31:39.871737Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10980514146934790746,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T14:31:40.342896Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.192957338s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T14:31:40.343302Z","caller":"traceutil/trace.go:171","msg":"trace[1943025616] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"2.193425154s","start":"2026-07-17T14:31:38.149853Z","end":"2026-07-17T14:31:40.343278Z","steps":["trace[1943025616] 'process raft request' (duration: 2.193259569s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:40.343427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:38.14983Z","time spent":"2.193536217s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:31:40.370465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000117449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-17T14:31:40.370536Z","caller":"traceutil/trace.go:171","msg":"trace[592741558] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000206283s","start":"2026-07-17T14:31:38.370313Z","end":"2026-07-17T14:31:40.37052Z","steps":["trace[592741558] 'agreement among raft nodes before linearized reading' (duration: 2.00011289s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:40.370569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:38.370232Z","time spent":"2.000331426s","remote":"127.0.0.1:37528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T14:31:40.47061Z","caller":"traceutil/trace.go:171","msg":"trace[1211550041] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"1.601604098s","start":"2026-07-17T14:31:38.868987Z","end":"2026-07-17T14:31:40.470591Z","steps":["trace[1211550041] 'process raft request' (duration: 1.601293938s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:40.470717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:38.868963Z","time spent":"1.601700981s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T14:31:40.470994Z","caller":"traceutil/trace.go:171","msg":"trace[28006424] linearizableReadLoop","detail":"{readStateIndex:1250; appliedIndex:1247; }","duration":"2.100647351s","start":"2026-07-17T14:31:38.370338Z","end":"2026-07-17T14:31:40.470985Z","steps":["trace[28006424] 'read index received' (duration: 1.972816112s)","trace[28006424] 'applied index is now lower than readState.Index' (duration: 127.828019ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:31:40.47115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.736029179s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T14:31:40.471185Z","caller":"traceutil/trace.go:171","msg":"trace[976320367] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1200; }","duration":"1.736097942s","start":"2026-07-17T14:31:38.73508Z","end":"2026-07-17T14:31:40.471178Z","steps":["trace[976320367] 'agreement among raft nodes before linearized reading' (duration: 1.735979488s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:40.471207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:38.735063Z","time spent":"1.736137933s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T14:31:40.471396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272609441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:31:40.471424Z","caller":"traceutil/trace.go:171","msg":"trace[308066960] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1200; }","duration":"1.272665983s","start":"2026-07-17T14:31:39.19875Z","end":"2026-07-17T14:31:40.471416Z","steps":["trace[308066960] 'agreement among raft nodes before linearized reading' (duration: 1.272627422s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:40.471442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:39.198735Z","time spent":"1.272702084s","remote":"127.0.0.1:51758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-07-17T14:31:40.471584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658601811s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T14:31:40.471638Z","caller":"traceutil/trace.go:171","msg":"trace[1257831265] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1200; }","duration":"1.658712324s","start":"2026-07-17T14:31:38.812911Z","end":"2026-07-17T14:31:40.471624Z","steps":["trace[1257831265] 'agreement among raft nodes before linearized reading' (duration: 1.658552169s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:40.471681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.727236437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-17T14:31:40.471681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:38.812884Z","time spent":"1.658780816s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T14:31:40.471708Z","caller":"traceutil/trace.go:171","msg":"trace[480255308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1200; }","duration":"1.727292099s","start":"2026-07-17T14:31:38.744411Z","end":"2026-07-17T14:31:40.471703Z","steps":["trace[480255308] 'agreement among raft nodes before linearized reading' (duration: 1.727219046s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:40.471726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:38.744396Z","time spent":"1.72732583s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T14:31:40.471719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.444277371s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-17T14:31:40.471762Z","caller":"traceutil/trace.go:171","msg":"trace[60980897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1200; }","duration":"1.444350663s","start":"2026-07-17T14:31:39.027397Z","end":"2026-07-17T14:31:40.471748Z","steps":["trace[60980897] 'agreement among raft nodes before linearized reading' (duration: 1.444229719s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:40.471794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:39.027383Z","time spent":"1.444403734s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T14:31:40.471932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439455315s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T14:31:40.472008Z","caller":"traceutil/trace.go:171","msg":"trace[2034215296] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1200; }","duration":"1.439550878s","start":"2026-07-17T14:31:39.032438Z","end":"2026-07-17T14:31:40.471988Z","steps":["trace[2034215296] 'agreement among raft nodes before linearized reading' (duration: 1.439333821s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:40.472051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:39.032426Z","time spent":"1.43961673s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T14:31:40.472537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.465352708s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T14:31:40.472594Z","caller":"traceutil/trace.go:171","msg":"trace[2134305508] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1200; }","duration":"1.466235686s","start":"2026-07-17T14:31:39.006341Z","end":"2026-07-17T14:31:40.472577Z","steps":["trace[2134305508] 'agreement among raft nodes before linearized reading' (duration: 1.465335847s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:40.472629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:39.006313Z","time spent":"1.466306348s","remote":"127.0.0.1:55030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-07-17T14:31:54.7054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.505019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T14:31:54.705493Z","caller":"traceutil/trace.go:171","msg":"trace[1362228677] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"135.858918ms","start":"2026-07-17T14:31:54.569617Z","end":"2026-07-17T14:31:54.705476Z","steps":["trace[1362228677] 'process raft request' (duration: 31.198863ms)","trace[1362228677] 'compare' (duration: 104.399306ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:31:58.96473Z","caller":"traceutil/trace.go:171","msg":"trace[1196521482] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1339; }","duration":"416.128478ms","start":"2026-07-17T14:31:58.548578Z","end":"2026-07-17T14:31:58.964706Z","steps":["trace[1196521482] 'read index received' (duration: 416.024194ms)","trace[1196521482] 'applied index is now lower than readState.Index' (duration: 103.204µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:31:58.964851Z","caller":"traceutil/trace.go:171","msg":"trace[1268029520] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"524.604555ms","start":"2026-07-17T14:31:58.440237Z","end":"2026-07-17T14:31:58.964841Z","steps":["trace[1268029520] 'process raft request' (duration: 524.313576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:58.965114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.514571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T14:31:58.965185Z","caller":"traceutil/trace.go:171","msg":"trace[1616737734] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1286; }","duration":"416.636054ms","start":"2026-07-17T14:31:58.548529Z","end":"2026-07-17T14:31:58.965165Z","steps":["trace[1616737734] 'agreement among raft nodes before linearized reading' (duration: 416.384806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:58.965226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:58.548516Z","time spent":"416.702416ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T14:31:58.965324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:58.44022Z","time spent":"525.017059ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:31:59.465238Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10980514146934790934,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T14:31:59.85712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.335353ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-17T14:31:59.857398Z","caller":"traceutil/trace.go:171","msg":"trace[2138669518] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1340; }","duration":"892.565624ms","start":"2026-07-17T14:31:58.96481Z","end":"2026-07-17T14:31:59.857376Z","steps":["trace[2138669518] 'read index received' (duration: 671.973403ms)","trace[2138669518] 'applied index is now lower than readState.Index' (duration: 220.590741ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:31:59.85751Z","caller":"traceutil/trace.go:171","msg":"trace[1539495429] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"888.321138ms","start":"2026-07-17T14:31:58.96915Z","end":"2026-07-17T14:31:59.857471Z","steps":["trace[1539495429] 'process raft request' (duration: 888.086121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:31:59.857692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:58.969123Z","time spent":"888.501053ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:31:59.918175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.357942806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T14:31:59.918251Z","caller":"traceutil/trace.go:171","msg":"trace[597529707] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1287; }","duration":"1.35805746s","start":"2026-07-17T14:31:58.560175Z","end":"2026-07-17T14:31:59.918232Z","steps":["trace[597529707] 'agreement among raft nodes before linearized reading' (duration: 1.297341738s)","trace[597529707] 'range keys from in-memory index tree' (duration: 60.535826ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:31:59.918283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:58.560159Z","time spent":"1.358116961s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T14:31:59.918332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187231237s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-17T14:31:59.918401Z","caller":"traceutil/trace.go:171","msg":"trace[1057430032] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1287; }","duration":"1.18734002s","start":"2026-07-17T14:31:58.73104Z","end":"2026-07-17T14:31:59.91838Z","steps":["trace[1057430032] 'agreement among raft nodes before linearized reading' (duration: 1.12635749s)","trace[1057430032] 'range keys from in-memory index tree' (duration: 60.796424ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:31:59.918431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:58.731022Z","time spent":"1.187402703s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T14:31:59.918451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346180448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T14:31:59.91854Z","caller":"traceutil/trace.go:171","msg":"trace[1467759707] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1287; }","duration":"1.346270861s","start":"2026-07-17T14:31:58.572243Z","end":"2026-07-17T14:31:59.918514Z","steps":["trace[1467759707] 'agreement among raft nodes before linearized reading' (duration: 1.285247009s)","trace[1467759707] 'range keys from in-memory index tree' (duration: 60.795965ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:31:59.918565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.346462916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T14:31:59.918587Z","caller":"traceutil/trace.go:171","msg":"trace[270295987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1287; }","duration":"1.346501728s","start":"2026-07-17T14:31:58.572078Z","end":"2026-07-17T14:31:59.918579Z","steps":["trace[270295987] 'agreement among raft nodes before linearized reading' (duration: 1.285429844s)","trace[270295987] 'range keys from in-memory index tree' (duration: 61.010141ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:31:59.91859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:58.572223Z","time spent":"1.346355663s","remote":"127.0.0.1:37768","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-07-17T14:31:59.918607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:58.572066Z","time spent":"1.346537049s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T14:31:59.918468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.370451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:31:59.918665Z","caller":"traceutil/trace.go:171","msg":"trace[1651436317] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1287; }","duration":"544.598128ms","start":"2026-07-17T14:31:59.37405Z","end":"2026-07-17T14:31:59.918649Z","steps":["trace[1651436317] 'agreement among raft nodes before linearized reading' (duration: 483.486773ms)","trace[1651436317] 'range keys from in-memory index tree' (duration: 60.879267ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:31:59.918727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:59.374031Z","time spent":"544.68831ms","remote":"127.0.0.1:37524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T14:32:00.0412Z","caller":"traceutil/trace.go:171","msg":"trace[1297899965] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"118.071726ms","start":"2026-07-17T14:31:59.923099Z","end":"2026-07-17T14:32:00.04117Z","steps":["trace[1297899965] 'process raft request' (duration: 117.87893ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:32:00.228159Z","caller":"traceutil/trace.go:171","msg":"trace[1215654338] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"303.256709ms","start":"2026-07-17T14:31:59.924885Z","end":"2026-07-17T14:32:00.228142Z","steps":["trace[1215654338] 'process raft request' (duration: 303.227998ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:32:00.228227Z","caller":"traceutil/trace.go:171","msg":"trace[522364758] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"304.034104ms","start":"2026-07-17T14:31:59.924157Z","end":"2026-07-17T14:32:00.228191Z","steps":["trace[522364758] 'process raft request' (duration: 299.52558ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:00.228279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:59.924876Z","time spent":"303.342782ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T14:32:00.22826Z","caller":"traceutil/trace.go:171","msg":"trace[483353308] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"303.863828ms","start":"2026-07-17T14:31:59.924353Z","end":"2026-07-17T14:32:00.228217Z","steps":["trace[483353308] 'process raft request' (duration: 303.727934ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:00.228388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:59.924134Z","time spent":"304.175389ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:32:00.228412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:31:59.924335Z","time spent":"304.004113ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T14:32:01.287344Z","caller":"traceutil/trace.go:171","msg":"trace[1698966956] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"574.982594ms","start":"2026-07-17T14:32:00.71234Z","end":"2026-07-17T14:32:01.287323Z","steps":["trace[1698966956] 'process raft request' (duration: 574.84754ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:01.287472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:00.712321Z","time spent":"575.087657ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T14:32:01.29435Z","caller":"traceutil/trace.go:171","msg":"trace[371326543] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"323.338964ms","start":"2026-07-17T14:32:00.97098Z","end":"2026-07-17T14:32:01.294319Z","steps":["trace[371326543] 'process raft request' (duration: 323.152118ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:01.294499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:00.970963Z","time spent":"323.459028ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:32:02.673032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.633865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T14:32:02.672995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.491951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-17T14:32:02.673098Z","caller":"traceutil/trace.go:171","msg":"trace[1686864285] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1293; }","duration":"942.727187ms","start":"2026-07-17T14:32:01.730352Z","end":"2026-07-17T14:32:02.673079Z","steps":["trace[1686864285] 'count revisions from in-memory index tree' (duration: 942.570952ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:32:02.673131Z","caller":"traceutil/trace.go:171","msg":"trace[118280884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1293; }","duration":"440.670137ms","start":"2026-07-17T14:32:02.232447Z","end":"2026-07-17T14:32:02.673118Z","steps":["trace[118280884] 'range keys from in-memory index tree' (duration: 440.356247ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:02.673139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:01.730333Z","time spent":"942.796219ms","remote":"127.0.0.1:51726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-07-17T14:32:02.673164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:02.232437Z","time spent":"440.718898ms","remote":"127.0.0.1:37768","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-07-17T14:32:02.673161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.608175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T14:32:02.673247Z","caller":"traceutil/trace.go:171","msg":"trace[1601794257] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1293; }","duration":"440.74783ms","start":"2026-07-17T14:32:02.232482Z","end":"2026-07-17T14:32:02.67323Z","steps":["trace[1601794257] 'range keys from in-memory index tree' (duration: 440.462171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:02.673289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:02.232458Z","time spent":"440.822462ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T14:32:02.673345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.356737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T14:32:02.673376Z","caller":"traceutil/trace.go:171","msg":"trace[49006575] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1293; }","duration":"887.407509ms","start":"2026-07-17T14:32:01.785959Z","end":"2026-07-17T14:32:02.673367Z","steps":["trace[49006575] 'count revisions from in-memory index tree' (duration: 887.308576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:02.673408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:01.785946Z","time spent":"887.45442ms","remote":"127.0.0.1:37852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":17,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-07-17T14:32:02.673379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299671602s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:32:02.673467Z","caller":"traceutil/trace.go:171","msg":"trace[159842683] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1293; }","duration":"1.299813427s","start":"2026-07-17T14:32:01.373627Z","end":"2026-07-17T14:32:02.673441Z","steps":["trace[159842683] 'range keys from in-memory index tree' (duration: 1.29959876s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:02.673518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:01.373606Z","time spent":"1.299900119s","remote":"127.0.0.1:37524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T14:32:02.673727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.310924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T14:32:02.673769Z","caller":"traceutil/trace.go:171","msg":"trace[906422050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1293; }","duration":"812.388067ms","start":"2026-07-17T14:32:01.86137Z","end":"2026-07-17T14:32:02.673758Z","steps":["trace[906422050] 'range keys from in-memory index tree' (duration: 812.100338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:02.6738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:01.861352Z","time spent":"812.440789ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T14:32:02.673979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.393513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-17T14:32:02.674014Z","caller":"traceutil/trace.go:171","msg":"trace[1023881078] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1293; }","duration":"717.467456ms","start":"2026-07-17T14:32:01.956539Z","end":"2026-07-17T14:32:02.674006Z","steps":["trace[1023881078] 'range keys from in-memory index tree' (duration: 717.279089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:02.674034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:01.956518Z","time spent":"717.510957ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-17T14:32:02.674101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093788083s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:32:02.674194Z","caller":"traceutil/trace.go:171","msg":"trace[1649874153] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1293; }","duration":"1.093881856s","start":"2026-07-17T14:32:01.580299Z","end":"2026-07-17T14:32:02.674181Z","steps":["trace[1649874153] 'range keys from in-memory index tree' (duration: 1.093777283s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:02.674191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.495235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T14:32:02.674237Z","caller":"traceutil/trace.go:171","msg":"trace[1911260191] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1293; }","duration":"544.581698ms","start":"2026-07-17T14:32:02.129642Z","end":"2026-07-17T14:32:02.674224Z","steps":["trace[1911260191] 'count revisions from in-memory index tree' (duration: 544.406352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:02.674281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.468286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-17T14:32:02.6743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:02.129622Z","time spent":"544.65763ms","remote":"127.0.0.1:37718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-07-17T14:32:02.674334Z","caller":"traceutil/trace.go:171","msg":"trace[75720059] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1293; }","duration":"629.556439ms","start":"2026-07-17T14:32:02.044765Z","end":"2026-07-17T14:32:02.674322Z","steps":["trace[75720059] 'range keys from in-memory index tree' (duration: 629.27034ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:02.674366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.044451ms","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-07-17T14:32:02.67439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:02.044747Z","time spent":"629.635052ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T14:32:02.674424Z","caller":"traceutil/trace.go:171","msg":"trace[2146585719] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1293; }","duration":"442.146625ms","start":"2026-07-17T14:32:02.232266Z","end":"2026-07-17T14:32:02.674412Z","steps":["trace[2146585719] 'range keys from in-memory index tree' (duration: 441.889946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:02.674245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.008233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T14:32:02.674462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:02.232244Z","time spent":"442.210227ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T14:32:02.674476Z","caller":"traceutil/trace.go:171","msg":"trace[824341462] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1293; }","duration":"462.273271ms","start":"2026-07-17T14:32:02.212194Z","end":"2026-07-17T14:32:02.674467Z","steps":["trace[824341462] 'count revisions from in-memory index tree' (duration: 461.934191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:02.6745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:02.212175Z","time spent":"462.317543ms","remote":"127.0.0.1:51768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-07-17T14:32:03.217659Z","caller":"traceutil/trace.go:171","msg":"trace[2012726230] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"538.070858ms","start":"2026-07-17T14:32:02.679554Z","end":"2026-07-17T14:32:03.217625Z","steps":["trace[2012726230] 'process raft request' (duration: 537.81727ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:32:03.217678Z","caller":"traceutil/trace.go:171","msg":"trace[1874886877] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"537.986656ms","start":"2026-07-17T14:32:02.679662Z","end":"2026-07-17T14:32:03.217649Z","steps":["trace[1874886877] 'process raft request' (duration: 537.882952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:03.217815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:02.679531Z","time spent":"538.208293ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:32:03.217868Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:02.679655Z","time spent":"538.096059ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T14:32:03.21802Z","caller":"traceutil/trace.go:171","msg":"trace[910177099] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"537.868191ms","start":"2026-07-17T14:32:02.680143Z","end":"2026-07-17T14:32:03.218011Z","steps":["trace[910177099] 'process raft request' (duration: 537.460998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:03.218066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:02.680134Z","time spent":"537.909943ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T14:32:03.218104Z","caller":"traceutil/trace.go:171","msg":"trace[1504117749] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"537.850671ms","start":"2026-07-17T14:32:02.680241Z","end":"2026-07-17T14:32:03.218092Z","steps":["trace[1504117749] 'process raft request' (duration: 537.676405ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:32:03.218096Z","caller":"traceutil/trace.go:171","msg":"trace[1721889727] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1348; }","duration":"537.172009ms","start":"2026-07-17T14:32:02.680906Z","end":"2026-07-17T14:32:03.218078Z","steps":["trace[1721889727] 'read index received' (duration: 25.354605ms)","trace[1721889727] 'applied index is now lower than readState.Index' (duration: 511.816344ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:32:03.218146Z","caller":"traceutil/trace.go:171","msg":"trace[497613096] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"537.096337ms","start":"2026-07-17T14:32:02.681037Z","end":"2026-07-17T14:32:03.218133Z","steps":["trace[497613096] 'process raft request' (duration: 536.972583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:03.218165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:02.680235Z","time spent":"537.900042ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:32:03.218188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:02.681021Z","time spent":"537.144628ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:32:03.218266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.350915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T14:32:03.218289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.622517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:32:03.218341Z","caller":"traceutil/trace.go:171","msg":"trace[61320759] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"138.709979ms","start":"2026-07-17T14:32:03.079619Z","end":"2026-07-17T14:32:03.218329Z","steps":["trace[61320759] 'agreement among raft nodes before linearized reading' (duration: 138.640367ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:32:03.218297Z","caller":"traceutil/trace.go:171","msg":"trace[309164230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"537.392726ms","start":"2026-07-17T14:32:02.680897Z","end":"2026-07-17T14:32:03.218289Z","steps":["trace[309164230] 'agreement among raft nodes before linearized reading' (duration: 537.304433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:03.218432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:02.680864Z","time spent":"537.555471ms","remote":"127.0.0.1:37528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T14:32:03.557522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.841881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T14:32:03.557704Z","caller":"traceutil/trace.go:171","msg":"trace[460853174] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"258.443498ms","start":"2026-07-17T14:32:03.299243Z","end":"2026-07-17T14:32:03.557687Z","steps":["trace[460853174] 'process raft request' (duration: 78.321588ms)","trace[460853174] 'compare' (duration: 179.718367ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:32:03.558037Z","caller":"traceutil/trace.go:171","msg":"trace[148669377] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1354; }","duration":"184.236383ms","start":"2026-07-17T14:32:03.373786Z","end":"2026-07-17T14:32:03.558023Z","steps":["trace[148669377] 'read index received' (duration: 3.746421ms)","trace[148669377] 'applied index is now lower than readState.Index' (duration: 180.488482ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:32:03.558223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.428629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.139\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T14:32:03.558265Z","caller":"traceutil/trace.go:171","msg":"trace[200198870] range","detail":"{range_begin:/registry/masterleases/199.19.213.139; range_end:; response_count:1; response_revision:1300; }","duration":"184.501452ms","start":"2026-07-17T14:32:03.373752Z","end":"2026-07-17T14:32:03.558253Z","steps":["trace[200198870] 'agreement among raft nodes before linearized reading' (duration: 184.345417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:03.919138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.577366ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-07-17T14:32:03.919399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:03.55945Z","time spent":"359.942441ms","remote":"127.0.0.1:37536","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-17T14:32:04.642161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.930342ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T14:32:04.642259Z","caller":"traceutil/trace.go:171","msg":"trace[837245652] linearizableReadLoop","detail":"{readStateIndex:1357; appliedIndex:1356; }","duration":"531.60453ms","start":"2026-07-17T14:32:04.110637Z","end":"2026-07-17T14:32:04.642242Z","steps":["trace[837245652] 'read index received' (duration: 40.851µs)","trace[837245652] 'applied index is now lower than readState.Index' (duration: 531.562519ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:32:04.642279Z","caller":"traceutil/trace.go:171","msg":"trace[2080406823] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"719.650376ms","start":"2026-07-17T14:32:03.922599Z","end":"2026-07-17T14:32:04.64225Z","steps":["trace[2080406823] 'process raft request' (duration: 126.553569ms)","trace[2080406823] 'compare' (duration: 592.798888ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:32:04.642403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:03.922583Z","time spent":"719.753369ms","remote":"127.0.0.1:37536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:32:04.642443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.854485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T14:32:04.64243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.780616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:32:04.64248Z","caller":"traceutil/trace.go:171","msg":"trace[1769156335] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"269.928607ms","start":"2026-07-17T14:32:04.372542Z","end":"2026-07-17T14:32:04.642471Z","steps":["trace[1769156335] 'agreement among raft nodes before linearized reading' (duration: 269.804313ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:32:04.642497Z","caller":"traceutil/trace.go:171","msg":"trace[1519189978] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"531.888839ms","start":"2026-07-17T14:32:04.110591Z","end":"2026-07-17T14:32:04.642479Z","steps":["trace[1519189978] 'agreement among raft nodes before linearized reading' (duration: 531.722704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:04.642551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:04.110571Z","time spent":"531.971031ms","remote":"127.0.0.1:37524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T14:32:05.58401Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10980514146934790977,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T14:32:05.597025Z","caller":"traceutil/trace.go:171","msg":"trace[273846576] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"883.395989ms","start":"2026-07-17T14:32:04.713583Z","end":"2026-07-17T14:32:05.596979Z","steps":["trace[273846576] 'process raft request' (duration: 883.210023ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:05.597209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:04.713565Z","time spent":"883.532943ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:32:05.857346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.573201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T14:32:05.857663Z","caller":"traceutil/trace.go:171","msg":"trace[1155892821] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1358; }","duration":"774.125887ms","start":"2026-07-17T14:32:05.083464Z","end":"2026-07-17T14:32:05.85759Z","steps":["trace[1155892821] 'read index received' (duration: 513.579051ms)","trace[1155892821] 'applied index is now lower than readState.Index' (duration: 260.545806ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:32:05.857752Z","caller":"traceutil/trace.go:171","msg":"trace[156574337] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"764.519099ms","start":"2026-07-17T14:32:05.093203Z","end":"2026-07-17T14:32:05.857722Z","steps":["trace[156574337] 'process raft request' (duration: 548.485843ms)","trace[156574337] 'compare' (duration: 215.428276ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:32:05.857812Z","caller":"traceutil/trace.go:171","msg":"trace[716308628] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"294.931442ms","start":"2026-07-17T14:32:05.562853Z","end":"2026-07-17T14:32:05.857784Z","steps":["trace[716308628] 'process raft request' (duration: 294.635652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:05.857857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.385315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-17T14:32:05.857883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:05.093181Z","time spent":"764.644642ms","remote":"127.0.0.1:37588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T14:32:05.857918Z","caller":"traceutil/trace.go:171","msg":"trace[771217737] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1304; }","duration":"774.485619ms","start":"2026-07-17T14:32:05.08342Z","end":"2026-07-17T14:32:05.857906Z","steps":["trace[771217737] 'agreement among raft nodes before linearized reading' (duration: 774.364565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:05.858038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:05.083402Z","time spent":"774.622123ms","remote":"127.0.0.1:38002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-07-17T14:32:05.858135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"722.428535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T14:32:05.858166Z","caller":"traceutil/trace.go:171","msg":"trace[102416148] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1304; }","duration":"722.501648ms","start":"2026-07-17T14:32:05.135656Z","end":"2026-07-17T14:32:05.858158Z","steps":["trace[102416148] 'agreement among raft nodes before linearized reading' (duration: 722.445506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:05.858188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:05.135639Z","time spent":"722.542588ms","remote":"127.0.0.1:51948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-17T14:32:05.858215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"483.760223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T14:32:05.858245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.4732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-17T14:32:05.858277Z","caller":"traceutil/trace.go:171","msg":"trace[141150213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"483.863826ms","start":"2026-07-17T14:32:05.374399Z","end":"2026-07-17T14:32:05.858263Z","steps":["trace[141150213] 'agreement among raft nodes before linearized reading' (duration: 483.739832ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:32:05.858284Z","caller":"traceutil/trace.go:171","msg":"trace[722257834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1304; }","duration":"635.535981ms","start":"2026-07-17T14:32:05.222736Z","end":"2026-07-17T14:32:05.858272Z","steps":["trace[722257834] 'agreement among raft nodes before linearized reading' (duration: 635.396817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:05.858293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.893594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"warn","ts":"2026-07-17T14:32:05.858312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:05.374383Z","time spent":"483.922997ms","remote":"127.0.0.1:37524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T14:32:05.858299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.569273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-17T14:32:05.858332Z","caller":"traceutil/trace.go:171","msg":"trace[367608576] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1304; }","duration":"635.974566ms","start":"2026-07-17T14:32:05.222346Z","end":"2026-07-17T14:32:05.858321Z","steps":["trace[367608576] 'agreement among raft nodes before linearized reading' (duration: 635.842492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:05.858362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:05.222326Z","time spent":"636.029528ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T14:32:05.858368Z","caller":"traceutil/trace.go:171","msg":"trace[1776588279] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1304; }","duration":"635.662626ms","start":"2026-07-17T14:32:05.222691Z","end":"2026-07-17T14:32:05.858353Z","steps":["trace[1776588279] 'agreement among raft nodes before linearized reading' (duration: 635.46752ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:05.858395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.606414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T14:32:05.858484Z","caller":"traceutil/trace.go:171","msg":"trace[895537253] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1304; }","duration":"635.706517ms","start":"2026-07-17T14:32:05.222762Z","end":"2026-07-17T14:32:05.858468Z","steps":["trace[895537253] 'agreement among raft nodes before linearized reading' (duration: 635.439768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:05.85841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:05.22268Z","time spent":"635.718897ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T14:32:05.858316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:05.222731Z","time spent":"635.577463ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T14:32:05.858565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:05.222757Z","time spent":"635.79365ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T14:32:06.140888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.454526ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-17T14:32:06.141012Z","caller":"traceutil/trace.go:171","msg":"trace[1915304134] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"278.752761ms","start":"2026-07-17T14:32:05.862236Z","end":"2026-07-17T14:32:06.140989Z","steps":["trace[1915304134] 'process raft request' (duration: 138.10988ms)","trace[1915304134] 'compare' (duration: 140.28657ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:32:06.454579Z","caller":"traceutil/trace.go:171","msg":"trace[2124800968] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"591.241367ms","start":"2026-07-17T14:32:05.863306Z","end":"2026-07-17T14:32:06.454547Z","steps":["trace[2124800968] 'process raft request' (duration: 591.180605ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:32:06.454686Z","caller":"traceutil/trace.go:171","msg":"trace[1901539412] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"592.182798ms","start":"2026-07-17T14:32:05.862479Z","end":"2026-07-17T14:32:06.454662Z","steps":["trace[1901539412] 'process raft request' (duration: 494.224469ms)","trace[1901539412] 'compare' (duration: 97.221955ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:32:06.454742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:05.863293Z","time spent":"591.367141ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:32:06.454907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:05.862464Z","time spent":"592.27039ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T14:32:06.454637Z","caller":"traceutil/trace.go:171","msg":"trace[55998693] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"591.679271ms","start":"2026-07-17T14:32:05.862936Z","end":"2026-07-17T14:32:06.454615Z","steps":["trace[55998693] 'process raft request' (duration: 591.388792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:06.455447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:05.862914Z","time spent":"592.469787ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:32:08.861132Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10980514146934791010,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T14:32:09.128552Z","caller":"traceutil/trace.go:171","msg":"trace[920319037] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"854.922943ms","start":"2026-07-17T14:32:08.273604Z","end":"2026-07-17T14:32:09.128527Z","steps":["trace[920319037] 'process raft request' (duration: 854.785489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:09.128795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:08.27358Z","time spent":"855.12793ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T14:32:09.683659Z","caller":"traceutil/trace.go:171","msg":"trace[1867512987] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"1.409465502s","start":"2026-07-17T14:32:08.274159Z","end":"2026-07-17T14:32:09.683625Z","steps":["trace[1867512987] 'process raft request' (duration: 1.409055118s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:09.683778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:08.274136Z","time spent":"1.409588145s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":518,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T14:32:09.683831Z","caller":"traceutil/trace.go:171","msg":"trace[103487419] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"1.396112032s","start":"2026-07-17T14:32:08.287678Z","end":"2026-07-17T14:32:09.68379Z","steps":["trace[103487419] 'process raft request' (duration: 1.395698919s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:09.684037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:08.287662Z","time spent":"1.396293948s","remote":"127.0.0.1:37588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T14:32:10.790711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107237945s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-17T14:32:10.79091Z","caller":"traceutil/trace.go:171","msg":"trace[474668642] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1376; }","duration":"2.430558927s","start":"2026-07-17T14:32:08.360331Z","end":"2026-07-17T14:32:10.79089Z","steps":["trace[474668642] 'read index received' (duration: 768.074361ms)","trace[474668642] 'applied index is now lower than readState.Index' (duration: 1.662483576s)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:32:10.791165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.430827626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:32:10.791203Z","caller":"traceutil/trace.go:171","msg":"trace[1076233154] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1323; }","duration":"2.430894157s","start":"2026-07-17T14:32:08.360295Z","end":"2026-07-17T14:32:10.791189Z","steps":["trace[1076233154] 'agreement among raft nodes before linearized reading' (duration: 2.430826455s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:10.791257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:08.360249Z","time spent":"2.430978311s","remote":"127.0.0.1:51710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"info","ts":"2026-07-17T14:32:10.791549Z","caller":"traceutil/trace.go:171","msg":"trace[1734720239] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"1.103550727s","start":"2026-07-17T14:32:09.687984Z","end":"2026-07-17T14:32:10.791535Z","steps":["trace[1734720239] 'process raft request' (duration: 1.102830914s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:10.791665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:09.687945Z","time spent":"1.103675211s","remote":"127.0.0.1:37588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":929,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T14:32:11.266509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.930762ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T14:32:11.266995Z","caller":"traceutil/trace.go:171","msg":"trace[104677830] linearizableReadLoop","detail":"{readStateIndex:1383; appliedIndex:1381; }","duration":"476.027551ms","start":"2026-07-17T14:32:10.790944Z","end":"2026-07-17T14:32:11.266972Z","steps":["trace[104677830] 'read index received' (duration: 366.427928ms)","trace[104677830] 'applied index is now lower than readState.Index' (duration: 109.597763ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:32:11.267021Z","caller":"traceutil/trace.go:171","msg":"trace[2088114899] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"1.578577105s","start":"2026-07-17T14:32:09.688403Z","end":"2026-07-17T14:32:11.26698Z","steps":["trace[2088114899] 'process raft request' (duration: 1.469095326s)","trace[2088114899] 'compare' (duration: 108.743495ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T14:32:11.267178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.808305769s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-07-17T14:32:11.26718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:09.688381Z","time spent":"1.57871703s","remote":"127.0.0.1:37588","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":837,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T14:32:11.267228Z","caller":"traceutil/trace.go:171","msg":"trace[1651538819] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1326; }","duration":"2.808377232s","start":"2026-07-17T14:32:08.45883Z","end":"2026-07-17T14:32:11.267208Z","steps":["trace[1651538819] 'agreement among raft nodes before linearized reading' (duration: 2.808217697s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:11.267272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:08.458815Z","time spent":"2.808446733s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T14:32:11.267324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.352321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T14:32:11.267341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115026793s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-17T14:32:11.26738Z","caller":"traceutil/trace.go:171","msg":"trace[1491264949] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1326; }","duration":"690.442774ms","start":"2026-07-17T14:32:10.576918Z","end":"2026-07-17T14:32:11.267361Z","steps":["trace[1491264949] 'agreement among raft nodes before linearized reading' (duration: 690.349141ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:32:11.267395Z","caller":"traceutil/trace.go:171","msg":"trace[1589073540] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1326; }","duration":"1.115135566s","start":"2026-07-17T14:32:10.152246Z","end":"2026-07-17T14:32:11.267382Z","steps":["trace[1589073540] 'agreement among raft nodes before linearized reading' (duration: 1.114972761s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:11.267431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:10.152225Z","time spent":"1.115198989s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T14:32:11.267424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:10.576901Z","time spent":"690.510655ms","remote":"127.0.0.1:51798","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-17T14:32:11.267532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.380078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:32:11.267584Z","caller":"traceutil/trace.go:171","msg":"trace[521192940] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:1326; }","duration":"879.44319ms","start":"2026-07-17T14:32:10.388108Z","end":"2026-07-17T14:32:11.267552Z","steps":["trace[521192940] 'agreement among raft nodes before linearized reading' (duration: 879.401908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:11.267539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.894473541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T14:32:11.267602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:10.38809Z","time spent":"879.507181ms","remote":"127.0.0.1:37814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":29,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"info","ts":"2026-07-17T14:32:11.267628Z","caller":"traceutil/trace.go:171","msg":"trace[1640375374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1326; }","duration":"1.894595125s","start":"2026-07-17T14:32:09.373017Z","end":"2026-07-17T14:32:11.267612Z","steps":["trace[1640375374] 'agreement among raft nodes before linearized reading' (duration: 1.89445642s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:11.267683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:09.373001Z","time spent":"1.894671387s","remote":"127.0.0.1:37524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T14:32:11.267726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.00294035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:32:11.267749Z","caller":"traceutil/trace.go:171","msg":"trace[1040014908] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1326; }","duration":"1.002990952s","start":"2026-07-17T14:32:10.26475Z","end":"2026-07-17T14:32:11.267741Z","steps":["trace[1040014908] 'agreement among raft nodes before linearized reading' (duration: 1.002956171s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:11.267774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:10.264734Z","time spent":"1.003035403s","remote":"127.0.0.1:51722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-07-17T14:32:11.267687Z","caller":"traceutil/trace.go:171","msg":"trace[1776744904] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"1.3964279s","start":"2026-07-17T14:32:09.871242Z","end":"2026-07-17T14:32:11.26767Z","steps":["trace[1776744904] 'process raft request' (duration: 1.395375536s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:11.267868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.807590466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-17T14:32:11.267919Z","caller":"traceutil/trace.go:171","msg":"trace[1420927536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1326; }","duration":"2.807691949s","start":"2026-07-17T14:32:08.460212Z","end":"2026-07-17T14:32:11.267904Z","steps":["trace[1420927536] 'agreement among raft nodes before linearized reading' (duration: 2.807517754s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:11.267945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:09.871217Z","time spent":"1.396667178s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T14:32:11.267976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:08.460205Z","time spent":"2.807758191s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T14:32:11.268081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.808302969s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-17T14:32:11.268413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.658427473s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T14:32:11.268923Z","caller":"traceutil/trace.go:171","msg":"trace[1142253398] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1326; }","duration":"1.65897351s","start":"2026-07-17T14:32:09.609927Z","end":"2026-07-17T14:32:11.2689Z","steps":["trace[1142253398] 'agreement among raft nodes before linearized reading' (duration: 1.657892155s)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:32:11.268966Z","caller":"traceutil/trace.go:171","msg":"trace[1613817832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1326; }","duration":"2.809162057s","start":"2026-07-17T14:32:08.459693Z","end":"2026-07-17T14:32:11.268855Z","steps":["trace[1613817832] 'agreement among raft nodes before linearized reading' (duration: 2.807832484s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:11.269304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:08.459673Z","time spent":"2.809610752s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-17T14:32:11.268138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.581691845s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-17T14:32:11.269714Z","caller":"traceutil/trace.go:171","msg":"trace[982658636] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1326; }","duration":"1.583300168s","start":"2026-07-17T14:32:09.686397Z","end":"2026-07-17T14:32:11.269698Z","steps":["trace[982658636] 'agreement among raft nodes before linearized reading' (duration: 1.581649705s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:11.26976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:09.686379Z","time spent":"1.58336542s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-17T14:32:11.269902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:09.609902Z","time spent":"1.659118615s","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T14:32:11.514621Z","caller":"traceutil/trace.go:171","msg":"trace[1034746454] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"241.27559ms","start":"2026-07-17T14:32:11.273311Z","end":"2026-07-17T14:32:11.514587Z","steps":["trace[1034746454] 'process raft request' (duration: 218.187374ms)","trace[1034746454] 'compare' (duration: 22.976893ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:32:11.516762Z","caller":"traceutil/trace.go:171","msg":"trace[1505034050] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"243.375068ms","start":"2026-07-17T14:32:11.273366Z","end":"2026-07-17T14:32:11.516741Z","steps":["trace[1505034050] 'process raft request' (duration: 243.11858ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:32:11.516757Z","caller":"traceutil/trace.go:171","msg":"trace[197603865] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"243.075218ms","start":"2026-07-17T14:32:11.273645Z","end":"2026-07-17T14:32:11.51672Z","steps":["trace[197603865] 'process raft request' (duration: 242.977665ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:32:11.517031Z","caller":"traceutil/trace.go:171","msg":"trace[1181704405] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"243.240644ms","start":"2026-07-17T14:32:11.273765Z","end":"2026-07-17T14:32:11.517006Z","steps":["trace[1181704405] 'process raft request' (duration: 242.906433ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:32:11.517299Z","caller":"traceutil/trace.go:171","msg":"trace[1962426893] linearizableReadLoop","detail":"{readStateIndex:1389; appliedIndex:1383; }","duration":"227.190615ms","start":"2026-07-17T14:32:11.290098Z","end":"2026-07-17T14:32:11.517288Z","steps":["trace[1962426893] 'read index received' (duration: 201.414161ms)","trace[1962426893] 'applied index is now lower than readState.Index' (duration: 25.775704ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T14:32:11.517287Z","caller":"traceutil/trace.go:171","msg":"trace[915509268] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"243.285296ms","start":"2026-07-17T14:32:11.273967Z","end":"2026-07-17T14:32:11.517252Z","steps":["trace[915509268] 'process raft request' (duration: 242.939624ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:32:11.517385Z","caller":"traceutil/trace.go:171","msg":"trace[1305924495] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"242.428227ms","start":"2026-07-17T14:32:11.274945Z","end":"2026-07-17T14:32:11.517373Z","steps":["trace[1305924495] 'process raft request' (duration: 242.222461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:11.517524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.421823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:32:11.517564Z","caller":"traceutil/trace.go:171","msg":"trace[1950526527] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1332; }","duration":"227.507965ms","start":"2026-07-17T14:32:11.290047Z","end":"2026-07-17T14:32:11.517555Z","steps":["trace[1950526527] 'agreement among raft nodes before linearized reading' (duration: 227.426193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:18.626887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.654255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T14:32:18.627018Z","caller":"traceutil/trace.go:171","msg":"trace[1868621521] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1379; }","duration":"165.847421ms","start":"2026-07-17T14:32:18.461145Z","end":"2026-07-17T14:32:18.626993Z","steps":["trace[1868621521] 'count revisions from in-memory index tree' (duration: 165.531691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:18.627409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.020851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T14:32:18.627482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.519733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T14:32:18.627501Z","caller":"traceutil/trace.go:171","msg":"trace[177086142] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1379; }","duration":"253.150444ms","start":"2026-07-17T14:32:18.374321Z","end":"2026-07-17T14:32:18.627471Z","steps":["trace[177086142] 'range keys from in-memory index tree' (duration: 252.930207ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:32:18.627536Z","caller":"traceutil/trace.go:171","msg":"trace[1708764458] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1379; }","duration":"496.578365ms","start":"2026-07-17T14:32:18.130943Z","end":"2026-07-17T14:32:18.627521Z","steps":["trace[1708764458] 'count revisions from in-memory index tree' (duration: 496.391369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T14:32:18.627621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T14:32:18.130884Z","time spent":"496.72007ms","remote":"127.0.0.1:37996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":5,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-07-17T14:36:24.238811Z","caller":"traceutil/trace.go:171","msg":"trace[517760415] transaction","detail":"{read_only:false; response_revision:2532; number_of_response:1; }","duration":"101.307634ms","start":"2026-07-17T14:36:24.137476Z","end":"2026-07-17T14:36:24.238784Z","steps":["trace[517760415] 'process raft request' (duration: 101.148829ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T14:36:42.972621Z","caller":"traceutil/trace.go:171","msg":"trace[382176326] transaction","detail":"{read_only:false; response_revision:2628; number_of_response:1; }","duration":"100.44185ms","start":"2026-07-17T14:36:42.872145Z","end":"2026-07-17T14:36:42.972587Z","steps":["trace[382176326] 'process raft request' (duration: 50.06207ms)","trace[382176326] 'compare' (duration: 50.091961ms)"],"step_count":2}