{"level":"warn","ts":"2026-07-24T17:35:01.575973Z","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-24T17:35:01.576058Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22: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-24T17:35:01.576132Z","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-24T17:35:01.576142Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-24T17:35:01.576162Z","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-24T17:35:01.576896Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-07-24T17:35:01.576999Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22: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-24T17:35:01.579968Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.7028ms"} {"level":"info","ts":"2026-07-24T17:35:01.58543Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-07-24T17:35:01.585494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T17:35:01.585515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-07-24T17:35:01.585528Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T17:35:01.585537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-07-24T17:35:01.585574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-07-24T17:35:01.591922Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T17:35:01.593719Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T17:35:01.595491Z","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-24T17:35:01.598669Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T17:35:01.598863Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T17:35:01.599015Z","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-24T17:35:01.599116Z","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-24T17:35:01.599126Z","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-24T17:35:01.601205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-07-24T17:35:01.607519Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-24T17:35:01.610853Z","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-24T17:35:01.611116Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T17:35:01.611146Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T17:35:01.611268Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-24T17:35:01.611293Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-24T17:35:02.085955Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T17:35:02.086018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T17:35:02.086052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-07-24T17:35:02.086077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-07-24T17:35:02.086088Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-24T17:35:02.086108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-07-24T17:35:02.086125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-24T17:35:02.087437Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T17:35:02.087453Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T17:35:02.087457Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T17:35:02.087646Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T17:35:02.087948Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T17:35:02.088019Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T17:35:02.088743Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T17:35:02.089024Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T17:35:02.089065Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T17:35:02.089697Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-24T17:35:02.091512Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-07-24T17:35:10.954858Z","caller":"traceutil/trace.go:171","msg":"trace[138820079] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"127.604355ms","start":"2026-07-24T17:35:10.827195Z","end":"2026-07-24T17:35:10.954799Z","steps":["trace[138820079] 'process raft request' (duration: 75.378239ms)","trace[138820079] 'compare' (duration: 52.037112ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:35:26.520335Z","caller":"traceutil/trace.go:171","msg":"trace[450994319] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"760.279319ms","start":"2026-07-24T17:35:25.760033Z","end":"2026-07-24T17:35:26.520312Z","steps":["trace[450994319] 'process raft request' (duration: 760.133945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:26.520822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:25.760011Z","time spent":"760.394931ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:35:27.121266Z","caller":"traceutil/trace.go:171","msg":"trace[1768370804] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"309.86408ms","start":"2026-07-24T17:35:26.811369Z","end":"2026-07-24T17:35:27.121233Z","steps":["trace[1768370804] 'read index received' (duration: 309.789638ms)","trace[1768370804] 'applied index is now lower than readState.Index' (duration: 73.102µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:35:27.121424Z","caller":"traceutil/trace.go:171","msg":"trace[1765674821] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"320.634296ms","start":"2026-07-24T17:35:26.80076Z","end":"2026-07-24T17:35:27.121394Z","steps":["trace[1765674821] 'process raft request' (duration: 320.318778ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:27.121455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.057364ms","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-24T17:35:27.12155Z","caller":"traceutil/trace.go:171","msg":"trace[1655856106] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:468; }","duration":"310.189738ms","start":"2026-07-24T17:35:26.81134Z","end":"2026-07-24T17:35:27.12153Z","steps":["trace[1655856106] 'agreement among raft nodes before linearized reading' (duration: 310.017044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:27.121604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:26.811332Z","time spent":"310.25686ms","remote":"127.0.0.1:50508","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-24T17:35:27.121648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:26.800737Z","time spent":"320.772279ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:35:27.142866Z","caller":"traceutil/trace.go:171","msg":"trace[886033282] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"148.800648ms","start":"2026-07-24T17:35:26.994043Z","end":"2026-07-24T17:35:27.142844Z","steps":["trace[886033282] 'process raft request' (duration: 148.678485ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:35:32.829858Z","caller":"traceutil/trace.go:171","msg":"trace[1780234019] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"194.056727ms","start":"2026-07-24T17:35:32.635775Z","end":"2026-07-24T17:35:32.829831Z","steps":["trace[1780234019] 'process raft request' (duration: 193.886732ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:35:38.934177Z","caller":"traceutil/trace.go:171","msg":"trace[885017741] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"104.73317ms","start":"2026-07-24T17:35:38.829411Z","end":"2026-07-24T17:35:38.934144Z","steps":["trace[885017741] 'process raft request' (duration: 104.541756ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:35:39.864098Z","caller":"traceutil/trace.go:171","msg":"trace[1812407181] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:546; }","duration":"126.875257ms","start":"2026-07-24T17:35:39.737196Z","end":"2026-07-24T17:35:39.864071Z","steps":["trace[1812407181] 'read index received' (duration: 126.683032ms)","trace[1812407181] 'applied index is now lower than readState.Index' (duration: 188.655µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:35:39.864205Z","caller":"traceutil/trace.go:171","msg":"trace[2124254331] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"147.372411ms","start":"2026-07-24T17:35:39.7168Z","end":"2026-07-24T17:35:39.864173Z","steps":["trace[2124254331] 'process raft request' (duration: 147.078793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:39.864245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.029891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:35:39.864296Z","caller":"traceutil/trace.go:171","msg":"trace[715428229] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:532; }","duration":"127.103502ms","start":"2026-07-24T17:35:39.737178Z","end":"2026-07-24T17:35:39.864281Z","steps":["trace[715428229] 'agreement among raft nodes before linearized reading' (duration: 127.0022ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:35:40.981629Z","caller":"traceutil/trace.go:171","msg":"trace[536234883] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"816.680391ms","start":"2026-07-24T17:35:40.164928Z","end":"2026-07-24T17:35:40.981609Z","steps":["trace[536234883] 'process raft request' (duration: 816.533808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:40.981786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:40.16491Z","time spent":"816.803145ms","remote":"127.0.0.1:50310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7684,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:35:40.990486Z","caller":"traceutil/trace.go:171","msg":"trace[872822641] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"825.000654ms","start":"2026-07-24T17:35:40.165466Z","end":"2026-07-24T17:35:40.990467Z","steps":["trace[872822641] 'process raft request' (duration: 824.484341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:40.990609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:40.165449Z","time spent":"825.088756ms","remote":"127.0.0.1:50310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:35:40.990693Z","caller":"traceutil/trace.go:171","msg":"trace[974614930] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"819.791341ms","start":"2026-07-24T17:35:40.170894Z","end":"2026-07-24T17:35:40.990685Z","steps":["trace[974614930] 'process raft request' (duration: 819.165235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:40.990736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:40.170885Z","time spent":"819.830322ms","remote":"127.0.0.1:50310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:35:40.990778Z","caller":"traceutil/trace.go:171","msg":"trace[2075543108] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"707.560288ms","start":"2026-07-24T17:35:40.283188Z","end":"2026-07-24T17:35:40.990748Z","steps":["trace[2075543108] 'process raft request' (duration: 707.048405ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:35:40.990933Z","caller":"traceutil/trace.go:171","msg":"trace[1547674934] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"813.413158ms","start":"2026-07-24T17:35:40.177507Z","end":"2026-07-24T17:35:40.99092Z","steps":["trace[1547674934] 'process raft request' (duration: 812.608467ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:40.99096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:40.283151Z","time spent":"707.722713ms","remote":"127.0.0.1:50310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T17:35:40.990993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:40.177488Z","time spent":"813.476799ms","remote":"127.0.0.1:50310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:35:43.594108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.471587ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:35:43.594258Z","caller":"traceutil/trace.go:171","msg":"trace[1150987372] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"188.410624ms","start":"2026-07-24T17:35:43.405829Z","end":"2026-07-24T17:35:43.594239Z","steps":["trace[1150987372] 'read index received' (duration: 36.485375ms)","trace[1150987372] 'applied index is now lower than readState.Index' (duration: 151.924209ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:35:43.594391Z","caller":"traceutil/trace.go:171","msg":"trace[1727002351] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"212.684753ms","start":"2026-07-24T17:35:43.381668Z","end":"2026-07-24T17:35:43.594353Z","steps":["trace[1727002351] 'process raft request' (duration: 60.711503ms)","trace[1727002351] 'compare' (duration: 151.315872ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:35:43.594507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.63469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:35:43.594542Z","caller":"traceutil/trace.go:171","msg":"trace[871910622] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:614; }","duration":"188.747143ms","start":"2026-07-24T17:35:43.405785Z","end":"2026-07-24T17:35:43.594532Z","steps":["trace[871910622] 'agreement among raft nodes before linearized reading' (duration: 188.649581ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:35:47.509078Z","caller":"traceutil/trace.go:171","msg":"trace[515285844] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"178.424915ms","start":"2026-07-24T17:35:47.330619Z","end":"2026-07-24T17:35:47.509043Z","steps":["trace[515285844] 'process raft request' (duration: 177.760078ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:35:47.509012Z","caller":"traceutil/trace.go:171","msg":"trace[1928877819] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"117.2845ms","start":"2026-07-24T17:35:47.391699Z","end":"2026-07-24T17:35:47.508984Z","steps":["trace[1928877819] 'read index received' (duration: 116.434839ms)","trace[1928877819] 'applied index is now lower than readState.Index' (duration: 848.551µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:35:47.509392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.830284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:35:47.509444Z","caller":"traceutil/trace.go:171","msg":"trace[900874509] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"102.928797ms","start":"2026-07-24T17:35:47.4065Z","end":"2026-07-24T17:35:47.509429Z","steps":["trace[900874509] 'agreement among raft nodes before linearized reading' (duration: 102.727732ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:47.509465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.743862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T17:35:47.509763Z","caller":"traceutil/trace.go:171","msg":"trace[679746150] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"118.100001ms","start":"2026-07-24T17:35:47.391646Z","end":"2026-07-24T17:35:47.509746Z","steps":["trace[679746150] 'agreement among raft nodes before linearized reading' (duration: 117.493565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:47.865525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.773942ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:35:47.865738Z","caller":"traceutil/trace.go:171","msg":"trace[282290896] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"302.389239ms","start":"2026-07-24T17:35:47.563324Z","end":"2026-07-24T17:35:47.865713Z","steps":["trace[282290896] 'process raft request' (duration: 302.315897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:47.865818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:47.563306Z","time spent":"302.480041ms","remote":"127.0.0.1:50576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:35:47.866182Z","caller":"traceutil/trace.go:171","msg":"trace[1035995334] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"353.085612ms","start":"2026-07-24T17:35:47.513078Z","end":"2026-07-24T17:35:47.866164Z","steps":["trace[1035995334] 'process raft request' (duration: 23.56209ms)","trace[1035995334] 'compare' (duration: 328.425673ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:35:47.866274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:47.513059Z","time spent":"353.171734ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:35:48.771127Z","caller":"traceutil/trace.go:171","msg":"trace[806377145] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:650; }","duration":"1.163765956s","start":"2026-07-24T17:35:47.607341Z","end":"2026-07-24T17:35:48.771107Z","steps":["trace[806377145] 'read index received' (duration: 245.551915ms)","trace[806377145] 'applied index is now lower than readState.Index' (duration: 918.21111ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:35:48.77123Z","caller":"traceutil/trace.go:171","msg":"trace[603576238] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.194363078s","start":"2026-07-24T17:35:47.57686Z","end":"2026-07-24T17:35:48.771223Z","steps":["trace[603576238] 'process raft request' (duration: 1.187657974s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:48.771305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:47.576838Z","time spent":"1.19441096s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:35:48.771431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"921.026503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-j52mb\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-24T17:35:48.771499Z","caller":"traceutil/trace.go:171","msg":"trace[371353068] range","detail":"{range_begin:/registry/pods/kube-system/cilium-j52mb; range_end:; response_count:1; response_revision:635; }","duration":"921.142456ms","start":"2026-07-24T17:35:47.850339Z","end":"2026-07-24T17:35:48.771481Z","steps":["trace[371353068] 'agreement among raft nodes before linearized reading' (duration: 920.968082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:48.771536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:47.850292Z","time spent":"921.235598ms","remote":"127.0.0.1:50436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-j52mb\" "} {"level":"warn","ts":"2026-07-24T17:35:48.771601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.455257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T17:35:48.771629Z","caller":"traceutil/trace.go:171","msg":"trace[1946144123] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"815.511029ms","start":"2026-07-24T17:35:47.956109Z","end":"2026-07-24T17:35:48.77162Z","steps":["trace[1946144123] 'agreement among raft nodes before linearized reading' (duration: 815.407496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:48.771649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:47.956087Z","time spent":"815.558129ms","remote":"127.0.0.1:50508","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-24T17:35:48.771637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.723055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:35:48.771731Z","caller":"traceutil/trace.go:171","msg":"trace[1016239727] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:635; }","duration":"893.852169ms","start":"2026-07-24T17:35:47.877853Z","end":"2026-07-24T17:35:48.771705Z","steps":["trace[1016239727] 'agreement among raft nodes before linearized reading' (duration: 893.696305ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:48.771784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:47.877836Z","time spent":"893.933ms","remote":"127.0.0.1:50580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-24T17:35:48.771849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.164499655s","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-24T17:35:48.771948Z","caller":"traceutil/trace.go:171","msg":"trace[350239256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"1.164634547s","start":"2026-07-24T17:35:47.607294Z","end":"2026-07-24T17:35:48.771929Z","steps":["trace[350239256] 'agreement among raft nodes before linearized reading' (duration: 1.164391111s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:48.772017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:47.607275Z","time spent":"1.16472856s","remote":"127.0.0.1:50508","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-24T17:35:49.906436Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515054457083527,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:35:50.163775Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.130931604s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T17:35:50.164152Z","caller":"traceutil/trace.go:171","msg":"trace[1051500884] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.131395867s","start":"2026-07-24T17:35:49.032733Z","end":"2026-07-24T17:35:50.164129Z","steps":["trace[1051500884] 'process raft request' (duration: 1.131246573s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:50.164299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:49.032398Z","time spent":"1.131824307s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:35:50.713318Z","caller":"traceutil/trace.go:171","msg":"trace[788209240] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.514886326s","start":"2026-07-24T17:35:49.198254Z","end":"2026-07-24T17:35:50.71314Z","steps":["trace[788209240] 'process raft request' (duration: 1.513368816s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:50.713662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:49.198186Z","time spent":"1.515312097s","remote":"127.0.0.1:50342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036201,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:35:50.728179Z","caller":"traceutil/trace.go:171","msg":"trace[852101914] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"1.322391907s","start":"2026-07-24T17:35:49.405744Z","end":"2026-07-24T17:35:50.728136Z","steps":["trace[852101914] 'read index received' (duration: 758.277355ms)","trace[852101914] 'applied index is now lower than readState.Index' (duration: 564.113361ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:35:50.728667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.32291154s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:35:50.728801Z","caller":"traceutil/trace.go:171","msg":"trace[1055868314] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"1.323018753s","start":"2026-07-24T17:35:49.405694Z","end":"2026-07-24T17:35:50.728713Z","steps":["trace[1055868314] 'agreement among raft nodes before linearized reading' (duration: 1.32287624s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:50.728903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:49.405673Z","time spent":"1.323221278s","remote":"127.0.0.1:50262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T17:35:50.72886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.514343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T17:35:50.729059Z","caller":"traceutil/trace.go:171","msg":"trace[788782356] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:640; }","duration":"858.787011ms","start":"2026-07-24T17:35:49.870261Z","end":"2026-07-24T17:35:50.729048Z","steps":["trace[788782356] 'agreement among raft nodes before linearized reading' (duration: 858.38374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:50.729128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:49.870238Z","time spent":"858.883303ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-24T17:35:52.538274Z","caller":"traceutil/trace.go:171","msg":"trace[849554609] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"369.166438ms","start":"2026-07-24T17:35:52.169078Z","end":"2026-07-24T17:35:52.538245Z","steps":["trace[849554609] 'read index received' (duration: 359.682742ms)","trace[849554609] 'applied index is now lower than readState.Index' (duration: 9.482526ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:35:52.538344Z","caller":"traceutil/trace.go:171","msg":"trace[350472803] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"539.78585ms","start":"2026-07-24T17:35:51.998524Z","end":"2026-07-24T17:35:52.53831Z","steps":["trace[350472803] 'process raft request' (duration: 530.299845ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:52.538525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.434185ms","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-24T17:35:52.538518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:51.998514Z","time spent":"539.913243ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:35:52.538571Z","caller":"traceutil/trace.go:171","msg":"trace[160386168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:660; }","duration":"369.537288ms","start":"2026-07-24T17:35:52.169021Z","end":"2026-07-24T17:35:52.538559Z","steps":["trace[160386168] 'agreement among raft nodes before linearized reading' (duration: 369.344173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:52.538604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:52.169Z","time spent":"369.596639ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T17:35:52.540187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.449805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:35:52.540243Z","caller":"traceutil/trace.go:171","msg":"trace[1016583842] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:661; }","duration":"134.568728ms","start":"2026-07-24T17:35:52.405659Z","end":"2026-07-24T17:35:52.540228Z","steps":["trace[1016583842] 'agreement among raft nodes before linearized reading' (duration: 134.472475ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:35:52.540359Z","caller":"traceutil/trace.go:171","msg":"trace[471945402] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"323.546095ms","start":"2026-07-24T17:35:52.216792Z","end":"2026-07-24T17:35:52.540338Z","steps":["trace[471945402] 'process raft request' (duration: 322.729834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:35:52.540582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:35:52.216762Z","time spent":"323.664689ms","remote":"127.0.0.1:50342","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:35:55.161638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.151348ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:35:55.161846Z","caller":"traceutil/trace.go:171","msg":"trace[651526315] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"158.258183ms","start":"2026-07-24T17:35:55.003545Z","end":"2026-07-24T17:35:55.161804Z","steps":["trace[651526315] 'compare' (duration: 155.890901ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:36:02.835936Z","caller":"traceutil/trace.go:171","msg":"trace[2141064461] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"239.647972ms","start":"2026-07-24T17:36:02.59626Z","end":"2026-07-24T17:36:02.835908Z","steps":["trace[2141064461] 'process raft request' (duration: 239.457627ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:36:02.8401Z","caller":"traceutil/trace.go:171","msg":"trace[2116800147] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"241.686514ms","start":"2026-07-24T17:36:02.598397Z","end":"2026-07-24T17:36:02.840083Z","steps":["trace[2116800147] 'process raft request' (duration: 241.409817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:05.345486Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515054457083843,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T17:36:05.634885Z","caller":"traceutil/trace.go:171","msg":"trace[913509139] linearizableReadLoop","detail":"{readStateIndex:826; appliedIndex:825; }","duration":"790.187602ms","start":"2026-07-24T17:36:04.844674Z","end":"2026-07-24T17:36:05.634862Z","steps":["trace[913509139] 'read index received' (duration: 790.072889ms)","trace[913509139] 'applied index is now lower than readState.Index' (duration: 113.123µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:36:05.635298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.246954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-24T17:36:05.635345Z","caller":"traceutil/trace.go:171","msg":"trace[1253265058] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:803; }","duration":"633.346056ms","start":"2026-07-24T17:36:05.001987Z","end":"2026-07-24T17:36:05.635333Z","steps":["trace[1253265058] 'agreement among raft nodes before linearized reading' (duration: 633.188982ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:05.635355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.63536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T17:36:05.635427Z","caller":"traceutil/trace.go:171","msg":"trace[1073400754] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"791.105166ms","start":"2026-07-24T17:36:04.844313Z","end":"2026-07-24T17:36:05.635418Z","steps":["trace[1073400754] 'process raft request' (duration: 790.415798ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:36:05.635449Z","caller":"traceutil/trace.go:171","msg":"trace[439364029] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:803; }","duration":"786.766744ms","start":"2026-07-24T17:36:04.848659Z","end":"2026-07-24T17:36:05.635426Z","steps":["trace[439364029] 'agreement among raft nodes before linearized reading' (duration: 786.514437ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:05.635497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:04.848647Z","time spent":"786.840735ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T17:36:05.635507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:04.844291Z","time spent":"791.161318ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:36:05.63538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:05.001959Z","time spent":"633.412569ms","remote":"127.0.0.1:50288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"warn","ts":"2026-07-24T17:36:05.635599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.399194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2gkkt\" ","response":"range_response_count:1 size:3964"} {"level":"warn","ts":"2026-07-24T17:36:05.635622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.943402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T17:36:05.635691Z","caller":"traceutil/trace.go:171","msg":"trace[2045209236] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:803; }","duration":"791.061275ms","start":"2026-07-24T17:36:04.844616Z","end":"2026-07-24T17:36:05.635677Z","steps":["trace[2045209236] 'agreement among raft nodes before linearized reading' (duration: 790.87007ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:36:05.635694Z","caller":"traceutil/trace.go:171","msg":"trace[943916578] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2gkkt; range_end:; response_count:1; response_revision:803; }","duration":"683.503207ms","start":"2026-07-24T17:36:04.952164Z","end":"2026-07-24T17:36:05.635668Z","steps":["trace[943916578] 'agreement among raft nodes before linearized reading' (duration: 683.22369ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:05.63568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.767312ms","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-24T17:36:05.635733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:04.844594Z","time spent":"791.130467ms","remote":"127.0.0.1:50508","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-24T17:36:05.635739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.861928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T17:36:05.63575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:04.952118Z","time spent":"683.618329ms","remote":"127.0.0.1:50436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-2gkkt\" "} {"level":"info","ts":"2026-07-24T17:36:05.635781Z","caller":"traceutil/trace.go:171","msg":"trace[740039649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"229.93698ms","start":"2026-07-24T17:36:05.405831Z","end":"2026-07-24T17:36:05.635768Z","steps":["trace[740039649] 'agreement among raft nodes before linearized reading' (duration: 229.878339ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:36:05.635792Z","caller":"traceutil/trace.go:171","msg":"trace[1472890172] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:803; }","duration":"747.945067ms","start":"2026-07-24T17:36:04.887816Z","end":"2026-07-24T17:36:05.635761Z","steps":["trace[1472890172] 'agreement among raft nodes before linearized reading' (duration: 747.600498ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:05.635894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:04.887796Z","time spent":"748.087181ms","remote":"127.0.0.1:50508","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-24T17:36:05.636297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.534269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T17:36:05.636342Z","caller":"traceutil/trace.go:171","msg":"trace[1566176986] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:803; }","duration":"744.587481ms","start":"2026-07-24T17:36:04.891736Z","end":"2026-07-24T17:36:05.636323Z","steps":["trace[1566176986] 'agreement among raft nodes before linearized reading' (duration: 743.607475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:05.636381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:04.891724Z","time spent":"744.649152ms","remote":"127.0.0.1:50508","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-24T17:36:06.357181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:05.637295Z","time spent":"719.87916ms","remote":"127.0.0.1:50288","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-24T17:36:06.366324Z","caller":"traceutil/trace.go:171","msg":"trace[1583987238] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"725.563027ms","start":"2026-07-24T17:36:05.640742Z","end":"2026-07-24T17:36:06.366305Z","steps":["trace[1583987238] 'process raft request' (duration: 725.261928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:06.366431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:05.640697Z","time spent":"725.67759ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:36:06.366624Z","caller":"traceutil/trace.go:171","msg":"trace[828377509] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"725.708111ms","start":"2026-07-24T17:36:05.640862Z","end":"2026-07-24T17:36:06.36657Z","steps":["trace[828377509] 'process raft request' (duration: 725.418683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:06.366821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:05.640842Z","time spent":"725.853664ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:36:06.36683Z","caller":"traceutil/trace.go:171","msg":"trace[1502391967] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"725.785622ms","start":"2026-07-24T17:36:05.641032Z","end":"2026-07-24T17:36:06.366818Z","steps":["trace[1502391967] 'process raft request' (duration: 725.423033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:06.366884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:05.641006Z","time spent":"725.850434ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:36:06.367077Z","caller":"traceutil/trace.go:171","msg":"trace[1795218246] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"725.780472ms","start":"2026-07-24T17:36:05.641271Z","end":"2026-07-24T17:36:06.367051Z","steps":["trace[1795218246] 'process raft request' (duration: 725.330591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:06.367225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:05.641252Z","time spent":"725.908386ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:36:06.367829Z","caller":"traceutil/trace.go:171","msg":"trace[1581508791] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"722.663181ms","start":"2026-07-24T17:36:05.645143Z","end":"2026-07-24T17:36:06.367806Z","steps":["trace[1581508791] 'process raft request' (duration: 721.789149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:06.36801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:05.645132Z","time spent":"722.757715ms","remote":"127.0.0.1:50436","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:36:30.051987Z","caller":"traceutil/trace.go:171","msg":"trace[1680702397] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"251.064768ms","start":"2026-07-24T17:36:29.800898Z","end":"2026-07-24T17:36:30.051963Z","steps":["trace[1680702397] 'process raft request' (duration: 250.902183ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:36:36.777729Z","caller":"traceutil/trace.go:171","msg":"trace[1771042496] linearizableReadLoop","detail":"{readStateIndex:1029; appliedIndex:1028; }","duration":"230.289939ms","start":"2026-07-24T17:36:36.547414Z","end":"2026-07-24T17:36:36.777704Z","steps":["trace[1771042496] 'read index received' (duration: 229.95693ms)","trace[1771042496] 'applied index is now lower than readState.Index' (duration: 332.129µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:36:36.777838Z","caller":"traceutil/trace.go:171","msg":"trace[533164087] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"250.845901ms","start":"2026-07-24T17:36:36.526951Z","end":"2026-07-24T17:36:36.777797Z","steps":["trace[533164087] 'process raft request' (duration: 250.473152ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:36.777976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.619352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T17:36:36.77802Z","caller":"traceutil/trace.go:171","msg":"trace[1666609124] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:999; }","duration":"229.695774ms","start":"2026-07-24T17:36:36.548312Z","end":"2026-07-24T17:36:36.778008Z","steps":["trace[1666609124] 'agreement among raft nodes before linearized reading' (duration: 229.53874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:36.778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.495478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-24T17:36:36.778033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.602317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T17:36:36.778075Z","caller":"traceutil/trace.go:171","msg":"trace[1612529403] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:999; }","duration":"229.623282ms","start":"2026-07-24T17:36:36.548435Z","end":"2026-07-24T17:36:36.778058Z","steps":["trace[1612529403] 'agreement among raft nodes before linearized reading' (duration: 229.432476ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:36:36.778095Z","caller":"traceutil/trace.go:171","msg":"trace[1145502496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:999; }","duration":"230.905305ms","start":"2026-07-24T17:36:36.547178Z","end":"2026-07-24T17:36:36.778083Z","steps":["trace[1145502496] 'agreement among raft nodes before linearized reading' (duration: 230.623117ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:36:37.129981Z","caller":"traceutil/trace.go:171","msg":"trace[2108419140] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"348.708288ms","start":"2026-07-24T17:36:36.781236Z","end":"2026-07-24T17:36:37.129944Z","steps":["trace[2108419140] 'process raft request' (duration: 346.493171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:37.130143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:36.781215Z","time spent":"348.844951ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:36:37.132401Z","caller":"traceutil/trace.go:171","msg":"trace[2031529382] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"350.395012ms","start":"2026-07-24T17:36:36.781977Z","end":"2026-07-24T17:36:37.132372Z","steps":["trace[2031529382] 'process raft request' (duration: 350.209888ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:36:37.132581Z","caller":"traceutil/trace.go:171","msg":"trace[504871426] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"350.495765ms","start":"2026-07-24T17:36:36.782063Z","end":"2026-07-24T17:36:37.132559Z","steps":["trace[504871426] 'process raft request' (duration: 350.267189ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:37.132683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:36.782039Z","time spent":"350.591707ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:36:37.132576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:36.781951Z","time spent":"350.523406ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:36:43.664663Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515054457084336,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:36:44.165246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515054457084336,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:36:44.666095Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515054457084336,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:36:44.865517Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.060082387s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T17:36:44.86608Z","caller":"traceutil/trace.go:171","msg":"trace[1024777697] transaction","detail":"{read_only:false; response_revision:1022; number_of_response:1; }","duration":"2.060707114s","start":"2026-07-24T17:36:42.805336Z","end":"2026-07-24T17:36:44.866043Z","steps":["trace[1024777697] 'process raft request' (duration: 2.060440917s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:44.866305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:42.805321Z","time spent":"2.060868589s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:36:45.301533Z","caller":"traceutil/trace.go:171","msg":"trace[1739604594] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1053; }","duration":"2.137461913s","start":"2026-07-24T17:36:43.164049Z","end":"2026-07-24T17:36:45.301511Z","steps":["trace[1739604594] 'read index received' (duration: 1.701725269s)","trace[1739604594] 'applied index is now lower than readState.Index' (duration: 435.732514ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:36:45.301566Z","caller":"traceutil/trace.go:171","msg":"trace[1367670369] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"1.838625018s","start":"2026-07-24T17:36:43.462906Z","end":"2026-07-24T17:36:45.301531Z","steps":["trace[1367670369] 'process raft request' (duration: 1.769474845s)","trace[1367670369] 'compare' (duration: 68.983248ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:36:45.301707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:43.462883Z","time spent":"1.83874416s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:36:45.301717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.030046599s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T17:36:45.301753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.13229551s","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-24T17:36:45.301758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169652307s","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-24T17:36:45.30178Z","caller":"traceutil/trace.go:171","msg":"trace[1112751241] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1023; }","duration":"2.030121342s","start":"2026-07-24T17:36:43.271643Z","end":"2026-07-24T17:36:45.301765Z","steps":["trace[1112751241] 'agreement among raft nodes before linearized reading' (duration: 2.030007049s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:36:45.301783Z","caller":"traceutil/trace.go:171","msg":"trace[1896232629] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1023; }","duration":"2.132383782s","start":"2026-07-24T17:36:43.169391Z","end":"2026-07-24T17:36:45.301775Z","steps":["trace[1896232629] 'agreement among raft nodes before linearized reading' (duration: 2.132230298s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:45.301814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:43.271533Z","time spent":"2.030273456s","remote":"127.0.0.1:50482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-07-24T17:36:45.301814Z","caller":"traceutil/trace.go:171","msg":"trace[833358293] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1023; }","duration":"1.16974408s","start":"2026-07-24T17:36:44.132054Z","end":"2026-07-24T17:36:45.301798Z","steps":["trace[833358293] 'agreement among raft nodes before linearized reading' (duration: 1.169570685s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:45.301826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:43.16938Z","time spent":"2.132430603s","remote":"127.0.0.1:50508","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-24T17:36:45.3018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.13772319s","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-24T17:36:45.301882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.708142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:36:45.301901Z","caller":"traceutil/trace.go:171","msg":"trace[1602600548] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1023; }","duration":"2.137871034s","start":"2026-07-24T17:36:43.164006Z","end":"2026-07-24T17:36:45.301877Z","steps":["trace[1602600548] 'agreement among raft nodes before linearized reading' (duration: 2.137568426s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:36:45.30191Z","caller":"traceutil/trace.go:171","msg":"trace[164209267] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1023; }","duration":"624.776274ms","start":"2026-07-24T17:36:44.677123Z","end":"2026-07-24T17:36:45.301899Z","steps":["trace[164209267] 'agreement among raft nodes before linearized reading' (duration: 624.737353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:45.301951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:43.163991Z","time spent":"2.137951236s","remote":"127.0.0.1:50508","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-24T17:36:45.301954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:44.677106Z","time spent":"624.835416ms","remote":"127.0.0.1:45530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:36:45.30196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.895792018s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T17:36:45.301972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.13233676s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-24T17:36:45.302001Z","caller":"traceutil/trace.go:171","msg":"trace[1272158990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"1.895892922s","start":"2026-07-24T17:36:43.406097Z","end":"2026-07-24T17:36:45.30199Z","steps":["trace[1272158990] 'agreement among raft nodes before linearized reading' (duration: 1.895827741s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:45.301843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:44.132037Z","time spent":"1.169799761s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T17:36:45.301982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.173129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-24T17:36:45.302034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:43.406039Z","time spent":"1.895989424s","remote":"127.0.0.1:50262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T17:36:45.30203Z","caller":"traceutil/trace.go:171","msg":"trace[504104327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1023; }","duration":"2.132438583s","start":"2026-07-24T17:36:43.169575Z","end":"2026-07-24T17:36:45.302014Z","steps":["trace[504104327] 'agreement among raft nodes before linearized reading' (duration: 2.132195587s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:36:45.302062Z","caller":"traceutil/trace.go:171","msg":"trace[317679009] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:1023; }","duration":"298.287561ms","start":"2026-07-24T17:36:45.003756Z","end":"2026-07-24T17:36:45.302044Z","steps":["trace[317679009] 'agreement among raft nodes before linearized reading' (duration: 298.033144ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:36:45.302076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:36:43.169552Z","time spent":"2.132510865s","remote":"127.0.0.1:50508","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-24T17:37:05.631816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.327124ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:37:05.631909Z","caller":"traceutil/trace.go:171","msg":"trace[2100204786] linearizableReadLoop","detail":"{readStateIndex:1128; appliedIndex:1127; }","duration":"200.421247ms","start":"2026-07-24T17:37:05.431475Z","end":"2026-07-24T17:37:05.631897Z","steps":["trace[2100204786] 'read index received' (duration: 52.995179ms)","trace[2100204786] 'applied index is now lower than readState.Index' (duration: 147.425217ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:37:05.632152Z","caller":"traceutil/trace.go:171","msg":"trace[455775312] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"201.291789ms","start":"2026-07-24T17:37:05.43085Z","end":"2026-07-24T17:37:05.632142Z","steps":["trace[455775312] 'process raft request' (duration: 53.566874ms)","trace[455775312] 'compare' (duration: 147.220522ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:05.632329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.856738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T17:37:05.632357Z","caller":"traceutil/trace.go:171","msg":"trace[751257356] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1091; }","duration":"200.92248ms","start":"2026-07-24T17:37:05.431427Z","end":"2026-07-24T17:37:05.63235Z","steps":["trace[751257356] 'agreement among raft nodes before linearized reading' (duration: 200.834607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:05.632514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.862285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T17:37:05.632592Z","caller":"traceutil/trace.go:171","msg":"trace[1744536456] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1091; }","duration":"176.996188ms","start":"2026-07-24T17:37:05.455585Z","end":"2026-07-24T17:37:05.632581Z","steps":["trace[1744536456] 'agreement among raft nodes before linearized reading' (duration: 176.788373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:05.632706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.519048ms","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-24T17:37:05.63327Z","caller":"traceutil/trace.go:171","msg":"trace[36829719] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1091; }","duration":"184.132603ms","start":"2026-07-24T17:37:05.449106Z","end":"2026-07-24T17:37:05.633239Z","steps":["trace[36829719] 'agreement among raft nodes before linearized reading' (duration: 183.291571ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:05.788077Z","caller":"traceutil/trace.go:171","msg":"trace[1860050848] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"152.381746ms","start":"2026-07-24T17:37:05.635671Z","end":"2026-07-24T17:37:05.788053Z","steps":["trace[1860050848] 'process raft request' (duration: 103.757391ms)","trace[1860050848] 'compare' (duration: 48.235465ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:37:05.788144Z","caller":"traceutil/trace.go:171","msg":"trace[216548425] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"150.961119ms","start":"2026-07-24T17:37:05.637167Z","end":"2026-07-24T17:37:05.788128Z","steps":["trace[216548425] 'process raft request' (duration: 150.889918ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:05.788409Z","caller":"traceutil/trace.go:171","msg":"trace[1236883678] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"152.357405ms","start":"2026-07-24T17:37:05.636044Z","end":"2026-07-24T17:37:05.788401Z","steps":["trace[1236883678] 'process raft request' (duration: 151.906114ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:08.596038Z","caller":"traceutil/trace.go:171","msg":"trace[601724364] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"765.541848ms","start":"2026-07-24T17:37:07.830475Z","end":"2026-07-24T17:37:08.596017Z","steps":["trace[601724364] 'process raft request' (duration: 765.414534ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:08.596166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:07.830462Z","time spent":"765.63868ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:37:08.760298Z","caller":"traceutil/trace.go:171","msg":"trace[1190890628] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"928.210732ms","start":"2026-07-24T17:37:07.832075Z","end":"2026-07-24T17:37:08.760286Z","steps":["trace[1190890628] 'process raft request' (duration: 928.093359ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:08.760291Z","caller":"traceutil/trace.go:171","msg":"trace[709450790] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"928.780906ms","start":"2026-07-24T17:37:07.831478Z","end":"2026-07-24T17:37:08.760259Z","steps":["trace[709450790] 'process raft request' (duration: 899.064932ms)","trace[709450790] 'compare' (duration: 29.403786ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:08.760365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.573735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:37:08.760415Z","caller":"traceutil/trace.go:171","msg":"trace[1696184767] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1101; }","duration":"456.671008ms","start":"2026-07-24T17:37:08.303727Z","end":"2026-07-24T17:37:08.760398Z","steps":["trace[1696184767] 'agreement among raft nodes before linearized reading' (duration: 456.592586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:08.760424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:07.831451Z","time spent":"928.89795ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:37:08.760271Z","caller":"traceutil/trace.go:171","msg":"trace[83275541] linearizableReadLoop","detail":"{readStateIndex:1138; appliedIndex:1135; }","duration":"456.462282ms","start":"2026-07-24T17:37:08.303781Z","end":"2026-07-24T17:37:08.760243Z","steps":["trace[83275541] 'read index received' (duration: 292.178545ms)","trace[83275541] 'applied index is now lower than readState.Index' (duration: 164.282667ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:08.760476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:08.303705Z","time spent":"456.752139ms","remote":"127.0.0.1:45422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:37:08.760626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:07.832066Z","time spent":"928.272713ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:37:08.760684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.653921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:37:08.760745Z","caller":"traceutil/trace.go:171","msg":"trace[474438218] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1101; }","duration":"354.799115ms","start":"2026-07-24T17:37:08.405934Z","end":"2026-07-24T17:37:08.760733Z","steps":["trace[474438218] 'agreement among raft nodes before linearized reading' (duration: 354.664332ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:08.760789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:08.405909Z","time spent":"354.872667ms","remote":"127.0.0.1:50262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T17:37:10.451199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.414829ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-24T17:37:10.451327Z","caller":"traceutil/trace.go:171","msg":"trace[427442264] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1141; }","duration":"146.823503ms","start":"2026-07-24T17:37:10.304489Z","end":"2026-07-24T17:37:10.451313Z","steps":["trace[427442264] 'read index received' (duration: 85.063µs)","trace[427442264] 'applied index is now lower than readState.Index' (duration: 146.73712ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:10.451438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.931295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:37:10.451505Z","caller":"traceutil/trace.go:171","msg":"trace[1377350887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1104; }","duration":"147.056728ms","start":"2026-07-24T17:37:10.304433Z","end":"2026-07-24T17:37:10.45149Z","steps":["trace[1377350887] 'agreement among raft nodes before linearized reading' (duration: 146.938275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:11.101131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.87783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T17:37:11.101207Z","caller":"traceutil/trace.go:171","msg":"trace[313511178] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1105; }","duration":"337.015314ms","start":"2026-07-24T17:37:10.764176Z","end":"2026-07-24T17:37:11.101192Z","steps":["trace[313511178] 'range keys from in-memory index tree' (duration: 336.689985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:11.101238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:10.764153Z","time spent":"337.078485ms","remote":"127.0.0.1:50508","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-24T17:37:11.101491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.708249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-24T17:37:11.101528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.039593ms","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-24T17:37:11.101563Z","caller":"traceutil/trace.go:171","msg":"trace[1899946932] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1105; }","duration":"335.808712ms","start":"2026-07-24T17:37:10.765738Z","end":"2026-07-24T17:37:11.101546Z","steps":["trace[1899946932] 'range keys from in-memory index tree' (duration: 335.188025ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:11.101594Z","caller":"traceutil/trace.go:171","msg":"trace[1062977376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1105; }","duration":"501.148726ms","start":"2026-07-24T17:37:10.600427Z","end":"2026-07-24T17:37:11.101576Z","steps":["trace[1062977376] 'range keys from in-memory index tree' (duration: 500.941161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:11.101604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:10.76573Z","time spent":"335.865653ms","remote":"127.0.0.1:50508","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-24T17:37:11.101629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:10.600409Z","time spent":"501.211447ms","remote":"127.0.0.1:50508","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-24T17:37:11.299338Z","caller":"traceutil/trace.go:171","msg":"trace[726109915] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"193.993269ms","start":"2026-07-24T17:37:11.105321Z","end":"2026-07-24T17:37:11.299315Z","steps":["trace[726109915] 'process raft request' (duration: 193.861516ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:11.374427Z","caller":"traceutil/trace.go:171","msg":"trace[1900537051] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"268.824697ms","start":"2026-07-24T17:37:11.105577Z","end":"2026-07-24T17:37:11.374402Z","steps":["trace[1900537051] 'process raft request' (duration: 268.616562ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:11.374924Z","caller":"traceutil/trace.go:171","msg":"trace[172155556] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"269.188777ms","start":"2026-07-24T17:37:11.105704Z","end":"2026-07-24T17:37:11.374893Z","steps":["trace[172155556] 'process raft request' (duration: 268.659523ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:11.374934Z","caller":"traceutil/trace.go:171","msg":"trace[484261674] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"268.962141ms","start":"2026-07-24T17:37:11.105946Z","end":"2026-07-24T17:37:11.374908Z","steps":["trace[484261674] 'process raft request' (duration: 268.832778ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:25.559286Z","caller":"traceutil/trace.go:171","msg":"trace[864195579] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1246; }","duration":"119.703286ms","start":"2026-07-24T17:37:25.439554Z","end":"2026-07-24T17:37:25.559257Z","steps":["trace[864195579] 'read index received' (duration: 44.928299ms)","trace[864195579] 'applied index is now lower than readState.Index' (duration: 74.773767ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:37:25.559404Z","caller":"traceutil/trace.go:171","msg":"trace[455345885] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"121.055421ms","start":"2026-07-24T17:37:25.43832Z","end":"2026-07-24T17:37:25.559376Z","steps":["trace[455345885] 'process raft request' (duration: 114.674165ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:25.55952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.945273ms","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-24T17:37:25.559571Z","caller":"traceutil/trace.go:171","msg":"trace[114285491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1205; }","duration":"120.056005ms","start":"2026-07-24T17:37:25.439501Z","end":"2026-07-24T17:37:25.559557Z","steps":["trace[114285491] 'agreement among raft nodes before linearized reading' (duration: 119.863231ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:26.292083Z","caller":"traceutil/trace.go:171","msg":"trace[63967441] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"114.835849ms","start":"2026-07-24T17:37:26.177206Z","end":"2026-07-24T17:37:26.292042Z","steps":["trace[63967441] 'process raft request' (duration: 114.575503ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:29.508606Z","caller":"traceutil/trace.go:171","msg":"trace[1833170908] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"102.843597ms","start":"2026-07-24T17:37:29.405746Z","end":"2026-07-24T17:37:29.508589Z","steps":["trace[1833170908] 'read index received' (duration: 102.744434ms)","trace[1833170908] 'applied index is now lower than readState.Index' (duration: 98.533µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:37:29.508685Z","caller":"traceutil/trace.go:171","msg":"trace[346676416] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"117.532149ms","start":"2026-07-24T17:37:29.391146Z","end":"2026-07-24T17:37:29.508678Z","steps":["trace[346676416] 'process raft request' (duration: 117.324264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:29.508855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.088444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:37:29.509042Z","caller":"traceutil/trace.go:171","msg":"trace[1112522628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"103.244698ms","start":"2026-07-24T17:37:29.405729Z","end":"2026-07-24T17:37:29.508974Z","steps":["trace[1112522628] 'agreement among raft nodes before linearized reading' (duration: 103.075513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:30.076493Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515054457084808,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-24T17:37:30.446447Z","caller":"traceutil/trace.go:171","msg":"trace[1645784126] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"934.327231ms","start":"2026-07-24T17:37:29.512094Z","end":"2026-07-24T17:37:30.446421Z","steps":["trace[1645784126] 'process raft request' (duration: 906.513267ms)","trace[1645784126] 'compare' (duration: 27.715881ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:30.446574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:29.512069Z","time spent":"934.442174ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:37:30.465244Z","caller":"traceutil/trace.go:171","msg":"trace[1893877197] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"893.612702ms","start":"2026-07-24T17:37:29.571608Z","end":"2026-07-24T17:37:30.465221Z","steps":["trace[1893877197] 'process raft request' (duration: 893.439627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:30.465361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:29.571585Z","time spent":"893.716385ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:37:30.498159Z","caller":"traceutil/trace.go:171","msg":"trace[179757336] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1259; }","duration":"922.195895ms","start":"2026-07-24T17:37:29.575931Z","end":"2026-07-24T17:37:30.498127Z","steps":["trace[179757336] 'read index received' (duration: 842.681245ms)","trace[179757336] 'applied index is now lower than readState.Index' (duration: 79.51307ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:30.498376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.425531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T17:37:30.49845Z","caller":"traceutil/trace.go:171","msg":"trace[1024060821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1218; }","duration":"922.546124ms","start":"2026-07-24T17:37:29.575888Z","end":"2026-07-24T17:37:30.498434Z","steps":["trace[1024060821] 'agreement among raft nodes before linearized reading' (duration: 922.335439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:30.498486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.40957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-24T17:37:30.498499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:29.575867Z","time spent":"922.623967ms","remote":"127.0.0.1:50508","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-24T17:37:30.498525Z","caller":"traceutil/trace.go:171","msg":"trace[858575708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1218; }","duration":"716.46722ms","start":"2026-07-24T17:37:29.782046Z","end":"2026-07-24T17:37:30.498513Z","steps":["trace[858575708] 'agreement among raft nodes before linearized reading' (duration: 716.389698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:30.498556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:29.78203Z","time spent":"716.518902ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T17:37:30.498396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.431896ms","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-24T17:37:30.498629Z","caller":"traceutil/trace.go:171","msg":"trace[2116071947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1218; }","duration":"911.696792ms","start":"2026-07-24T17:37:29.586922Z","end":"2026-07-24T17:37:30.498618Z","steps":["trace[2116071947] 'agreement among raft nodes before linearized reading' (duration: 911.355333ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:30.498659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:29.586915Z","time spent":"911.735623ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-24T17:37:42.248921Z","caller":"traceutil/trace.go:171","msg":"trace[1536678875] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"690.856963ms","start":"2026-07-24T17:37:41.558048Z","end":"2026-07-24T17:37:42.248905Z","steps":["trace[1536678875] 'process raft request' (duration: 690.767561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:42.249017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:41.558034Z","time spent":"690.931645ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:37:43.147036Z","caller":"traceutil/trace.go:171","msg":"trace[951693825] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1304; }","duration":"491.440572ms","start":"2026-07-24T17:37:42.655573Z","end":"2026-07-24T17:37:43.147014Z","steps":["trace[951693825] 'read index received' (duration: 491.261318ms)","trace[951693825] 'applied index is now lower than readState.Index' (duration: 178.354µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:37:43.147172Z","caller":"traceutil/trace.go:171","msg":"trace[1258287963] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"570.948252ms","start":"2026-07-24T17:37:42.576182Z","end":"2026-07-24T17:37:43.14713Z","steps":["trace[1258287963] 'process raft request' (duration: 570.704696ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:43.147261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.665878ms","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-24T17:37:43.147302Z","caller":"traceutil/trace.go:171","msg":"trace[66280049] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1259; }","duration":"491.759171ms","start":"2026-07-24T17:37:42.655533Z","end":"2026-07-24T17:37:43.147292Z","steps":["trace[66280049] 'agreement among raft nodes before linearized reading' (duration: 491.597806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:43.147311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:42.576171Z","time spent":"571.066395ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:37:43.147344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:42.655515Z","time spent":"491.818242ms","remote":"127.0.0.1:50508","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-24T17:37:43.147348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.824882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-24T17:37:43.147406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.761542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:37:43.147423Z","caller":"traceutil/trace.go:171","msg":"trace[981918929] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1259; }","duration":"476.962845ms","start":"2026-07-24T17:37:42.670442Z","end":"2026-07-24T17:37:43.147404Z","steps":["trace[981918929] 'agreement among raft nodes before linearized reading' (duration: 476.74366ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:43.147503Z","caller":"traceutil/trace.go:171","msg":"trace[1166416230] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1259; }","duration":"463.894426ms","start":"2026-07-24T17:37:42.683591Z","end":"2026-07-24T17:37:43.147486Z","steps":["trace[1166416230] 'agreement among raft nodes before linearized reading' (duration: 463.771712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:43.14753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:42.67042Z","time spent":"477.096099ms","remote":"127.0.0.1:50508","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-24T17:37:43.147547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:42.683573Z","time spent":"463.962977ms","remote":"127.0.0.1:50550","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:37:44.783547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.721347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:37:44.783645Z","caller":"traceutil/trace.go:171","msg":"trace[1923726577] transaction","detail":"{read_only:false; response_revision:1264; number_of_response:1; }","duration":"163.445644ms","start":"2026-07-24T17:37:44.620181Z","end":"2026-07-24T17:37:44.783627Z","steps":["trace[1923726577] 'process raft request' (duration: 48.563053ms)","trace[1923726577] 'compare' (duration: 114.606684ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:37:45.509248Z","caller":"traceutil/trace.go:171","msg":"trace[1990428905] linearizableReadLoop","detail":"{readStateIndex:1311; appliedIndex:1310; }","duration":"356.88975ms","start":"2026-07-24T17:37:45.152333Z","end":"2026-07-24T17:37:45.509223Z","steps":["trace[1990428905] 'read index received' (duration: 356.766387ms)","trace[1990428905] 'applied index is now lower than readState.Index' (duration: 121.563µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:45.509311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:45.00683Z","time spent":"502.47012ms","remote":"127.0.0.1:50288","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-24T17:37:45.509483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.128876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T17:37:45.509518Z","caller":"traceutil/trace.go:171","msg":"trace[1738451022] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1264; }","duration":"357.238968ms","start":"2026-07-24T17:37:45.152271Z","end":"2026-07-24T17:37:45.50951Z","steps":["trace[1738451022] 'agreement among raft nodes before linearized reading' (duration: 357.094724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:45.509548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:45.152239Z","time spent":"357.30172ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T17:37:45.509931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.86638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-24T17:37:45.50993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.707063ms","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-24T17:37:45.510014Z","caller":"traceutil/trace.go:171","msg":"trace[340561447] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1264; }","duration":"339.829487ms","start":"2026-07-24T17:37:45.170171Z","end":"2026-07-24T17:37:45.51Z","steps":["trace[340561447] 'agreement among raft nodes before linearized reading' (duration: 339.59069ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:45.510013Z","caller":"traceutil/trace.go:171","msg":"trace[65581713] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1264; }","duration":"338.977224ms","start":"2026-07-24T17:37:45.171014Z","end":"2026-07-24T17:37:45.509991Z","steps":["trace[65581713] 'agreement among raft nodes before linearized reading' (duration: 338.750538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:45.510057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:45.17016Z","time spent":"339.888898ms","remote":"127.0.0.1:50508","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-24T17:37:45.510062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:45.171005Z","time spent":"339.046786ms","remote":"127.0.0.1:50508","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-24T17:37:50.916049Z","caller":"traceutil/trace.go:171","msg":"trace[1423984677] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"269.607028ms","start":"2026-07-24T17:37:50.646421Z","end":"2026-07-24T17:37:50.916028Z","steps":["trace[1423984677] 'process raft request' (duration: 269.28446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:51.583166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.1001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:37:51.583294Z","caller":"traceutil/trace.go:171","msg":"trace[975490368] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1358; }","duration":"559.559205ms","start":"2026-07-24T17:37:51.023716Z","end":"2026-07-24T17:37:51.583275Z","steps":["trace[975490368] 'read index received' (duration: 440.2444ms)","trace[975490368] 'applied index is now lower than readState.Index' (duration: 119.313495ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:37:51.583338Z","caller":"traceutil/trace.go:171","msg":"trace[1191040940] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"663.500812ms","start":"2026-07-24T17:37:50.919818Z","end":"2026-07-24T17:37:51.583318Z","steps":["trace[1191040940] 'process raft request' (duration: 544.163385ms)","trace[1191040940] 'compare' (duration: 118.771292ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:51.583405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"559.665969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-24T17:37:51.583429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:50.919796Z","time spent":"663.590454ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:37:51.58347Z","caller":"traceutil/trace.go:171","msg":"trace[1967028272] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1311; }","duration":"559.779942ms","start":"2026-07-24T17:37:51.023677Z","end":"2026-07-24T17:37:51.583457Z","steps":["trace[1967028272] 'agreement among raft nodes before linearized reading' (duration: 559.645258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:51.583514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:51.023641Z","time spent":"559.863094ms","remote":"127.0.0.1:50436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:37:51.583415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.508544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:37:51.583642Z","caller":"traceutil/trace.go:171","msg":"trace[256203896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1311; }","duration":"179.767591ms","start":"2026-07-24T17:37:51.403864Z","end":"2026-07-24T17:37:51.583631Z","steps":["trace[256203896] 'agreement among raft nodes before linearized reading' (duration: 179.514614ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:52.024817Z","caller":"traceutil/trace.go:171","msg":"trace[495639173] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"437.371786ms","start":"2026-07-24T17:37:51.587417Z","end":"2026-07-24T17:37:52.024789Z","steps":["trace[495639173] 'process raft request' (duration: 437.161051ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:52.025016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:51.587399Z","time spent":"437.524621ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:37:52.698294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.41683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:37:52.699278Z","caller":"traceutil/trace.go:171","msg":"trace[926150277] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1359; }","duration":"797.714198ms","start":"2026-07-24T17:37:51.90155Z","end":"2026-07-24T17:37:52.699264Z","steps":["trace[926150277] 'read index received' (duration: 123.112315ms)","trace[926150277] 'applied index is now lower than readState.Index' (duration: 674.601033ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:52.699479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.938175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T17:37:52.699472Z","caller":"traceutil/trace.go:171","msg":"trace[805464727] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"931.694617ms","start":"2026-07-24T17:37:51.767752Z","end":"2026-07-24T17:37:52.699447Z","steps":["trace[805464727] 'process raft request' (duration: 931.476301ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:52.699504Z","caller":"traceutil/trace.go:171","msg":"trace[1489420084] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1315; }","duration":"798.019716ms","start":"2026-07-24T17:37:51.901475Z","end":"2026-07-24T17:37:52.699495Z","steps":["trace[1489420084] 'agreement among raft nodes before linearized reading' (duration: 797.963685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:52.699532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:51.901442Z","time spent":"798.083378ms","remote":"127.0.0.1:50310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":31,"response size":30,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:37:52.699601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:51.767735Z","time spent":"931.792329ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:37:52.699697Z","caller":"traceutil/trace.go:171","msg":"trace[370772175] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"1.111457827s","start":"2026-07-24T17:37:51.58823Z","end":"2026-07-24T17:37:52.699688Z","steps":["trace[370772175] 'process raft request' (duration: 539.518427ms)","trace[370772175] 'compare' (duration: 570.325898ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:52.699737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:51.588202Z","time spent":"1.111512098s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:37:52.699799Z","caller":"traceutil/trace.go:171","msg":"trace[883801080] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"1.110978205s","start":"2026-07-24T17:37:51.588814Z","end":"2026-07-24T17:37:52.699792Z","steps":["trace[883801080] 'process raft request' (duration: 1.110350039s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:52.699846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:51.588804Z","time spent":"1.111015336s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:37:53.261356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.557295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:37:53.261476Z","caller":"traceutil/trace.go:171","msg":"trace[1721193111] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1363; }","duration":"562.151696ms","start":"2026-07-24T17:37:52.699304Z","end":"2026-07-24T17:37:53.261455Z","steps":["trace[1721193111] 'read index received' (duration: 125.328703ms)","trace[1721193111] 'applied index is now lower than readState.Index' (duration: 436.820773ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:53.261709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"882.887486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T17:37:53.261741Z","caller":"traceutil/trace.go:171","msg":"trace[2072379678] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1316; }","duration":"882.962168ms","start":"2026-07-24T17:37:52.378768Z","end":"2026-07-24T17:37:53.26173Z","steps":["trace[2072379678] 'agreement among raft nodes before linearized reading' (duration: 882.768203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:53.261776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:52.378747Z","time spent":"883.02087ms","remote":"127.0.0.1:50508","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-24T17:37:53.261787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.830937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:37:53.261859Z","caller":"traceutil/trace.go:171","msg":"trace[1669794771] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"623.038281ms","start":"2026-07-24T17:37:52.638804Z","end":"2026-07-24T17:37:53.261842Z","steps":["trace[1669794771] 'process raft request' (duration: 185.90381ms)","trace[1669794771] 'compare' (duration: 436.282608ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:53.26188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"922.714473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T17:37:53.261914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:52.638779Z","time spent":"623.107863ms","remote":"127.0.0.1:50330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:37:53.261917Z","caller":"traceutil/trace.go:171","msg":"trace[854004164] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1316; }","duration":"857.008832ms","start":"2026-07-24T17:37:52.404873Z","end":"2026-07-24T17:37:53.261882Z","steps":["trace[854004164] 'agreement among raft nodes before linearized reading' (duration: 856.618232ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:53.261971Z","caller":"traceutil/trace.go:171","msg":"trace[1007183069] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1316; }","duration":"922.823345ms","start":"2026-07-24T17:37:52.339128Z","end":"2026-07-24T17:37:53.261952Z","steps":["trace[1007183069] 'agreement among raft nodes before linearized reading' (duration: 922.654171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:53.26199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:52.404853Z","time spent":"857.122664ms","remote":"127.0.0.1:50262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T17:37:53.262029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:52.33907Z","time spent":"922.948269ms","remote":"127.0.0.1:50536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:37:53.761859Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515054457085027,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:37:54.087171Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.163189303s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-24T17:37:54.521813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.318927ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:37:54.521914Z","caller":"traceutil/trace.go:171","msg":"trace[1702866537] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"1.598040834s","start":"2026-07-24T17:37:52.923855Z","end":"2026-07-24T17:37:54.521896Z","steps":["trace[1702866537] 'process raft request' (duration: 1.163559882s)","trace[1702866537] 'compare' (duration: 434.174884ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:54.521972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:52.923831Z","time spent":"1.598114216s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:37:55.264442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999907187s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-24T17:37:55.264979Z","caller":"traceutil/trace.go:171","msg":"trace[937227280] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000509772s","start":"2026-07-24T17:37:53.264442Z","end":"2026-07-24T17:37:55.264952Z","steps":["trace[937227280] 'agreement among raft nodes before linearized reading' (duration: 1.99966512s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:55.265093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:53.264421Z","time spent":"2.000638565s","remote":"127.0.0.1:50270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/24 17:37:55 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-24T17:37:56.583556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.891765511s","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-24T17:37:56.58372Z","caller":"traceutil/trace.go:171","msg":"trace[1226682961] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1317; }","duration":"1.891928535s","start":"2026-07-24T17:37:54.691756Z","end":"2026-07-24T17:37:56.583685Z","steps":["trace[1226682961] 'range keys from in-memory index tree' (duration: 1.891746531s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:56.583894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.933649031s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:37:56.584029Z","caller":"traceutil/trace.go:171","msg":"trace[1383503472] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"1.316638768s","start":"2026-07-24T17:37:55.267377Z","end":"2026-07-24T17:37:56.584016Z","steps":["trace[1383503472] 'process raft request' (duration: 1.316587656s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:56.584069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:55.267355Z","time spent":"1.316698199s","remote":"127.0.0.1:50330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:37:56.584206Z","caller":"traceutil/trace.go:171","msg":"trace[2128423823] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"3.317080438s","start":"2026-07-24T17:37:53.267118Z","end":"2026-07-24T17:37:56.584199Z","steps":["trace[2128423823] 'process raft request' (duration: 1.382938523s)","trace[2128423823] 'compare' (duration: 1.933436546s)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:56.58424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:53.267102Z","time spent":"3.317119499s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:37:56.584328Z","caller":"traceutil/trace.go:171","msg":"trace[615150037] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1364; }","duration":"3.322815717s","start":"2026-07-24T17:37:53.261506Z","end":"2026-07-24T17:37:56.584321Z","steps":["trace[615150037] 'read index received' (duration: 825.889971ms)","trace[615150037] 'applied index is now lower than readState.Index' (duration: 2.496924896s)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:56.584392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.569718926s","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-24T17:37:56.584441Z","caller":"traceutil/trace.go:171","msg":"trace[531060632] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1319; }","duration":"3.569806818s","start":"2026-07-24T17:37:53.014628Z","end":"2026-07-24T17:37:56.584435Z","steps":["trace[531060632] 'agreement among raft nodes before linearized reading' (duration: 3.569729866s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:56.584465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:53.014608Z","time spent":"3.56985077s","remote":"127.0.0.1:45416","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:37:57.084768Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515054457085031,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:37:57.271873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000463671s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-24T17:37:57.271968Z","caller":"traceutil/trace.go:171","msg":"trace[1181546145] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000640055s","start":"2026-07-24T17:37:55.271308Z","end":"2026-07-24T17:37:57.271948Z","steps":["trace[1181546145] 'agreement among raft nodes before linearized reading' (duration: 2.000514832s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:57.272018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:55.271295Z","time spent":"2.000713398s","remote":"127.0.0.1:50262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T17:37:58.37746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"871.220681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:37:58.377717Z","caller":"traceutil/trace.go:171","msg":"trace[1615506056] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"1.125884752s","start":"2026-07-24T17:37:57.251815Z","end":"2026-07-24T17:37:58.3777Z","steps":["trace[1615506056] 'process raft request' (duration: 1.12582537s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:58.378008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:57.251772Z","time spent":"1.126190549s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:37:58.378638Z","caller":"traceutil/trace.go:171","msg":"trace[493475147] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"1.850766942s","start":"2026-07-24T17:37:56.527855Z","end":"2026-07-24T17:37:58.378622Z","steps":["trace[493475147] 'process raft request' (duration: 978.303779ms)","trace[493475147] 'compare' (duration: 870.76836ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:58.378766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:56.527826Z","time spent":"1.850899276s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:37:58.379198Z","caller":"traceutil/trace.go:171","msg":"trace[469046909] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1367; }","duration":"1.794801616s","start":"2026-07-24T17:37:56.584341Z","end":"2026-07-24T17:37:58.379143Z","steps":["trace[469046909] 'read index received' (duration: 921.833709ms)","trace[469046909] 'applied index is now lower than readState.Index' (duration: 872.963207ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:58.379334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.676151846s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T17:37:58.379416Z","caller":"traceutil/trace.go:171","msg":"trace[1225025413] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1321; }","duration":"3.676362531s","start":"2026-07-24T17:37:54.703034Z","end":"2026-07-24T17:37:58.379397Z","steps":["trace[1225025413] 'agreement among raft nodes before linearized reading' (duration: 3.676120495s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:58.379458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:54.703005Z","time spent":"3.676444764s","remote":"127.0.0.1:50508","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-24T17:37:58.379473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.843123264s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T17:37:58.379619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.674019081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T17:37:58.379681Z","caller":"traceutil/trace.go:171","msg":"trace[1689862496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1321; }","duration":"3.674121073s","start":"2026-07-24T17:37:54.70555Z","end":"2026-07-24T17:37:58.379671Z","steps":["trace[1689862496] 'agreement among raft nodes before linearized reading' (duration: 3.67400371s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:58.379717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:54.70553Z","time spent":"3.674178875s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T17:37:58.379735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.378380058s","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-24T17:37:58.379786Z","caller":"traceutil/trace.go:171","msg":"trace[144582186] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1321; }","duration":"4.37845617s","start":"2026-07-24T17:37:54.001311Z","end":"2026-07-24T17:37:58.379768Z","steps":["trace[144582186] 'agreement among raft nodes before linearized reading' (duration: 4.378341337s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:58.379616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.350624177s","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-24T17:37:58.379787Z","caller":"traceutil/trace.go:171","msg":"trace[803983249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1321; }","duration":"1.843474544s","start":"2026-07-24T17:37:56.536259Z","end":"2026-07-24T17:37:58.379734Z","steps":["trace[803983249] 'agreement among raft nodes before linearized reading' (duration: 1.843018011s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:58.379905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:56.536238Z","time spent":"1.843651298s","remote":"127.0.0.1:50262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T17:37:58.379941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.791802992s","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-24T17:37:58.379999Z","caller":"traceutil/trace.go:171","msg":"trace[268458524] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1321; }","duration":"4.791864843s","start":"2026-07-24T17:37:53.588123Z","end":"2026-07-24T17:37:58.379988Z","steps":["trace[268458524] 'agreement among raft nodes before linearized reading' (duration: 4.79173729s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:58.379992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.32517502s","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-24T17:37:58.380036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:53.588061Z","time spent":"4.791962816s","remote":"127.0.0.1:50508","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-24T17:37:58.380038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.011985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T17:37:58.380087Z","caller":"traceutil/trace.go:171","msg":"trace[726487830] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1321; }","duration":"125.111887ms","start":"2026-07-24T17:37:58.254958Z","end":"2026-07-24T17:37:58.380069Z","steps":["trace[726487830] 'agreement among raft nodes before linearized reading' (duration: 124.995004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:58.38014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.088321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:37:58.380177Z","caller":"traceutil/trace.go:171","msg":"trace[359471083] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1321; }","duration":"873.163883ms","start":"2026-07-24T17:37:57.507004Z","end":"2026-07-24T17:37:58.380168Z","steps":["trace[359471083] 'agreement among raft nodes before linearized reading' (duration: 873.107812ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:58.380223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:57.506979Z","time spent":"873.234334ms","remote":"127.0.0.1:56358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:37:58.379251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.373023154s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-24T17:37:58.380299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.107672618s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:37:58.380318Z","caller":"traceutil/trace.go:171","msg":"trace[2078030559] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:1321; }","duration":"3.374129004s","start":"2026-07-24T17:37:55.006179Z","end":"2026-07-24T17:37:58.380308Z","steps":["trace[2078030559] 'agreement among raft nodes before linearized reading' (duration: 3.372973003s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:58.380357Z","caller":"traceutil/trace.go:171","msg":"trace[2106815992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1321; }","duration":"1.107742309s","start":"2026-07-24T17:37:57.272589Z","end":"2026-07-24T17:37:58.380331Z","steps":["trace[2106815992] 'agreement among raft nodes before linearized reading' (duration: 1.107657487s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:58.380396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:57.272575Z","time spent":"1.107813082s","remote":"127.0.0.1:50270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T17:37:58.379838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:54.00129Z","time spent":"4.378539662s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-07-24T17:37:58.380087Z","caller":"traceutil/trace.go:171","msg":"trace[1231350134] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1321; }","duration":"1.325326004s","start":"2026-07-24T17:37:57.054735Z","end":"2026-07-24T17:37:58.380061Z","steps":["trace[1231350134] 'agreement among raft nodes before linearized reading' (duration: 1.32516573s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:58.380355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:55.006156Z","time spent":"3.374187676s","remote":"127.0.0.1:50288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"warn","ts":"2026-07-24T17:37:58.380654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:57.054713Z","time spent":"1.325925059s","remote":"127.0.0.1:50736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":3,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-07-24T17:37:58.379885Z","caller":"traceutil/trace.go:171","msg":"trace[878833062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1321; }","duration":"4.350941835s","start":"2026-07-24T17:37:54.028908Z","end":"2026-07-24T17:37:58.37985Z","steps":["trace[878833062] 'agreement among raft nodes before linearized reading' (duration: 4.350468192s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:58.38085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:54.028883Z","time spent":"4.35195458s","remote":"127.0.0.1:50508","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-24T17:37:58.380576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.086994453s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T17:37:58.381037Z","caller":"traceutil/trace.go:171","msg":"trace[501267183] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1321; }","duration":"2.087520567s","start":"2026-07-24T17:37:56.293496Z","end":"2026-07-24T17:37:58.381016Z","steps":["trace[501267183] 'agreement among raft nodes before linearized reading' (duration: 2.08687083s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:58.38111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:56.293477Z","time spent":"2.08761334s","remote":"127.0.0.1:50758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:37:58.383221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.874984938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T17:37:58.383379Z","caller":"traceutil/trace.go:171","msg":"trace[132992458] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1321; }","duration":"2.875191643s","start":"2026-07-24T17:37:55.50817Z","end":"2026-07-24T17:37:58.383362Z","steps":["trace[132992458] 'agreement among raft nodes before linearized reading' (duration: 2.87238907s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:58.383465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:55.508148Z","time spent":"2.875300856s","remote":"127.0.0.1:50352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:37:59.114216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.925859ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-07-24T17:37:59.114373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:58.384791Z","time spent":"729.576755ms","remote":"127.0.0.1:50288","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-24T17:37:59.169327Z","caller":"traceutil/trace.go:171","msg":"trace[1539705915] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"784.082102ms","start":"2026-07-24T17:37:58.38521Z","end":"2026-07-24T17:37:59.169292Z","steps":["trace[1539705915] 'process raft request' (duration: 783.717903ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:59.169375Z","caller":"traceutil/trace.go:171","msg":"trace[364988295] linearizableReadLoop","detail":"{readStateIndex:1375; appliedIndex:1369; }","duration":"783.21341ms","start":"2026-07-24T17:37:58.386144Z","end":"2026-07-24T17:37:59.169357Z","steps":["trace[364988295] 'read index received' (duration: 403.69383ms)","trace[364988295] 'applied index is now lower than readState.Index' (duration: 379.5185ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:37:59.169418Z","caller":"traceutil/trace.go:171","msg":"trace[204989391] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"783.934318ms","start":"2026-07-24T17:37:58.38546Z","end":"2026-07-24T17:37:59.169394Z","steps":["trace[204989391] 'process raft request' (duration: 783.729473ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:59.169457Z","caller":"traceutil/trace.go:171","msg":"trace[84333923] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"783.792195ms","start":"2026-07-24T17:37:58.385652Z","end":"2026-07-24T17:37:59.169444Z","steps":["trace[84333923] 'process raft request' (duration: 783.61689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:59.169513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:58.385444Z","time spent":"784.01821ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:37:59.169503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:58.38519Z","time spent":"784.218236ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:37:59.16953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:58.385642Z","time spent":"783.843536ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:37:59.169625Z","caller":"traceutil/trace.go:171","msg":"trace[467598964] transaction","detail":"{read_only:false; response_revision:1323; number_of_response:1; }","duration":"784.295948ms","start":"2026-07-24T17:37:58.38532Z","end":"2026-07-24T17:37:59.169615Z","steps":["trace[467598964] 'process raft request' (duration: 783.813615ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:37:59.169647Z","caller":"traceutil/trace.go:171","msg":"trace[1886747844] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"784.399252ms","start":"2026-07-24T17:37:58.385234Z","end":"2026-07-24T17:37:59.169633Z","steps":["trace[1886747844] 'process raft request' (duration: 784.081372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:59.169685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:58.385289Z","time spent":"784.36115ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:37:59.169723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:58.385199Z","time spent":"784.483294ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:37:59.169816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.665483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18c54a70b587d135\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-07-24T17:37:59.16987Z","caller":"traceutil/trace.go:171","msg":"trace[2004024053] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18c54a70b587d135; range_end:; response_count:1; response_revision:1326; }","duration":"783.746664ms","start":"2026-07-24T17:37:58.386103Z","end":"2026-07-24T17:37:59.16985Z","steps":["trace[2004024053] 'agreement among raft nodes before linearized reading' (duration: 783.59828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:59.169914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:58.386091Z","time spent":"783.808486ms","remote":"127.0.0.1:50330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18c54a70b587d135\" "} {"level":"info","ts":"2026-07-24T17:37:59.578852Z","caller":"traceutil/trace.go:171","msg":"trace[1080553224] linearizableReadLoop","detail":"{readStateIndex:1376; appliedIndex:1375; }","duration":"409.41468ms","start":"2026-07-24T17:37:59.169412Z","end":"2026-07-24T17:37:59.578827Z","steps":["trace[1080553224] 'read index received' (duration: 363.568006ms)","trace[1080553224] 'applied index is now lower than readState.Index' (duration: 45.845774ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:59.579169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.190305579s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:37:59.579216Z","caller":"traceutil/trace.go:171","msg":"trace[1579270947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1327; }","duration":"1.190395681s","start":"2026-07-24T17:37:58.388807Z","end":"2026-07-24T17:37:59.579203Z","steps":["trace[1579270947] 'agreement among raft nodes before linearized reading' (duration: 1.190121104s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:59.579249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:58.388793Z","time spent":"1.190448142s","remote":"127.0.0.1:50262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T17:37:59.579439Z","caller":"traceutil/trace.go:171","msg":"trace[1838770030] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"463.755733ms","start":"2026-07-24T17:37:59.115653Z","end":"2026-07-24T17:37:59.579408Z","steps":["trace[1838770030] 'process raft request' (duration: 417.333295ms)","trace[1838770030] 'compare' (duration: 45.70884ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:37:59.579478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.028165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:37:59.579537Z","caller":"traceutil/trace.go:171","msg":"trace[887470433] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1327; }","duration":"637.132488ms","start":"2026-07-24T17:37:58.942387Z","end":"2026-07-24T17:37:59.579519Z","steps":["trace[887470433] 'agreement among raft nodes before linearized reading' (duration: 637.007695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:59.579561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.36884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-24T17:37:59.579581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:58.942359Z","time spent":"637.21443ms","remote":"127.0.0.1:37320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:37:59.579584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:59.11563Z","time spent":"463.879047ms","remote":"127.0.0.1:50288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:37:59.579614Z","caller":"traceutil/trace.go:171","msg":"trace[41085772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1327; }","duration":"991.450322ms","start":"2026-07-24T17:37:58.58815Z","end":"2026-07-24T17:37:59.579601Z","steps":["trace[41085772] 'agreement among raft nodes before linearized reading' (duration: 991.292478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:37:59.579648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:58.588132Z","time spent":"991.508053ms","remote":"127.0.0.1:50508","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-24T17:38:00.081389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515054457085045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:38:00.58213Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011515054457085045,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T17:38:00.763433Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.230421913s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T17:38:00.805374Z","caller":"traceutil/trace.go:171","msg":"trace[1298972205] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"1.63209181s","start":"2026-07-24T17:37:59.173251Z","end":"2026-07-24T17:38:00.805343Z","steps":["trace[1298972205] 'process raft request' (duration: 1.590587341s)","trace[1298972205] 'compare' (duration: 40.256897ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:38:00.806095Z","caller":"traceutil/trace.go:171","msg":"trace[1620527616] transaction","detail":"{read_only:false; response_revision:1329; number_of_response:1; }","duration":"1.222433196s","start":"2026-07-24T17:37:59.583628Z","end":"2026-07-24T17:38:00.806061Z","steps":["trace[1620527616] 'process raft request' (duration: 1.221319376s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:00.806138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:59.173235Z","time spent":"1.632207493s","remote":"127.0.0.1:50330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:38:00.806237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:59.583605Z","time spent":"1.22256352s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:38:00.806716Z","caller":"traceutil/trace.go:171","msg":"trace[790338845] transaction","detail":"{read_only:false; response_revision:1330; number_of_response:1; }","duration":"420.706303ms","start":"2026-07-24T17:38:00.38599Z","end":"2026-07-24T17:38:00.806696Z","steps":["trace[790338845] 'process raft request' (duration: 419.099391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:00.806827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:00.385972Z","time spent":"420.788155ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:38:01.06226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.093064ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-24T17:38:01.06248Z","caller":"traceutil/trace.go:171","msg":"trace[576116664] linearizableReadLoop","detail":"{readStateIndex:1379; appliedIndex:1376; }","duration":"1.481746997s","start":"2026-07-24T17:37:59.580715Z","end":"2026-07-24T17:38:01.062462Z","steps":["trace[576116664] 'read index received' (duration: 1.182991709s)","trace[576116664] 'applied index is now lower than readState.Index' (duration: 298.754158ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:38:01.062612Z","caller":"traceutil/trace.go:171","msg":"trace[1738880355] transaction","detail":"{read_only:false; response_revision:1331; number_of_response:1; }","duration":"252.539135ms","start":"2026-07-24T17:38:00.810048Z","end":"2026-07-24T17:38:01.062587Z","steps":["trace[1738880355] 'process raft request' (duration: 252.311009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:01.102492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.520461345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"warn","ts":"2026-07-24T17:38:01.102603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.796615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:38:01.102651Z","caller":"traceutil/trace.go:171","msg":"trace[1254643588] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1331; }","duration":"710.855887ms","start":"2026-07-24T17:38:00.391779Z","end":"2026-07-24T17:38:01.102634Z","steps":["trace[1254643588] 'agreement among raft nodes before linearized reading' (duration: 670.816594ms)","trace[1254643588] 'range keys from in-memory index tree' (duration: 39.988652ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:38:01.102689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.521944724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:38:01.102752Z","caller":"traceutil/trace.go:171","msg":"trace[1631546208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1331; }","duration":"1.522043547s","start":"2026-07-24T17:37:59.580689Z","end":"2026-07-24T17:38:01.102733Z","steps":["trace[1631546208] 'agreement among raft nodes before linearized reading' (duration: 1.481837089s)","trace[1631546208] 'range keys from in-memory index tree' (duration: 40.096124ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:38:01.10279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:59.580674Z","time spent":"1.522110348s","remote":"127.0.0.1:50270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T17:38:01.102746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:00.39177Z","time spent":"710.96433ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-24T17:38:01.102637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.109923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:38:01.103063Z","caller":"traceutil/trace.go:171","msg":"trace[727496850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1331; }","duration":"797.488953ms","start":"2026-07-24T17:38:00.305453Z","end":"2026-07-24T17:38:01.102942Z","steps":["trace[727496850] 'agreement among raft nodes before linearized reading' (duration: 757.148262ms)","trace[727496850] 'range keys from in-memory index tree' (duration: 39.973882ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:38:01.103226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:00.305433Z","time spent":"797.752559ms","remote":"127.0.0.1:50262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T17:38:01.103391Z","caller":"traceutil/trace.go:171","msg":"trace[1059628637] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1331; }","duration":"1.520574268s","start":"2026-07-24T17:37:59.581974Z","end":"2026-07-24T17:38:01.102549Z","steps":["trace[1059628637] 'agreement among raft nodes before linearized reading' (duration: 1.480607877s)","trace[1059628637] 'range keys from in-memory index tree' (duration: 39.851397ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:38:01.103495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:37:59.581956Z","time spent":"1.521489181s","remote":"127.0.0.1:50406","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-07-24T17:38:01.940875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.35522ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:38:01.941045Z","caller":"traceutil/trace.go:171","msg":"trace[315791570] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"754.369399ms","start":"2026-07-24T17:38:01.186659Z","end":"2026-07-24T17:38:01.941029Z","steps":["trace[315791570] 'process raft request' (duration: 754.311697ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:38:01.941078Z","caller":"traceutil/trace.go:171","msg":"trace[1379619667] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"831.326813ms","start":"2026-07-24T17:38:01.109723Z","end":"2026-07-24T17:38:01.941049Z","steps":["trace[1379619667] 'process raft request' (duration: 356.735257ms)","trace[1379619667] 'compare' (duration: 474.210656ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:38:01.941105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:01.18664Z","time spent":"754.440591ms","remote":"127.0.0.1:50330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":869,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-24T17:38:01.941137Z","caller":"traceutil/trace.go:171","msg":"trace[683237591] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1382; }","duration":"831.180739ms","start":"2026-07-24T17:38:01.109935Z","end":"2026-07-24T17:38:01.941115Z","steps":["trace[683237591] 'read index received' (duration: 71.884431ms)","trace[683237591] 'applied index is now lower than readState.Index' (duration: 759.293758ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:38:01.941202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:01.109696Z","time spent":"831.443646ms","remote":"127.0.0.1:50330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":843,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T17:38:01.941306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.361843ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:38:01.941348Z","caller":"traceutil/trace.go:171","msg":"trace[425323127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1335; }","duration":"831.436306ms","start":"2026-07-24T17:38:01.109901Z","end":"2026-07-24T17:38:01.941337Z","steps":["trace[425323127] 'agreement among raft nodes before linearized reading' (duration: 831.322693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:01.941378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:01.109892Z","time spent":"831.479067ms","remote":"127.0.0.1:50270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-24T17:38:01.941686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.089061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T17:38:01.941747Z","caller":"traceutil/trace.go:171","msg":"trace[1702710341] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1335; }","duration":"767.188744ms","start":"2026-07-24T17:38:01.174542Z","end":"2026-07-24T17:38:01.94173Z","steps":["trace[1702710341] 'agreement among raft nodes before linearized reading' (duration: 766.991319ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:01.941768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.790858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T17:38:01.941803Z","caller":"traceutil/trace.go:171","msg":"trace[11725794] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1335; }","duration":"636.86703ms","start":"2026-07-24T17:38:01.304925Z","end":"2026-07-24T17:38:01.941792Z","steps":["trace[11725794] 'agreement among raft nodes before linearized reading' (duration: 636.799539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:01.941792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:01.174511Z","time spent":"767.269345ms","remote":"127.0.0.1:50508","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-24T17:38:01.941818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.69496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-24T17:38:01.941833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:01.304904Z","time spent":"636.920611ms","remote":"127.0.0.1:50342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":11,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:38:01.941827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.709691ms","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-24T17:38:01.941858Z","caller":"traceutil/trace.go:171","msg":"trace[236868623] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1335; }","duration":"756.762162ms","start":"2026-07-24T17:38:01.185084Z","end":"2026-07-24T17:38:01.941846Z","steps":["trace[236868623] 'agreement among raft nodes before linearized reading' (duration: 756.647799ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:38:01.941873Z","caller":"traceutil/trace.go:171","msg":"trace[1672105350] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1335; }","duration":"766.805653ms","start":"2026-07-24T17:38:01.175055Z","end":"2026-07-24T17:38:01.94186Z","steps":["trace[1672105350] 'agreement among raft nodes before linearized reading' (duration: 766.623508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:01.94189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:01.185068Z","time spent":"756.814843ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-24T17:38:01.941906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:01.175039Z","time spent":"766.859125ms","remote":"127.0.0.1:50508","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-24T17:38:01.942026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.267465ms","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-24T17:38:01.942051Z","caller":"traceutil/trace.go:171","msg":"trace[1591538124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1335; }","duration":"767.305417ms","start":"2026-07-24T17:38:01.174737Z","end":"2026-07-24T17:38:01.942042Z","steps":["trace[1591538124] 'agreement among raft nodes before linearized reading' (duration: 767.232455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:01.942075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:01.174727Z","time spent":"767.342008ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T17:38:01.942087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.315067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-24T17:38:01.942135Z","caller":"traceutil/trace.go:171","msg":"trace[1840450230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1335; }","duration":"767.395129ms","start":"2026-07-24T17:38:01.174726Z","end":"2026-07-24T17:38:01.942121Z","steps":["trace[1840450230] 'agreement among raft nodes before linearized reading' (duration: 767.268586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:01.942174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:01.174708Z","time spent":"767.45523ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T17:38:02.551777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.592664ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:38:02.551882Z","caller":"traceutil/trace.go:171","msg":"trace[1071605644] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"605.33037ms","start":"2026-07-24T17:38:01.946531Z","end":"2026-07-24T17:38:02.551862Z","steps":["trace[1071605644] 'process raft request' (duration: 122.571951ms)","trace[1071605644] 'compare' (duration: 482.360958ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:38:02.551946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:01.946492Z","time spent":"605.423162ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:38:03.619265Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.550214138s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T17:38:03.61985Z","caller":"traceutil/trace.go:171","msg":"trace[24798510] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"1.672689648s","start":"2026-07-24T17:38:01.947125Z","end":"2026-07-24T17:38:03.619815Z","steps":["trace[24798510] 'process raft request' (duration: 1.672338849s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:38:03.620289Z","caller":"traceutil/trace.go:171","msg":"trace[2014040273] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"1.672736159s","start":"2026-07-24T17:38:01.947538Z","end":"2026-07-24T17:38:03.620275Z","steps":["trace[2014040273] 'process raft request' (duration: 1.672201845s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:38:03.619846Z","caller":"traceutil/trace.go:171","msg":"trace[312968706] linearizableReadLoop","detail":"{readStateIndex:1390; appliedIndex:1385; }","duration":"1.669261368s","start":"2026-07-24T17:38:01.950563Z","end":"2026-07-24T17:38:03.619824Z","steps":["trace[312968706] 'read index received' (duration: 118.534246ms)","trace[312968706] 'applied index is now lower than readState.Index' (duration: 1.550726222s)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:38:03.620429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:01.947118Z","time spent":"1.673209671s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:38:03.620504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:01.947532Z","time spent":"1.672907583s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:38:03.620005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.669425173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:38:03.620874Z","caller":"traceutil/trace.go:171","msg":"trace[835709713] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1340; }","duration":"1.670336436s","start":"2026-07-24T17:38:01.950507Z","end":"2026-07-24T17:38:03.620844Z","steps":["trace[835709713] 'agreement among raft nodes before linearized reading' (duration: 1.669385422s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:03.620999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:01.950496Z","time spent":"1.670474281s","remote":"127.0.0.1:50262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T17:38:03.620173Z","caller":"traceutil/trace.go:171","msg":"trace[1239927121] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"1.672995526s","start":"2026-07-24T17:38:01.947164Z","end":"2026-07-24T17:38:03.620159Z","steps":["trace[1239927121] 'process raft request' (duration: 1.672470282s)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:38:03.620206Z","caller":"traceutil/trace.go:171","msg":"trace[63071553] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"1.672129543s","start":"2026-07-24T17:38:01.948069Z","end":"2026-07-24T17:38:03.620199Z","steps":["trace[63071553] 'process raft request' (duration: 1.671705452s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:03.621757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:01.94806Z","time spent":"1.673636543s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:38:03.622214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:01.947138Z","time spent":"1.673948101s","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:38:03.655391Z","caller":"traceutil/trace.go:171","msg":"trace[415222050] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"939.782447ms","start":"2026-07-24T17:38:02.715589Z","end":"2026-07-24T17:38:03.655372Z","steps":["trace[415222050] 'process raft request' (duration: 939.648053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:03.6555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:02.715569Z","time spent":"939.87672ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:38:03.655581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.090716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-24T17:38:03.655667Z","caller":"traceutil/trace.go:171","msg":"trace[140666978] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1342; }","duration":"337.223459ms","start":"2026-07-24T17:38:03.318431Z","end":"2026-07-24T17:38:03.655654Z","steps":["trace[140666978] 'agreement among raft nodes before linearized reading' (duration: 337.069835ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:03.655706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:03.318397Z","time spent":"337.301571ms","remote":"127.0.0.1:50324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-07-24T17:38:03.655841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.334012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:106252"} {"level":"info","ts":"2026-07-24T17:38:03.655981Z","caller":"traceutil/trace.go:171","msg":"trace[1205831784] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"844.65206ms","start":"2026-07-24T17:38:02.811302Z","end":"2026-07-24T17:38:03.655954Z","steps":["trace[1205831784] 'process raft request' (duration: 844.032014ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:38:03.655971Z","caller":"traceutil/trace.go:171","msg":"trace[542996568] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1342; }","duration":"337.533147ms","start":"2026-07-24T17:38:03.318401Z","end":"2026-07-24T17:38:03.655934Z","steps":["trace[542996568] 'agreement among raft nodes before linearized reading' (duration: 337.133187ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:03.656049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:03.318357Z","time spent":"337.679651ms","remote":"127.0.0.1:50320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":106275,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-07-24T17:38:03.6562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.221394748s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T17:38:03.656332Z","caller":"traceutil/trace.go:171","msg":"trace[838492828] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1342; }","duration":"1.221674055s","start":"2026-07-24T17:38:02.434642Z","end":"2026-07-24T17:38:03.656316Z","steps":["trace[838492828] 'agreement among raft nodes before linearized reading' (duration: 1.221273955s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:03.656391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:02.434587Z","time spent":"1.221785848s","remote":"127.0.0.1:50706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:38:03.656052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:02.811284Z","time spent":"844.729112ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:38:03.656522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"845.889303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T17:38:03.656659Z","caller":"traceutil/trace.go:171","msg":"trace[412520881] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1342; }","duration":"846.034736ms","start":"2026-07-24T17:38:02.810568Z","end":"2026-07-24T17:38:03.656602Z","steps":["trace[412520881] 'agreement among raft nodes before linearized reading' (duration: 845.630686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:03.656799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:02.810546Z","time spent":"846.168939ms","remote":"127.0.0.1:50508","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-24T17:38:04.876566Z","caller":"traceutil/trace.go:171","msg":"trace[1874121099] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"177.107211ms","start":"2026-07-24T17:38:04.699399Z","end":"2026-07-24T17:38:04.876506Z","steps":["trace[1874121099] 'read index received' (duration: 176.904236ms)","trace[1874121099] 'applied index is now lower than readState.Index' (duration: 201.935µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:38:04.876814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.335467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:38:04.876872Z","caller":"traceutil/trace.go:171","msg":"trace[849114235] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1345; }","duration":"177.500711ms","start":"2026-07-24T17:38:04.699347Z","end":"2026-07-24T17:38:04.876848Z","steps":["trace[849114235] 'agreement among raft nodes before linearized reading' (duration: 177.286575ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:38:04.87729Z","caller":"traceutil/trace.go:171","msg":"trace[896579533] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"318.364919ms","start":"2026-07-24T17:38:04.558911Z","end":"2026-07-24T17:38:04.877276Z","steps":["trace[896579533] 'process raft request' (duration: 317.447565ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:04.877382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:04.558894Z","time spent":"318.43133ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:38:08.547653Z","caller":"traceutil/trace.go:171","msg":"trace[556518111] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"142.624483ms","start":"2026-07-24T17:38:08.404996Z","end":"2026-07-24T17:38:08.547621Z","steps":["trace[556518111] 'process raft request' (duration: 142.369797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:08.547777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.281144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T17:38:08.547872Z","caller":"traceutil/trace.go:171","msg":"trace[917627921] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1379; }","duration":"142.425568ms","start":"2026-07-24T17:38:08.405426Z","end":"2026-07-24T17:38:08.547852Z","steps":["trace[917627921] 'agreement among raft nodes before linearized reading' (duration: 142.254884ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:38:08.548159Z","caller":"traceutil/trace.go:171","msg":"trace[250337291] linearizableReadLoop","detail":"{readStateIndex:1430; appliedIndex:1429; }","duration":"142.062659ms","start":"2026-07-24T17:38:08.40547Z","end":"2026-07-24T17:38:08.547533Z","steps":["trace[250337291] 'read index received' (duration: 141.756821ms)","trace[250337291] 'applied index is now lower than readState.Index' (duration: 305.038µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:38:08.78195Z","caller":"traceutil/trace.go:171","msg":"trace[613948858] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"227.978576ms","start":"2026-07-24T17:38:08.553938Z","end":"2026-07-24T17:38:08.781916Z","steps":["trace[613948858] 'process raft request' (duration: 193.998292ms)","trace[613948858] 'compare' (duration: 33.80477ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:38:08.797941Z","caller":"traceutil/trace.go:171","msg":"trace[2001762713] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"243.680104ms","start":"2026-07-24T17:38:08.554238Z","end":"2026-07-24T17:38:08.797918Z","steps":["trace[2001762713] 'process raft request' (duration: 243.53423ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:38:08.798249Z","caller":"traceutil/trace.go:171","msg":"trace[334005720] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"243.744666ms","start":"2026-07-24T17:38:08.554495Z","end":"2026-07-24T17:38:08.79824Z","steps":["trace[334005720] 'process raft request' (duration: 243.382376ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:38:08.798249Z","caller":"traceutil/trace.go:171","msg":"trace[1369727168] linearizableReadLoop","detail":"{readStateIndex:1434; appliedIndex:1430; }","duration":"169.454162ms","start":"2026-07-24T17:38:08.628772Z","end":"2026-07-24T17:38:08.798226Z","steps":["trace[1369727168] 'read index received' (duration: 119.198064ms)","trace[1369727168] 'applied index is now lower than readState.Index' (duration: 50.255038ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:38:08.798474Z","caller":"traceutil/trace.go:171","msg":"trace[1165982971] transaction","detail":"{read_only:false; response_revision:1383; number_of_response:1; }","duration":"237.641767ms","start":"2026-07-24T17:38:08.560825Z","end":"2026-07-24T17:38:08.798467Z","steps":["trace[1165982971] 'process raft request' (duration: 237.297769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:08.798585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.811331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-07-24T17:38:08.799066Z","caller":"traceutil/trace.go:171","msg":"trace[142562299] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1383; }","duration":"170.321344ms","start":"2026-07-24T17:38:08.628715Z","end":"2026-07-24T17:38:08.799036Z","steps":["trace[142562299] 'agreement among raft nodes before linearized reading' (duration: 169.78767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:09.043342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.888063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:38:09.043558Z","caller":"traceutil/trace.go:171","msg":"trace[707788221] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"240.653585ms","start":"2026-07-24T17:38:08.802882Z","end":"2026-07-24T17:38:09.043535Z","steps":["trace[707788221] 'process raft request' (duration: 95.491296ms)","trace[707788221] 'compare' (duration: 144.680027ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:38:09.04382Z","caller":"traceutil/trace.go:171","msg":"trace[1072252426] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1434; }","duration":"110.966528ms","start":"2026-07-24T17:38:08.932835Z","end":"2026-07-24T17:38:09.043802Z","steps":["trace[1072252426] 'read index received' (duration: 108.871854ms)","trace[1072252426] 'applied index is now lower than readState.Index' (duration: 2.092854ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:38:09.043997Z","caller":"traceutil/trace.go:171","msg":"trace[1521577548] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"236.014884ms","start":"2026-07-24T17:38:08.807951Z","end":"2026-07-24T17:38:09.043965Z","steps":["trace[1521577548] 'process raft request' (duration: 235.493111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:09.044754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.909273ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-24T17:38:09.044803Z","caller":"traceutil/trace.go:171","msg":"trace[1327216268] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1385; }","duration":"111.996565ms","start":"2026-07-24T17:38:08.932795Z","end":"2026-07-24T17:38:09.044792Z","steps":["trace[1327216268] 'agreement among raft nodes before linearized reading' (duration: 111.070121ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:38:11.927686Z","caller":"traceutil/trace.go:171","msg":"trace[1268112045] linearizableReadLoop","detail":"{readStateIndex:1448; appliedIndex:1447; }","duration":"211.696022ms","start":"2026-07-24T17:38:11.715965Z","end":"2026-07-24T17:38:11.927661Z","steps":["trace[1268112045] 'read index received' (duration: 211.319412ms)","trace[1268112045] 'applied index is now lower than readState.Index' (duration: 375.48µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:38:11.927728Z","caller":"traceutil/trace.go:171","msg":"trace[1577603598] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"222.38584ms","start":"2026-07-24T17:38:11.705308Z","end":"2026-07-24T17:38:11.927694Z","steps":["trace[1577603598] 'process raft request' (duration: 222.192985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:11.927996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.99769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-24T17:38:11.928251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.834763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T17:38:11.928294Z","caller":"traceutil/trace.go:171","msg":"trace[653400133] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1397; }","duration":"194.895474ms","start":"2026-07-24T17:38:11.733388Z","end":"2026-07-24T17:38:11.928283Z","steps":["trace[653400133] 'agreement among raft nodes before linearized reading' (duration: 194.778761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:11.928441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.40385ms","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-24T17:38:11.928089Z","caller":"traceutil/trace.go:171","msg":"trace[1644800015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1397; }","duration":"212.128843ms","start":"2026-07-24T17:38:11.71593Z","end":"2026-07-24T17:38:11.928058Z","steps":["trace[1644800015] 'agreement among raft nodes before linearized reading' (duration: 211.859236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:11.928477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.589542ms","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-24T17:38:11.928663Z","caller":"traceutil/trace.go:171","msg":"trace[471373593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1397; }","duration":"195.812748ms","start":"2026-07-24T17:38:11.73284Z","end":"2026-07-24T17:38:11.928653Z","steps":["trace[471373593] 'agreement among raft nodes before linearized reading' (duration: 195.573942ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:11.928484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.064819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-24T17:38:11.928758Z","caller":"traceutil/trace.go:171","msg":"trace[1165967096] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1397; }","duration":"195.412298ms","start":"2026-07-24T17:38:11.733337Z","end":"2026-07-24T17:38:11.92875Z","steps":["trace[1165967096] 'agreement among raft nodes before linearized reading' (duration: 194.901915ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:38:11.928742Z","caller":"traceutil/trace.go:171","msg":"trace[2040253898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1397; }","duration":"212.604725ms","start":"2026-07-24T17:38:11.715981Z","end":"2026-07-24T17:38:11.928585Z","steps":["trace[2040253898] 'agreement among raft nodes before linearized reading' (duration: 212.169564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:12.398163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.302932ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-24T17:38:12.398407Z","caller":"traceutil/trace.go:171","msg":"trace[83727985] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"464.726129ms","start":"2026-07-24T17:38:11.933661Z","end":"2026-07-24T17:38:12.398387Z","steps":["trace[83727985] 'process raft request' (duration: 464.663047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:12.398487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:11.933637Z","time spent":"464.812662ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:38:12.398734Z","caller":"traceutil/trace.go:171","msg":"trace[1436151116] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"465.060228ms","start":"2026-07-24T17:38:11.93366Z","end":"2026-07-24T17:38:12.39872Z","steps":["trace[1436151116] 'process raft request' (duration: 301.092458ms)","trace[1436151116] 'compare' (duration: 163.098787ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:38:12.398835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:11.933638Z","time spent":"465.13072ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:38:12.66399Z","caller":"traceutil/trace.go:171","msg":"trace[754452649] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"730.16724ms","start":"2026-07-24T17:38:11.933796Z","end":"2026-07-24T17:38:12.663963Z","steps":["trace[754452649] 'process raft request' (duration: 645.3118ms)","trace[754452649] 'compare' (duration: 84.654135ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:38:12.66415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:11.933785Z","time spent":"730.277943ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:38:12.6642Z","caller":"traceutil/trace.go:171","msg":"trace[1493726079] transaction","detail":"{read_only:false; response_revision:1401; number_of_response:1; }","duration":"730.400546ms","start":"2026-07-24T17:38:11.93378Z","end":"2026-07-24T17:38:12.66418Z","steps":["trace[1493726079] 'process raft request' (duration: 730.143629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:12.66432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:11.933761Z","time spent":"730.500338ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:38:12.664423Z","caller":"traceutil/trace.go:171","msg":"trace[575676350] linearizableReadLoop","detail":"{readStateIndex:1453; appliedIndex:1448; }","duration":"528.579071ms","start":"2026-07-24T17:38:12.135805Z","end":"2026-07-24T17:38:12.664384Z","steps":["trace[575676350] 'read index received' (duration: 99.170741ms)","trace[575676350] 'applied index is now lower than readState.Index' (duration: 429.40685ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:38:12.664482Z","caller":"traceutil/trace.go:171","msg":"trace[211553884] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"729.614006ms","start":"2026-07-24T17:38:11.934822Z","end":"2026-07-24T17:38:12.664436Z","steps":["trace[211553884] 'process raft request' (duration: 729.302878ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:12.664604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.796227ms","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-24T17:38:12.664654Z","caller":"traceutil/trace.go:171","msg":"trace[120691180] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1402; }","duration":"528.8881ms","start":"2026-07-24T17:38:12.135752Z","end":"2026-07-24T17:38:12.66464Z","steps":["trace[120691180] 'agreement among raft nodes before linearized reading' (duration: 528.808248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:12.664697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:12.135731Z","time spent":"528.955051ms","remote":"127.0.0.1:37294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-07-24T17:38:12.664697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.74812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-24T17:38:12.664731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:11.93481Z","time spent":"729.812881ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T17:38:12.664753Z","caller":"traceutil/trace.go:171","msg":"trace[1525551331] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1402; }","duration":"257.869453ms","start":"2026-07-24T17:38:12.406865Z","end":"2026-07-24T17:38:12.664735Z","steps":["trace[1525551331] 'agreement among raft nodes before linearized reading' (duration: 257.75206ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:38:13.653732Z","caller":"traceutil/trace.go:171","msg":"trace[1751939959] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"496.65314ms","start":"2026-07-24T17:38:13.157058Z","end":"2026-07-24T17:38:13.653711Z","steps":["trace[1751939959] 'process raft request' (duration: 496.527717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:38:13.653895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:38:13.15704Z","time spent":"496.794303ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:38:24.323782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.088269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-07-24T17:38:24.323887Z","caller":"traceutil/trace.go:171","msg":"trace[637533622] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1453; }","duration":"112.211052ms","start":"2026-07-24T17:38:24.211658Z","end":"2026-07-24T17:38:24.323869Z","steps":["trace[637533622] 'range keys from in-memory index tree' (duration: 111.903154ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:39:45.302349Z","caller":"traceutil/trace.go:171","msg":"trace[380299594] transaction","detail":"{read_only:false; response_revision:1825; number_of_response:1; }","duration":"130.980974ms","start":"2026-07-24T17:39:45.171327Z","end":"2026-07-24T17:39:45.302308Z","steps":["trace[380299594] 'process raft request' (duration: 130.735317ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:39:45.431083Z","caller":"traceutil/trace.go:171","msg":"trace[1046087492] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"123.89594ms","start":"2026-07-24T17:39:45.307136Z","end":"2026-07-24T17:39:45.431032Z","steps":["trace[1046087492] 'process raft request' (duration: 62.74155ms)","trace[1046087492] 'compare' (duration: 61.051007ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:39:45.48729Z","caller":"traceutil/trace.go:171","msg":"trace[35620277] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"179.634779ms","start":"2026-07-24T17:39:45.307621Z","end":"2026-07-24T17:39:45.487256Z","steps":["trace[35620277] 'process raft request' (duration: 179.374383ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:39:45.487352Z","caller":"traceutil/trace.go:171","msg":"trace[1961026456] transaction","detail":"{read_only:false; response_revision:1828; number_of_response:1; }","duration":"178.946811ms","start":"2026-07-24T17:39:45.308367Z","end":"2026-07-24T17:39:45.487314Z","steps":["trace[1961026456] 'process raft request' (duration: 178.791527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:39:47.637292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.546399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-24T17:39:47.637401Z","caller":"traceutil/trace.go:171","msg":"trace[963873703] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1835; }","duration":"146.692233ms","start":"2026-07-24T17:39:47.490684Z","end":"2026-07-24T17:39:47.637376Z","steps":["trace[963873703] 'range keys from in-memory index tree' (duration: 146.314663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:39:47.637415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.680283ms","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-24T17:39:47.637515Z","caller":"traceutil/trace.go:171","msg":"trace[522956662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1835; }","duration":"146.837187ms","start":"2026-07-24T17:39:47.49064Z","end":"2026-07-24T17:39:47.637477Z","steps":["trace[522956662] 'range keys from in-memory index tree' (duration: 146.470567ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:39:49.911273Z","caller":"traceutil/trace.go:171","msg":"trace[935587821] transaction","detail":"{read_only:false; response_revision:1847; number_of_response:1; }","duration":"233.489441ms","start":"2026-07-24T17:39:49.677734Z","end":"2026-07-24T17:39:49.911223Z","steps":["trace[935587821] 'process raft request' (duration: 161.197151ms)","trace[935587821] 'compare' (duration: 71.996472ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T17:42:18.906917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.691389ms","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-24T17:42:18.907Z","caller":"traceutil/trace.go:171","msg":"trace[242497954] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:2549; }","duration":"143.827212ms","start":"2026-07-24T17:42:18.763158Z","end":"2026-07-24T17:42:18.906985Z","steps":["trace[242497954] 'count revisions from in-memory index tree' (duration: 143.606816ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:42:26.64062Z","caller":"traceutil/trace.go:171","msg":"trace[650504460] linearizableReadLoop","detail":"{readStateIndex:2693; appliedIndex:2692; }","duration":"372.845064ms","start":"2026-07-24T17:42:26.267753Z","end":"2026-07-24T17:42:26.640598Z","steps":["trace[650504460] 'read index received' (duration: 372.70015ms)","trace[650504460] 'applied index is now lower than readState.Index' (duration: 143.974µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T17:42:26.640935Z","caller":"traceutil/trace.go:171","msg":"trace[104281269] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"471.048078ms","start":"2026-07-24T17:42:26.169878Z","end":"2026-07-24T17:42:26.640926Z","steps":["trace[104281269] 'process raft request' (duration: 470.558306ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:42:26.641019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:42:26.169848Z","time spent":"471.11139ms","remote":"127.0.0.1:50508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T17:42:26.641346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.917327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-07-24T17:42:26.641389Z","caller":"traceutil/trace.go:171","msg":"trace[166991826] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2589; }","duration":"164.975509ms","start":"2026-07-24T17:42:26.476402Z","end":"2026-07-24T17:42:26.641378Z","steps":["trace[166991826] 'agreement among raft nodes before linearized reading' (duration: 164.853516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:42:26.641307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.965211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-07-24T17:42:26.641442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.651584ms","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:534"} {"level":"info","ts":"2026-07-24T17:42:26.641602Z","caller":"traceutil/trace.go:171","msg":"trace[1301254076] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2589; }","duration":"169.157196ms","start":"2026-07-24T17:42:26.472281Z","end":"2026-07-24T17:42:26.641438Z","steps":["trace[1301254076] 'agreement among raft nodes before linearized reading' (duration: 168.849228ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T17:42:26.641697Z","caller":"traceutil/trace.go:171","msg":"trace[1301884650] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2589; }","duration":"373.800458ms","start":"2026-07-24T17:42:26.267705Z","end":"2026-07-24T17:42:26.641506Z","steps":["trace[1301884650] 'agreement among raft nodes before linearized reading' (duration: 373.50571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T17:42:26.641817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T17:42:26.267682Z","time spent":"374.097565ms","remote":"127.0.0.1:50508","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\" "}