{"level":"warn","ts":"2026-05-05T11:17:35.822913Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T11:17:35.823014Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.122:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.122:2380","--initial-cluster=instance=https://199.19.213.122:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.122:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.122: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-05-05T11:17:35.823089Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T11:17:35.823098Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.122:2380"]} {"level":"info","ts":"2026-05-05T11:17:35.823133Z","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-05-05T11:17:35.823886Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"]} {"level":"info","ts":"2026-05-05T11:17:35.82398Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.122:2380"],"listen-peer-urls":["https://199.19.213.122:2380"],"advertise-client-urls":["https://199.19.213.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.122: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-05-05T11:17:35.828286Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.041129ms"} {"level":"info","ts":"2026-05-05T11:17:35.835541Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f9ba8c11acfbc749","cluster-id":"ca7c3e103d4cd533"} {"level":"info","ts":"2026-05-05T11:17:35.835601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T11:17:35.835624Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became follower at term 0"} {"level":"info","ts":"2026-05-05T11:17:35.835637Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f9ba8c11acfbc749 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T11:17:35.835652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became follower at term 1"} {"level":"info","ts":"2026-05-05T11:17:35.835686Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=(17994849268656752457)"} {"level":"warn","ts":"2026-05-05T11:17:35.84298Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T11:17:35.844491Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T11:17:35.845576Z","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-05-05T11:17:35.847825Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f9ba8c11acfbc749","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T11:17:35.847994Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f9ba8c11acfbc749","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T11:17:35.848229Z","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-05-05T11:17:35.848309Z","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-05-05T11:17:35.848319Z","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-05-05T11:17:35.849983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 switched to configuration voters=(17994849268656752457)"} {"level":"info","ts":"2026-05-05T11:17:35.850143Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ca7c3e103d4cd533","local-member-id":"f9ba8c11acfbc749","added-peer-id":"f9ba8c11acfbc749","added-peer-peer-urls":["https://199.19.213.122:2380"]} {"level":"info","ts":"2026-05-05T11:17:35.852128Z","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-05-05T11:17:35.852466Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f9ba8c11acfbc749","initial-advertise-peer-urls":["https://199.19.213.122:2380"],"listen-peer-urls":["https://199.19.213.122:2380"],"advertise-client-urls":["https://199.19.213.122:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.122:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T11:17:35.852504Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T11:17:35.852776Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.122:2380"} {"level":"info","ts":"2026-05-05T11:17:35.85281Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.122:2380"} {"level":"info","ts":"2026-05-05T11:17:36.236833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T11:17:36.236954Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T11:17:36.236985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 received MsgPreVoteResp from f9ba8c11acfbc749 at term 1"} {"level":"info","ts":"2026-05-05T11:17:36.237009Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became candidate at term 2"} {"level":"info","ts":"2026-05-05T11:17:36.237019Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 received MsgVoteResp from f9ba8c11acfbc749 at term 2"} {"level":"info","ts":"2026-05-05T11:17:36.237038Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f9ba8c11acfbc749 became leader at term 2"} {"level":"info","ts":"2026-05-05T11:17:36.237057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f9ba8c11acfbc749 elected leader f9ba8c11acfbc749 at term 2"} {"level":"info","ts":"2026-05-05T11:17:36.237947Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:17:36.238698Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T11:17:36.238699Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f9ba8c11acfbc749","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.122:2379]}","request-path":"/0/members/f9ba8c11acfbc749/attributes","cluster-id":"ca7c3e103d4cd533","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T11:17:36.238893Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T11:17:36.239229Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T11:17:36.239254Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T11:17:36.239242Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ca7c3e103d4cd533","local-member-id":"f9ba8c11acfbc749","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:17:36.239352Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:17:36.239389Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T11:17:36.242979Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T11:17:36.243384Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.122:2379"} {"level":"warn","ts":"2026-05-05T11:17:38.158863Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182574713920375,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T11:17:38.347023Z","caller":"traceutil/trace.go:171","msg":"trace[158741128] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"699.001408ms","start":"2026-05-05T11:17:37.647996Z","end":"2026-05-05T11:17:38.346998Z","steps":["trace[158741128] 'process raft request' (duration: 698.738481ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:38.347899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.647979Z","time spent":"699.132882ms","remote":"127.0.0.1:53578","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:17:39.700928Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.354101195s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T11:17:39.70141Z","caller":"traceutil/trace.go:171","msg":"trace[541695917] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"2.053325578s","start":"2026-05-05T11:17:37.648037Z","end":"2026-05-05T11:17:39.701362Z","steps":["trace[541695917] 'process raft request' (duration: 2.053078122s)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:39.701579Z","caller":"traceutil/trace.go:171","msg":"trace[822287524] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:5; }","duration":"2.042908758s","start":"2026-05-05T11:17:37.658644Z","end":"2026-05-05T11:17:39.701553Z","steps":["trace[822287524] 'read index received' (duration: 688.465695ms)","trace[822287524] 'applied index is now lower than readState.Index' (duration: 1.354441923s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:17:39.701585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.648025Z","time spent":"2.053472373s","remote":"127.0.0.1:53582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:17:39.701662Z","caller":"traceutil/trace.go:171","msg":"trace[1372976368] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.974109691s","start":"2026-05-05T11:17:37.727543Z","end":"2026-05-05T11:17:39.701653Z","steps":["trace[1372976368] 'process raft request' (duration: 1.973793303s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.701745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.727527Z","time spent":"1.974162132s","remote":"127.0.0.1:54040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:17:39.701884Z","caller":"traceutil/trace.go:171","msg":"trace[2071483982] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.974282346s","start":"2026-05-05T11:17:37.727593Z","end":"2026-05-05T11:17:39.701875Z","steps":["trace[2071483982] 'process raft request' (duration: 1.973790723s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.701924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.727578Z","time spent":"1.974328547s","remote":"127.0.0.1:54040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:17:39.702024Z","caller":"traceutil/trace.go:171","msg":"trace[1958987294] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.966018301s","start":"2026-05-05T11:17:37.735994Z","end":"2026-05-05T11:17:39.702012Z","steps":["trace[1958987294] 'process raft request' (duration: 1.965494317s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.702077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.735984Z","time spent":"1.966067272s","remote":"127.0.0.1:53678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:17:39.702176Z","caller":"traceutil/trace.go:171","msg":"trace[1202059483] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.974514971s","start":"2026-05-05T11:17:37.727651Z","end":"2026-05-05T11:17:39.702166Z","steps":["trace[1202059483] 'process raft request' (duration: 1.973755941s)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:39.702209Z","caller":"traceutil/trace.go:171","msg":"trace[534929362] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.974487531s","start":"2026-05-05T11:17:37.727711Z","end":"2026-05-05T11:17:39.702198Z","steps":["trace[534929362] 'process raft request' (duration: 1.973721341s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.702262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.727682Z","time spent":"1.974559823s","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:17:39.702266Z","caller":"traceutil/trace.go:171","msg":"trace[1336656341] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.959364968s","start":"2026-05-05T11:17:37.742882Z","end":"2026-05-05T11:17:39.702247Z","steps":["trace[1336656341] 'process raft request' (duration: 1.958639519s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.702287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.727641Z","time spent":"1.974566573s","remote":"127.0.0.1:54040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T11:17:39.702319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.742868Z","time spent":"1.9594286s","remote":"127.0.0.1:53750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:17:39.702422Z","caller":"traceutil/trace.go:171","msg":"trace[920135922] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.974167452s","start":"2026-05-05T11:17:37.728235Z","end":"2026-05-05T11:17:39.702402Z","steps":["trace[920135922] 'process raft request' (duration: 1.973225848s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.702488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.728212Z","time spent":"1.974243054s","remote":"127.0.0.1:54040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T11:17:39.70267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.003979337s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T11:17:39.70272Z","caller":"traceutil/trace.go:171","msg":"trace[860986579] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"2.004062599s","start":"2026-05-05T11:17:37.698649Z","end":"2026-05-05T11:17:39.702712Z","steps":["trace[860986579] 'agreement among raft nodes before linearized reading' (duration: 2.003964306s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.702744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.698636Z","time spent":"2.0041045s","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-05-05T11:17:39.701552Z","caller":"traceutil/trace.go:171","msg":"trace[748302774] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.973935086s","start":"2026-05-05T11:17:37.727539Z","end":"2026-05-05T11:17:39.701474Z","steps":["trace[748302774] 'process raft request' (duration: 1.973711361s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.702871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.92400143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T11:17:39.702884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.077303202s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T11:17:39.702915Z","caller":"traceutil/trace.go:171","msg":"trace[126814897] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.924111963s","start":"2026-05-05T11:17:37.778791Z","end":"2026-05-05T11:17:39.702903Z","steps":["trace[126814897] 'agreement among raft nodes before linearized reading' (duration: 1.924061241s)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:39.703126Z","caller":"traceutil/trace.go:171","msg":"trace[1530009177] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"1.077352333s","start":"2026-05-05T11:17:38.625569Z","end":"2026-05-05T11:17:39.702921Z","steps":["trace[1530009177] 'agreement among raft nodes before linearized reading' (duration: 1.077286341s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.703179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.974713426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T11:17:39.703186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:38.625512Z","time spent":"1.07766372s","remote":"127.0.0.1:53874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-05-05T11:17:39.703153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.727526Z","time spent":"1.975358313s","remote":"127.0.0.1:54040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T11:17:39.703246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.976893363s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T11:17:39.703276Z","caller":"traceutil/trace.go:171","msg":"trace[616095108] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.976953545s","start":"2026-05-05T11:17:37.726317Z","end":"2026-05-05T11:17:39.70327Z","steps":["trace[616095108] 'agreement among raft nodes before linearized reading' (duration: 1.976914164s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.703306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.726299Z","time spent":"1.977002315s","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-05T11:17:39.702622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.043927474s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T11:17:39.70357Z","caller":"traceutil/trace.go:171","msg":"trace[1993580536] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"2.044928619s","start":"2026-05-05T11:17:37.658632Z","end":"2026-05-05T11:17:39.70356Z","steps":["trace[1993580536] 'agreement among raft nodes before linearized reading' (duration: 2.043870813s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.703618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.658626Z","time spent":"2.04498248s","remote":"127.0.0.1:53900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-05-05T11:17:39.703213Z","caller":"traceutil/trace.go:171","msg":"trace[602415288] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"1.974763097s","start":"2026-05-05T11:17:37.72844Z","end":"2026-05-05T11:17:39.703203Z","steps":["trace[602415288] 'agreement among raft nodes before linearized reading' (duration: 1.974719066s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.703714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.72843Z","time spent":"1.975249219s","remote":"127.0.0.1:53594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-05-05T11:17:39.702874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000153167s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T11:17:39.703883Z","caller":"traceutil/trace.go:171","msg":"trace[381613298] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"2.001176052s","start":"2026-05-05T11:17:37.702698Z","end":"2026-05-05T11:17:39.703874Z","steps":["trace[381613298] 'agreement among raft nodes before linearized reading' (duration: 2.000166127s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.702822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.978107904s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.122\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T11:17:39.703971Z","caller":"traceutil/trace.go:171","msg":"trace[1562053609] range","detail":"{range_begin:/registry/masterleases/199.19.213.122; range_end:; response_count:0; response_revision:11; }","duration":"1.979284503s","start":"2026-05-05T11:17:37.724655Z","end":"2026-05-05T11:17:39.703939Z","steps":["trace[1562053609] 'agreement among raft nodes before linearized reading' (duration: 1.978122564s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.703982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.702687Z","time spent":"2.001230484s","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-05T11:17:39.703137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.778769Z","time spent":"1.924361678s","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-05T11:17:39.704029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.724628Z","time spent":"1.979386746s","remote":"127.0.0.1:53552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.19.213.122\" "} {"level":"warn","ts":"2026-05-05T11:17:39.70285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999979572s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T11:17:39.704225Z","caller":"traceutil/trace.go:171","msg":"trace[1639799111] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"2.001348936s","start":"2026-05-05T11:17:37.702864Z","end":"2026-05-05T11:17:39.704213Z","steps":["trace[1639799111] 'agreement among raft nodes before linearized reading' (duration: 1.999969752s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.704281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:37.702827Z","time spent":"2.001443358s","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-05T11:17:39.702937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.717981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T11:17:39.704401Z","caller":"traceutil/trace.go:171","msg":"trace[355428874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"961.239329ms","start":"2026-05-05T11:17:38.743153Z","end":"2026-05-05T11:17:39.704393Z","steps":["trace[355428874] 'agreement among raft nodes before linearized reading' (duration: 959.695971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.704454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:38.743134Z","time spent":"961.311461ms","remote":"127.0.0.1:53514","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-05-05T11:17:39.702811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.07722931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T11:17:39.70452Z","caller":"traceutil/trace.go:171","msg":"trace[1280341919] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"1.078949302s","start":"2026-05-05T11:17:38.625558Z","end":"2026-05-05T11:17:39.704507Z","steps":["trace[1280341919] 'agreement among raft nodes before linearized reading' (duration: 1.077189359s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:39.704574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:38.6255Z","time spent":"1.079058876s","remote":"127.0.0.1:53854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"warn","ts":"2026-05-05T11:17:40.208706Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182574713920389,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T11:17:40.223251Z","caller":"traceutil/trace.go:171","msg":"trace[2081805612] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"515.760832ms","start":"2026-05-05T11:17:39.707465Z","end":"2026-05-05T11:17:40.223226Z","steps":["trace[2081805612] 'process raft request' (duration: 512.847361ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:40.223382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:39.70745Z","time spent":"515.868065ms","remote":"127.0.0.1:53946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:17:40.228092Z","caller":"traceutil/trace.go:171","msg":"trace[1163435492] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"516.541188ms","start":"2026-05-05T11:17:39.71153Z","end":"2026-05-05T11:17:40.228071Z","steps":["trace[1163435492] 'process raft request' (duration: 516.429565ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:40.228203Z","caller":"traceutil/trace.go:171","msg":"trace[1332903823] linearizableReadLoop","detail":"{readStateIndex:25; appliedIndex:14; }","duration":"520.026915ms","start":"2026-05-05T11:17:39.708163Z","end":"2026-05-05T11:17:40.22819Z","steps":["trace[1332903823] 'read index received' (duration: 512.120281ms)","trace[1332903823] 'applied index is now lower than readState.Index' (duration: 7.905874ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:17:40.228148Z","caller":"traceutil/trace.go:171","msg":"trace[2041395334] transaction","detail":"{read_only:false; number_of_response:0; response_revision:14; }","duration":"516.58213ms","start":"2026-05-05T11:17:39.711517Z","end":"2026-05-05T11:17:40.228099Z","steps":["trace[2041395334] 'process raft request' (duration: 516.423946ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:40.228261Z","caller":"traceutil/trace.go:171","msg":"trace[1639025156] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"516.681692ms","start":"2026-05-05T11:17:39.711574Z","end":"2026-05-05T11:17:40.228256Z","steps":["trace[1639025156] 'process raft request' (duration: 516.423395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:40.228235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:39.711513Z","time spent":"516.651941ms","remote":"127.0.0.1:54040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T11:17:40.228302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:39.711571Z","time spent":"516.707762ms","remote":"127.0.0.1:54040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:17:40.228385Z","caller":"traceutil/trace.go:171","msg":"trace[256564801] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"515.456552ms","start":"2026-05-05T11:17:39.712923Z","end":"2026-05-05T11:17:40.228379Z","steps":["trace[256564801] 'process raft request' (duration: 515.163094ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:40.228416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:39.712914Z","time spent":"515.485952ms","remote":"127.0.0.1:53874","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:17:40.228489Z","caller":"traceutil/trace.go:171","msg":"trace[897201618] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"516.60415ms","start":"2026-05-05T11:17:39.711859Z","end":"2026-05-05T11:17:40.228464Z","steps":["trace[897201618] 'process raft request' (duration: 516.158639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:40.22856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.403206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-vc7f9\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-05-05T11:17:40.228489Z","caller":"traceutil/trace.go:171","msg":"trace[1573379391] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"514.122058ms","start":"2026-05-05T11:17:39.714347Z","end":"2026-05-05T11:17:40.228469Z","steps":["trace[1573379391] 'process raft request' (duration: 513.760669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:40.228601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:39.711854Z","time spent":"516.687213ms","remote":"127.0.0.1:54040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T11:17:40.228595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:39.71149Z","time spent":"516.798504ms","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:17:40.228668Z","caller":"traceutil/trace.go:171","msg":"trace[1291882185] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"516.538689ms","start":"2026-05-05T11:17:39.712124Z","end":"2026-05-05T11:17:40.228662Z","steps":["trace[1291882185] 'process raft request' (duration: 515.918453ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:40.228674Z","caller":"traceutil/trace.go:171","msg":"trace[753623718] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"518.297853ms","start":"2026-05-05T11:17:39.710366Z","end":"2026-05-05T11:17:40.228664Z","steps":["trace[753623718] 'process raft request' (duration: 517.548063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:40.228709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:39.712115Z","time spent":"516.57924ms","remote":"127.0.0.1:54040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T11:17:40.228798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:39.710358Z","time spent":"518.351954ms","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T11:17:40.228824Z","caller":"traceutil/trace.go:171","msg":"trace[783401693] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"516.193861ms","start":"2026-05-05T11:17:39.712598Z","end":"2026-05-05T11:17:40.228791Z","steps":["trace[783401693] 'process raft request' (duration: 515.464452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:40.228846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:39.714339Z","time spent":"514.335234ms","remote":"127.0.0.1:53678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:17:40.228584Z","caller":"traceutil/trace.go:171","msg":"trace[1199722808] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-vc7f9; range_end:; response_count:1; response_revision:21; }","duration":"520.449216ms","start":"2026-05-05T11:17:39.708128Z","end":"2026-05-05T11:17:40.228577Z","steps":["trace[1199722808] 'agreement among raft nodes before linearized reading' (duration: 520.377534ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:40.228595Z","caller":"traceutil/trace.go:171","msg":"trace[958912948] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"518.262592ms","start":"2026-05-05T11:17:39.710224Z","end":"2026-05-05T11:17:40.228486Z","steps":["trace[958912948] 'process raft request' (duration: 517.627627ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:40.228915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:39.71259Z","time spent":"516.280843ms","remote":"127.0.0.1:54040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T11:17:40.228951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:39.70812Z","time spent":"520.817956ms","remote":"127.0.0.1:53750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":885,"request content":"key:\"/registry/certificatesigningrequests/csr-vc7f9\" "} {"level":"warn","ts":"2026-05-05T11:17:40.228999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:39.710196Z","time spent":"518.758115ms","remote":"127.0.0.1:53900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T11:17:40.237315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.918848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T11:17:40.237429Z","caller":"traceutil/trace.go:171","msg":"trace[1523859675] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22; }","duration":"494.074693ms","start":"2026-05-05T11:17:39.743311Z","end":"2026-05-05T11:17:40.237386Z","steps":["trace[1523859675] 'agreement among raft nodes before linearized reading' (duration: 493.934159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:40.237727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:39.74329Z","time spent":"494.3963ms","remote":"127.0.0.1:53514","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-05-05T11:17:40.237839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.078864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T11:17:40.237322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.212774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-05T11:17:40.237886Z","caller":"traceutil/trace.go:171","msg":"trace[158241716] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:22; }","duration":"526.140666ms","start":"2026-05-05T11:17:39.711737Z","end":"2026-05-05T11:17:40.237878Z","steps":["trace[158241716] 'agreement among raft nodes before linearized reading' (duration: 526.077574ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:40.237895Z","caller":"traceutil/trace.go:171","msg":"trace[858155270] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:22; }","duration":"470.800108ms","start":"2026-05-05T11:17:39.767083Z","end":"2026-05-05T11:17:40.237883Z","steps":["trace[858155270] 'agreement among raft nodes before linearized reading' (duration: 470.149312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:40.237908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:39.711725Z","time spent":"526.174067ms","remote":"127.0.0.1:53870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-05-05T11:17:40.237929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:39.767075Z","time spent":"470.844629ms","remote":"127.0.0.1:53620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-05T11:17:40.237608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.850794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T11:17:40.238085Z","caller":"traceutil/trace.go:171","msg":"trace[1667310879] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:22; }","duration":"326.355685ms","start":"2026-05-05T11:17:39.911718Z","end":"2026-05-05T11:17:40.238074Z","steps":["trace[1667310879] 'agreement among raft nodes before linearized reading' (duration: 325.867593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:40.238156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:17:39.911697Z","time spent":"326.442338ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" "} {"level":"info","ts":"2026-05-05T11:17:49.258178Z","caller":"traceutil/trace.go:171","msg":"trace[1711530275] linearizableReadLoop","detail":"{readStateIndex:378; appliedIndex:377; }","duration":"162.680176ms","start":"2026-05-05T11:17:49.095474Z","end":"2026-05-05T11:17:49.258154Z","steps":["trace[1711530275] 'read index received' (duration: 90.82784ms)","trace[1711530275] 'applied index is now lower than readState.Index' (duration: 71.851026ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:17:49.258315Z","caller":"traceutil/trace.go:171","msg":"trace[1439632439] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"181.799595ms","start":"2026-05-05T11:17:49.07649Z","end":"2026-05-05T11:17:49.258289Z","steps":["trace[1439632439] 'process raft request' (duration: 109.866517ms)","trace[1439632439] 'compare' (duration: 71.670972ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:17:49.258515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.029394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-05-05T11:17:49.258595Z","caller":"traceutil/trace.go:171","msg":"trace[1822359806] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:368; }","duration":"163.130876ms","start":"2026-05-05T11:17:49.095444Z","end":"2026-05-05T11:17:49.258575Z","steps":["trace[1822359806] 'agreement among raft nodes before linearized reading' (duration: 163.011703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:49.25872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.137516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-05T11:17:49.259886Z","caller":"traceutil/trace.go:171","msg":"trace[1577427293] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:368; }","duration":"112.432853ms","start":"2026-05-05T11:17:49.146388Z","end":"2026-05-05T11:17:49.258821Z","steps":["trace[1577427293] 'agreement among raft nodes before linearized reading' (duration: 112.129297ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:49.438103Z","caller":"traceutil/trace.go:171","msg":"trace[388253500] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"162.88413ms","start":"2026-05-05T11:17:49.275134Z","end":"2026-05-05T11:17:49.438018Z","steps":["trace[388253500] 'process raft request' (duration: 83.819951ms)","trace[388253500] 'compare' (duration: 78.823014ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:17:49.532933Z","caller":"traceutil/trace.go:171","msg":"trace[1801701342] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"173.923676ms","start":"2026-05-05T11:17:49.358984Z","end":"2026-05-05T11:17:49.532907Z","steps":["trace[1801701342] 'process raft request' (duration: 173.736922ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:49.548082Z","caller":"traceutil/trace.go:171","msg":"trace[5965389] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"104.302229ms","start":"2026-05-05T11:17:49.443753Z","end":"2026-05-05T11:17:49.548055Z","steps":["trace[5965389] 'process raft request' (duration: 100.594019ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:49.548283Z","caller":"traceutil/trace.go:171","msg":"trace[173643488] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"104.516043ms","start":"2026-05-05T11:17:49.443753Z","end":"2026-05-05T11:17:49.548269Z","steps":["trace[173643488] 'process raft request' (duration: 103.814168ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:17:49.548383Z","caller":"traceutil/trace.go:171","msg":"trace[1341432992] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"103.525392ms","start":"2026-05-05T11:17:49.444845Z","end":"2026-05-05T11:17:49.548371Z","steps":["trace[1341432992] 'process raft request' (duration: 102.784107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:51.021144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.871078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T11:17:51.021235Z","caller":"traceutil/trace.go:171","msg":"trace[1955778273] range","detail":"{range_begin:/registry/masterleases/199.19.213.122; range_end:; response_count:1; response_revision:402; }","duration":"109.034601ms","start":"2026-05-05T11:17:50.912181Z","end":"2026-05-05T11:17:51.021216Z","steps":["trace[1955778273] 'range keys from in-memory index tree' (duration: 108.679914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:17:51.02148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.81302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5336"} {"level":"info","ts":"2026-05-05T11:17:51.021586Z","caller":"traceutil/trace.go:171","msg":"trace[443424633] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:402; }","duration":"140.946913ms","start":"2026-05-05T11:17:50.880621Z","end":"2026-05-05T11:17:51.021568Z","steps":["trace[443424633] 'range keys from in-memory index tree' (duration: 128.293287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:09.570421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.232242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:18:09.570579Z","caller":"traceutil/trace.go:171","msg":"trace[574085431] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:604; }","duration":"313.443566ms","start":"2026-05-05T11:18:09.257114Z","end":"2026-05-05T11:18:09.570557Z","steps":["trace[574085431] 'range keys from in-memory index tree' (duration: 313.127112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:09.570671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:09.257101Z","time spent":"313.558418ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-05T11:18:09.570679Z","caller":"traceutil/trace.go:171","msg":"trace[860174681] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"306.572262ms","start":"2026-05-05T11:18:09.264078Z","end":"2026-05-05T11:18:09.570651Z","steps":["trace[860174681] 'process raft request' (duration: 304.616089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:09.570847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:09.264058Z","time spent":"306.687194ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:18:09.585038Z","caller":"traceutil/trace.go:171","msg":"trace[542689408] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:622; }","duration":"173.378347ms","start":"2026-05-05T11:18:09.411641Z","end":"2026-05-05T11:18:09.585019Z","steps":["trace[542689408] 'read index received' (duration: 157.052294ms)","trace[542689408] 'applied index is now lower than readState.Index' (duration: 16.325243ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:18:09.585247Z","caller":"traceutil/trace.go:171","msg":"trace[733371863] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"270.100862ms","start":"2026-05-05T11:18:09.31514Z","end":"2026-05-05T11:18:09.58524Z","steps":["trace[733371863] 'process raft request' (duration: 269.766606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:09.585395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.743773ms","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-05-05T11:18:09.585412Z","caller":"traceutil/trace.go:171","msg":"trace[1739809183] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"173.805553ms","start":"2026-05-05T11:18:09.4116Z","end":"2026-05-05T11:18:09.585406Z","steps":["trace[1739809183] 'agreement among raft nodes before linearized reading' (duration: 173.740022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:09.585532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.405789ms","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-05-05T11:18:09.585568Z","caller":"traceutil/trace.go:171","msg":"trace[1267859372] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"136.503091ms","start":"2026-05-05T11:18:09.449054Z","end":"2026-05-05T11:18:09.585558Z","steps":["trace[1267859372] 'agreement among raft nodes before linearized reading' (duration: 136.41693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:09.585699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.119007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:18:09.585772Z","caller":"traceutil/trace.go:171","msg":"trace[1072888097] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:606; }","duration":"129.180048ms","start":"2026-05-05T11:18:09.456528Z","end":"2026-05-05T11:18:09.585708Z","steps":["trace[1072888097] 'agreement among raft nodes before linearized reading' (duration: 129.156738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:10.417268Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182574713921497,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T11:18:10.906049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:10.263189Z","time spent":"642.852063ms","remote":"127.0.0.1:43156","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-05T11:18:10.917838Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182574713921497,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T11:18:10.979802Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.06734658s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T11:18:10.980257Z","caller":"traceutil/trace.go:171","msg":"trace[1739904874] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"1.067902889s","start":"2026-05-05T11:18:09.912329Z","end":"2026-05-05T11:18:10.980232Z","steps":["trace[1739904874] 'process raft request' (duration: 1.067714926s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:10.980511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:09.912307Z","time spent":"1.068029062s","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:18:10.986912Z","caller":"traceutil/trace.go:171","msg":"trace[680912281] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:631; }","duration":"1.069727879s","start":"2026-05-05T11:18:09.917162Z","end":"2026-05-05T11:18:10.986889Z","steps":["trace[680912281] 'read index received' (duration: 1.062901006s)","trace[680912281] 'applied index is now lower than readState.Index' (duration: 6.825953ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:18:10.987176Z","caller":"traceutil/trace.go:171","msg":"trace[671110322] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"1.068480797s","start":"2026-05-05T11:18:09.918684Z","end":"2026-05-05T11:18:10.987165Z","steps":["trace[671110322] 'process raft request' (duration: 1.067883818s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:10.987304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:09.918652Z","time spent":"1.068560698s","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:18:10.987408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.204687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T11:18:10.987395Z","caller":"traceutil/trace.go:171","msg":"trace[267755858] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"925.456197ms","start":"2026-05-05T11:18:10.061916Z","end":"2026-05-05T11:18:10.987372Z","steps":["trace[267755858] 'process raft request' (duration: 924.843518ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:10.987499Z","caller":"traceutil/trace.go:171","msg":"trace[1554609236] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"973.33777ms","start":"2026-05-05T11:18:10.014114Z","end":"2026-05-05T11:18:10.987451Z","steps":["trace[1554609236] 'agreement among raft nodes before linearized reading' (duration: 973.092746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:10.987508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:10.061888Z","time spent":"925.579809ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T11:18:10.987534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.637281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-05-05T11:18:10.987574Z","caller":"traceutil/trace.go:171","msg":"trace[1256311997] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:618; }","duration":"130.705373ms","start":"2026-05-05T11:18:10.856858Z","end":"2026-05-05T11:18:10.987563Z","steps":["trace[1256311997] 'agreement among raft nodes before linearized reading' (duration: 130.456528ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:10.987596Z","caller":"traceutil/trace.go:171","msg":"trace[1773468872] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"524.06937ms","start":"2026-05-05T11:18:10.463517Z","end":"2026-05-05T11:18:10.987586Z","steps":["trace[1773468872] 'process raft request' (duration: 523.291716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:10.987602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:10.014086Z","time spent":"973.503773ms","remote":"127.0.0.1:53762","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-05-05T11:18:10.987637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:10.463481Z","time spent":"524.13756ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T11:18:10.987808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.564992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:18:10.987843Z","caller":"traceutil/trace.go:171","msg":"trace[1832283088] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"323.599353ms","start":"2026-05-05T11:18:10.664232Z","end":"2026-05-05T11:18:10.987831Z","steps":["trace[1832283088] 'agreement among raft nodes before linearized reading' (duration: 323.548502ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:10.987856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.070692005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"warn","ts":"2026-05-05T11:18:10.98787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:10.664171Z","time spent":"323.693724ms","remote":"127.0.0.1:53514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T11:18:10.987885Z","caller":"traceutil/trace.go:171","msg":"trace[2057637904] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:618; }","duration":"1.070720476s","start":"2026-05-05T11:18:09.917154Z","end":"2026-05-05T11:18:10.987875Z","steps":["trace[2057637904] 'agreement among raft nodes before linearized reading' (duration: 1.070595334s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:10.987913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:09.917102Z","time spent":"1.070804597s","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":65460,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" "} {"level":"warn","ts":"2026-05-05T11:18:10.987978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.360194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"warn","ts":"2026-05-05T11:18:10.988014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.25212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:18:10.988019Z","caller":"traceutil/trace.go:171","msg":"trace[882671992] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:618; }","duration":"330.437206ms","start":"2026-05-05T11:18:10.657569Z","end":"2026-05-05T11:18:10.988006Z","steps":["trace[882671992] 'agreement among raft nodes before linearized reading' (duration: 330.328684ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:10.988048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:10.657551Z","time spent":"330.491676ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":92,"response count":1,"response size":5610,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" "} {"level":"info","ts":"2026-05-05T11:18:10.988047Z","caller":"traceutil/trace.go:171","msg":"trace[801249895] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:618; }","duration":"730.30537ms","start":"2026-05-05T11:18:10.25773Z","end":"2026-05-05T11:18:10.988035Z","steps":["trace[801249895] 'agreement among raft nodes before linearized reading' (duration: 730.260169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:10.98808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:10.257715Z","time spent":"730.356881ms","remote":"127.0.0.1:53576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-05-05T11:18:12.098213Z","caller":"traceutil/trace.go:171","msg":"trace[147123543] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"187.571789ms","start":"2026-05-05T11:18:11.910623Z","end":"2026-05-05T11:18:12.098194Z","steps":["trace[147123543] 'process raft request' (duration: 187.271404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:17.527947Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182574713921650,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T11:18:18.028792Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182574713921650,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T11:18:18.530642Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182574713921650,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T11:18:18.685635Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.182649345s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T11:18:18.686002Z","caller":"traceutil/trace.go:171","msg":"trace[928698656] linearizableReadLoop","detail":"{readStateIndex:702; appliedIndex:701; }","duration":"1.65888661s","start":"2026-05-05T11:18:17.027092Z","end":"2026-05-05T11:18:18.685979Z","steps":["trace[928698656] 'read index received' (duration: 1.658762628s)","trace[928698656] 'applied index is now lower than readState.Index' (duration: 122.772µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:18:18.686249Z","caller":"traceutil/trace.go:171","msg":"trace[1346964216] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"1.819268748s","start":"2026-05-05T11:18:16.866929Z","end":"2026-05-05T11:18:18.686197Z","steps":["trace[1346964216] 'process raft request' (duration: 1.818918933s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:18.686385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:16.866898Z","time spent":"1.81941896s","remote":"127.0.0.1:53680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:18:18.686416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.634631333s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-05T11:18:18.686462Z","caller":"traceutil/trace.go:171","msg":"trace[1035893118] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:682; }","duration":"1.634711524s","start":"2026-05-05T11:18:17.051739Z","end":"2026-05-05T11:18:18.68645Z","steps":["trace[1035893118] 'agreement among raft nodes before linearized reading' (duration: 1.634563052s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:18.686507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:17.05173Z","time spent":"1.634763255s","remote":"127.0.0.1:53762","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-05-05T11:18:18.686694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.279438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.687041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"918.87721ms","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-05-05T11:18:18.687058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.931141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:18:18.687066Z","caller":"traceutil/trace.go:171","msg":"trace[2107841780] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:682; }","duration":"918.93029ms","start":"2026-05-05T11:18:17.76813Z","end":"2026-05-05T11:18:18.68706Z","steps":["trace[2107841780] 'agreement among raft nodes before linearized reading' (duration: 918.872809ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:18.687076Z","caller":"traceutil/trace.go:171","msg":"trace[235969309] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:682; }","duration":"470.966782ms","start":"2026-05-05T11:18:18.216103Z","end":"2026-05-05T11:18:18.68707Z","steps":["trace[235969309] 'agreement among raft nodes before linearized reading' (duration: 470.941842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:18.687087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:17.768116Z","time spent":"918.966772ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T11:18:18.687095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:18.216093Z","time spent":"470.996703ms","remote":"127.0.0.1:44784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"info","ts":"2026-05-05T11:18:18.687132Z","caller":"traceutil/trace.go:171","msg":"trace[1666606779] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:682; }","duration":"197.694825ms","start":"2026-05-05T11:18:18.489355Z","end":"2026-05-05T11:18:18.68705Z","steps":["trace[1666606779] 'agreement among raft nodes before linearized reading' (duration: 197.281649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:18.687201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054009097s","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-05-05T11:18:18.687224Z","caller":"traceutil/trace.go:171","msg":"trace[889187012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"1.054096399s","start":"2026-05-05T11:18:17.633123Z","end":"2026-05-05T11:18:18.687219Z","steps":["trace[889187012] 'agreement among raft nodes before linearized reading' (duration: 1.054039678s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:18.687232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.509021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.687242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:17.633104Z","time spent":"1.054134019s","remote":"127.0.0.1:53762","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-05-05T11:18:18.687249Z","caller":"traceutil/trace.go:171","msg":"trace[61103719] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:682; }","duration":"471.570331ms","start":"2026-05-05T11:18:18.215673Z","end":"2026-05-05T11:18:18.687244Z","steps":["trace[61103719] 'agreement among raft nodes before linearized reading' (duration: 471.53606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:18.687345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054286851s","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-05-05T11:18:18.687338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:18.21564Z","time spent":"471.621342ms","remote":"127.0.0.1:44784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"info","ts":"2026-05-05T11:18:18.687368Z","caller":"traceutil/trace.go:171","msg":"trace[715690703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:682; }","duration":"1.054331783s","start":"2026-05-05T11:18:17.63303Z","end":"2026-05-05T11:18:18.687362Z","steps":["trace[715690703] 'agreement among raft nodes before linearized reading' (duration: 1.054277422s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:18.687386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:17.633016Z","time spent":"1.054364473s","remote":"127.0.0.1:53762","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-05-05T11:18:18.687736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.794475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:18:18.689262Z","caller":"traceutil/trace.go:171","msg":"trace[1043537248] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:682; }","duration":"121.319969ms","start":"2026-05-05T11:18:18.567928Z","end":"2026-05-05T11:18:18.689248Z","steps":["trace[1043537248] 'agreement among raft nodes before linearized reading' (duration: 119.781665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:18.68841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.659212825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T11:18:18.689545Z","caller":"traceutil/trace.go:171","msg":"trace[2006999819] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:682; }","duration":"1.662488935s","start":"2026-05-05T11:18:17.027044Z","end":"2026-05-05T11:18:18.689533Z","steps":["trace[2006999819] 'agreement among raft nodes before linearized reading' (duration: 1.659127694s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:18.689614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:17.027024Z","time spent":"1.662576346s","remote":"127.0.0.1:53762","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-05-05T11:18:18.686756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.162308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.686841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.244189ms","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":"warn","ts":"2026-05-05T11:18:18.686878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.037158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.68692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.871621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.68693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.316757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.686942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.520467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.686956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.947753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.686965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.754401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.686989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.654196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.686991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.826922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.687008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.052642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.687037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.133624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.688494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.300374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.688557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.65748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.688605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.130627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.688638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.533164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.688639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.623955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:18.686714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.37437ms","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-05-05T11:18:18.692361Z","caller":"traceutil/trace.go:171","msg":"trace[1158059033] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:682; }","duration":"203.047067ms","start":"2026-05-05T11:18:18.4893Z","end":"2026-05-05T11:18:18.692347Z","steps":["trace[1158059033] 'agreement among raft nodes before linearized reading' (duration: 197.38295ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:18.692546Z","caller":"traceutil/trace.go:171","msg":"trace[2122877298] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:682; }","duration":"243.992167ms","start":"2026-05-05T11:18:18.448546Z","end":"2026-05-05T11:18:18.692538Z","steps":["trace[2122877298] 'agreement among raft nodes before linearized reading' (duration: 238.191568ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:18.692681Z","caller":"traceutil/trace.go:171","msg":"trace[104237561] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:682; }","duration":"244.16112ms","start":"2026-05-05T11:18:18.448513Z","end":"2026-05-05T11:18:18.692674Z","steps":["trace[104237561] 'agreement among raft nodes before linearized reading' (duration: 238.258549ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:18.692754Z","caller":"traceutil/trace.go:171","msg":"trace[2095377559] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:682; }","duration":"448.77602ms","start":"2026-05-05T11:18:18.243938Z","end":"2026-05-05T11:18:18.692714Z","steps":["trace[2095377559] 'agreement among raft nodes before linearized reading' (duration: 443.059092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:18.693206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:18.243931Z","time spent":"449.176496ms","remote":"127.0.0.1:44790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":114,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-05T11:18:18.692781Z","caller":"traceutil/trace.go:171","msg":"trace[1634814808] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:682; }","duration":"289.788392ms","start":"2026-05-05T11:18:18.402987Z","end":"2026-05-05T11:18:18.692775Z","steps":["trace[1634814808] 'agreement among raft nodes before linearized reading' (duration: 283.957972ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:18.692779Z","caller":"traceutil/trace.go:171","msg":"trace[1400509969] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:682; }","duration":"493.354657ms","start":"2026-05-05T11:18:18.199402Z","end":"2026-05-05T11:18:18.692756Z","steps":["trace[1400509969] 'agreement among raft nodes before linearized reading' (duration: 487.529147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:18.693443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:18.199395Z","time spent":"494.024057ms","remote":"127.0.0.1:44774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"info","ts":"2026-05-05T11:18:18.692796Z","caller":"traceutil/trace.go:171","msg":"trace[1465875863] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:682; }","duration":"493.60022ms","start":"2026-05-05T11:18:18.19919Z","end":"2026-05-05T11:18:18.69279Z","steps":["trace[1465875863] 'agreement among raft nodes before linearized reading' (duration: 487.763151ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:18.693626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:18.199184Z","time spent":"494.429523ms","remote":"127.0.0.1:44774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"info","ts":"2026-05-05T11:18:18.692803Z","caller":"traceutil/trace.go:171","msg":"trace[1532477922] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:682; }","duration":"288.99197ms","start":"2026-05-05T11:18:18.403805Z","end":"2026-05-05T11:18:18.692797Z","steps":["trace[1532477922] 'agreement among raft nodes before linearized reading' (duration: 283.056379ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:18.692819Z","caller":"traceutil/trace.go:171","msg":"trace[1177031315] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:682; }","duration":"289.796832ms","start":"2026-05-05T11:18:18.403018Z","end":"2026-05-05T11:18:18.692815Z","steps":["trace[1177031315] 'agreement among raft nodes before linearized reading' (duration: 283.870891ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:18.692833Z","caller":"traceutil/trace.go:171","msg":"trace[2000730082] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:682; }","duration":"448.495326ms","start":"2026-05-05T11:18:18.244309Z","end":"2026-05-05T11:18:18.692804Z","steps":["trace[2000730082] 'agreement among raft nodes before linearized reading' (duration: 442.668447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:18.693966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:18.244287Z","time spent":"449.665304ms","remote":"127.0.0.1:44790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 "} {"level":"info","ts":"2026-05-05T11:18:18.692834Z","caller":"traceutil/trace.go:171","msg":"trace[957034987] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:682; }","duration":"477.239179ms","start":"2026-05-05T11:18:18.215591Z","end":"2026-05-05T11:18:18.69283Z","steps":["trace[957034987] 'agreement among raft nodes before linearized reading' (duration: 471.314278ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:18.694128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:18.215584Z","time spent":"478.533409ms","remote":"127.0.0.1:44784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-05-05T11:18:18.692847Z","caller":"traceutil/trace.go:171","msg":"trace[1488468674] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:682; }","duration":"493.719292ms","start":"2026-05-05T11:18:18.199124Z","end":"2026-05-05T11:18:18.692843Z","steps":["trace[1488468674] 'agreement among raft nodes before linearized reading' (duration: 487.854232ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:18.694308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:18.199105Z","time spent":"495.192195ms","remote":"127.0.0.1:44774","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"info","ts":"2026-05-05T11:18:18.69286Z","caller":"traceutil/trace.go:171","msg":"trace[610164384] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:682; }","duration":"153.789198ms","start":"2026-05-05T11:18:18.539065Z","end":"2026-05-05T11:18:18.692854Z","steps":["trace[610164384] 'agreement among raft nodes before linearized reading' (duration: 149.553924ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:18.692864Z","caller":"traceutil/trace.go:171","msg":"trace[214514663] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:682; }","duration":"202.701251ms","start":"2026-05-05T11:18:18.490159Z","end":"2026-05-05T11:18:18.69286Z","steps":["trace[214514663] 'agreement among raft nodes before linearized reading' (duration: 198.303604ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:18.692877Z","caller":"traceutil/trace.go:171","msg":"trace[377173427] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:682; }","duration":"125.003035ms","start":"2026-05-05T11:18:18.567868Z","end":"2026-05-05T11:18:18.692871Z","steps":["trace[377173427] 'agreement among raft nodes before linearized reading' (duration: 120.643619ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:18.692875Z","caller":"traceutil/trace.go:171","msg":"trace[202828758] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:682; }","duration":"449.012163ms","start":"2026-05-05T11:18:18.24386Z","end":"2026-05-05T11:18:18.692872Z","steps":["trace[202828758] 'agreement among raft nodes before linearized reading' (duration: 443.157544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:18.694907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:18.243839Z","time spent":"451.054466ms","remote":"127.0.0.1:44790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":115,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 "} {"level":"info","ts":"2026-05-05T11:18:18.692893Z","caller":"traceutil/trace.go:171","msg":"trace[1316938547] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:682; }","duration":"153.465924ms","start":"2026-05-05T11:18:18.539422Z","end":"2026-05-05T11:18:18.692888Z","steps":["trace[1316938547] 'agreement among raft nodes before linearized reading' (duration: 149.154028ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:18.692918Z","caller":"traceutil/trace.go:171","msg":"trace[1703098294] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:682; }","duration":"153.92281ms","start":"2026-05-05T11:18:18.538989Z","end":"2026-05-05T11:18:18.692912Z","steps":["trace[1703098294] 'agreement among raft nodes before linearized reading' (duration: 149.631175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:21.223263Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182574713921705,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T11:18:21.723829Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14360182574713921705,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T11:18:21.875211Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.155042175s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T11:18:21.875527Z","caller":"traceutil/trace.go:171","msg":"trace[876160177] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"1.155470751s","start":"2026-05-05T11:18:20.720039Z","end":"2026-05-05T11:18:21.87551Z","steps":["trace[876160177] 'process raft request' (duration: 1.155302409s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:21.875626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:20.720015Z","time spent":"1.155554702s","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:18:21.876187Z","caller":"traceutil/trace.go:171","msg":"trace[985744636] linearizableReadLoop","detail":"{readStateIndex:709; appliedIndex:707; }","duration":"1.1539925s","start":"2026-05-05T11:18:20.722175Z","end":"2026-05-05T11:18:21.876168Z","steps":["trace[985744636] 'read index received' (duration: 1.153181658s)","trace[985744636] 'applied index is now lower than readState.Index' (duration: 810.192µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:18:21.876285Z","caller":"traceutil/trace.go:171","msg":"trace[281357882] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"482.74893ms","start":"2026-05-05T11:18:21.393512Z","end":"2026-05-05T11:18:21.876261Z","steps":["trace[281357882] 'process raft request' (duration: 482.564188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:21.876356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154163202s","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-05-05T11:18:21.876356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.677863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:21.876373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151627854s","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-05-05T11:18:21.876381Z","caller":"traceutil/trace.go:171","msg":"trace[2104970405] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:689; }","duration":"1.154237713s","start":"2026-05-05T11:18:20.722135Z","end":"2026-05-05T11:18:21.876373Z","steps":["trace[2104970405] 'agreement among raft nodes before linearized reading' (duration: 1.154125591s)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:21.876402Z","caller":"traceutil/trace.go:171","msg":"trace[64005742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:689; }","duration":"1.151677325s","start":"2026-05-05T11:18:20.724716Z","end":"2026-05-05T11:18:21.876394Z","steps":["trace[64005742] 'agreement among raft nodes before linearized reading' (duration: 1.151594563s)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:21.876391Z","caller":"traceutil/trace.go:171","msg":"trace[1248208055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"211.754675ms","start":"2026-05-05T11:18:21.664627Z","end":"2026-05-05T11:18:21.876382Z","steps":["trace[1248208055] 'agreement among raft nodes before linearized reading' (duration: 211.674893ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:21.876409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:20.722092Z","time spent":"1.154310165s","remote":"127.0.0.1:53762","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-05-05T11:18:21.876419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:20.72471Z","time spent":"1.151704966s","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T11:18:21.876408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:21.393485Z","time spent":"482.857372ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T11:18:21.87649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.272529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.122\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T11:18:21.876503Z","caller":"traceutil/trace.go:171","msg":"trace[745102011] range","detail":"{range_begin:/registry/masterleases/199.19.213.122; range_end:; response_count:1; response_revision:689; }","duration":"963.323189ms","start":"2026-05-05T11:18:20.913176Z","end":"2026-05-05T11:18:21.876499Z","steps":["trace[745102011] 'agreement among raft nodes before linearized reading' (duration: 963.297498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:21.876515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:20.913156Z","time spent":"963.3568ms","remote":"127.0.0.1:53552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.122\" "} {"level":"warn","ts":"2026-05-05T11:18:21.876489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154136311s","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-05-05T11:18:21.876547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.151930689s","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-05-05T11:18:21.876557Z","caller":"traceutil/trace.go:171","msg":"trace[1554646703] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:689; }","duration":"1.154240263s","start":"2026-05-05T11:18:20.722297Z","end":"2026-05-05T11:18:21.876537Z","steps":["trace[1554646703] 'agreement among raft nodes before linearized reading' (duration: 1.154009459s)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:21.876569Z","caller":"traceutil/trace.go:171","msg":"trace[971066736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:689; }","duration":"1.15197904s","start":"2026-05-05T11:18:20.724581Z","end":"2026-05-05T11:18:21.87656Z","steps":["trace[971066736] 'agreement among raft nodes before linearized reading' (duration: 1.151915229s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:21.876591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:20.724571Z","time spent":"1.15201653s","remote":"127.0.0.1:53762","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-05-05T11:18:21.876602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:20.722285Z","time spent":"1.154308484s","remote":"127.0.0.1:53762","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-05-05T11:18:27.037253Z","caller":"traceutil/trace.go:171","msg":"trace[1157819029] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"107.411937ms","start":"2026-05-05T11:18:26.929801Z","end":"2026-05-05T11:18:27.037213Z","steps":["trace[1157819029] 'process raft request' (duration: 81.233748ms)","trace[1157819029] 'compare' (duration: 26.014966ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:18:27.856078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.975504ms","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-05-05T11:18:27.856264Z","caller":"traceutil/trace.go:171","msg":"trace[356612919] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:724; }","duration":"300.202517ms","start":"2026-05-05T11:18:27.556035Z","end":"2026-05-05T11:18:27.856238Z","steps":["trace[356612919] 'range keys from in-memory index tree' (duration: 299.946014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:27.856641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.791814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T11:18:27.856785Z","caller":"traceutil/trace.go:171","msg":"trace[1534336677] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"192.257701ms","start":"2026-05-05T11:18:27.664505Z","end":"2026-05-05T11:18:27.856763Z","steps":["trace[1534336677] 'read index received' (duration: 61.751µs)","trace[1534336677] 'applied index is now lower than readState.Index' (duration: 192.155299ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:18:27.856847Z","caller":"traceutil/trace.go:171","msg":"trace[36500425] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"371.853439ms","start":"2026-05-05T11:18:27.484969Z","end":"2026-05-05T11:18:27.856822Z","steps":["trace[36500425] 'process raft request' (duration: 25.491457ms)","trace[36500425] 'compare' (duration: 345.616042ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T11:18:27.856964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:27.484939Z","time spent":"371.976902ms","remote":"127.0.0.1:53592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T11:18:27.856976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.475954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T11:18:27.857046Z","caller":"traceutil/trace.go:171","msg":"trace[1034441028] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:725; }","duration":"192.608655ms","start":"2026-05-05T11:18:27.664423Z","end":"2026-05-05T11:18:27.857032Z","steps":["trace[1034441028] 'agreement among raft nodes before linearized reading' (duration: 192.410543ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:28.1306Z","caller":"traceutil/trace.go:171","msg":"trace[1366347985] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"116.288745ms","start":"2026-05-05T11:18:28.014282Z","end":"2026-05-05T11:18:28.130571Z","steps":["trace[1366347985] 'process raft request' (duration: 115.110789ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:28.130668Z","caller":"traceutil/trace.go:171","msg":"trace[1274204525] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"116.270654ms","start":"2026-05-05T11:18:28.014391Z","end":"2026-05-05T11:18:28.130662Z","steps":["trace[1274204525] 'process raft request' (duration: 116.060591ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:28.130623Z","caller":"traceutil/trace.go:171","msg":"trace[1231291057] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"104.272324ms","start":"2026-05-05T11:18:28.026333Z","end":"2026-05-05T11:18:28.130605Z","steps":["trace[1231291057] 'process raft request' (duration: 104.173592ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:36.154676Z","caller":"traceutil/trace.go:171","msg":"trace[1684923737] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"111.110399ms","start":"2026-05-05T11:18:36.043547Z","end":"2026-05-05T11:18:36.154658Z","steps":["trace[1684923737] 'process raft request' (duration: 110.988058ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:36.156277Z","caller":"traceutil/trace.go:171","msg":"trace[1439273299] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"111.317463ms","start":"2026-05-05T11:18:36.044937Z","end":"2026-05-05T11:18:36.156255Z","steps":["trace[1439273299] 'process raft request' (duration: 110.886297ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:18:39.900965Z","caller":"traceutil/trace.go:171","msg":"trace[682000504] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"235.374272ms","start":"2026-05-05T11:18:39.665542Z","end":"2026-05-05T11:18:39.900916Z","steps":["trace[682000504] 'read index received' (duration: 234.704273ms)","trace[682000504] 'applied index is now lower than readState.Index' (duration: 668.639µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:18:39.901165Z","caller":"traceutil/trace.go:171","msg":"trace[497674640] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"907.752354ms","start":"2026-05-05T11:18:38.993363Z","end":"2026-05-05T11:18:39.901115Z","steps":["trace[497674640] 'process raft request' (duration: 907.265198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:18:39.901284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.711497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T11:18:39.901439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:18:38.993342Z","time spent":"907.928846ms","remote":"127.0.0.1:53730","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:18:39.901487Z","caller":"traceutil/trace.go:171","msg":"trace[119560901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:809; }","duration":"235.864399ms","start":"2026-05-05T11:18:39.665536Z","end":"2026-05-05T11:18:39.901401Z","steps":["trace[119560901] 'agreement among raft nodes before linearized reading' (duration: 235.620886ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:19:30.750856Z","caller":"traceutil/trace.go:171","msg":"trace[1333720888] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"182.438453ms","start":"2026-05-05T11:19:30.568387Z","end":"2026-05-05T11:19:30.750825Z","steps":["trace[1333720888] 'process raft request' (duration: 141.207864ms)","trace[1333720888] 'compare' (duration: 41.013115ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T11:19:30.751016Z","caller":"traceutil/trace.go:171","msg":"trace[1963587003] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"181.953003ms","start":"2026-05-05T11:19:30.569042Z","end":"2026-05-05T11:19:30.750995Z","steps":["trace[1963587003] 'process raft request' (duration: 181.668068ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:19:30.751154Z","caller":"traceutil/trace.go:171","msg":"trace[200883667] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"181.516735ms","start":"2026-05-05T11:19:30.569626Z","end":"2026-05-05T11:19:30.751142Z","steps":["trace[200883667] 'process raft request' (duration: 181.110488ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:20:24.924971Z","caller":"traceutil/trace.go:171","msg":"trace[1594900075] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"112.237301ms","start":"2026-05-05T11:20:24.812707Z","end":"2026-05-05T11:20:24.924945Z","steps":["trace[1594900075] 'process raft request' (duration: 111.969407ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:20:25.140139Z","caller":"traceutil/trace.go:171","msg":"trace[1086471461] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"208.846091ms","start":"2026-05-05T11:20:24.930527Z","end":"2026-05-05T11:20:25.139373Z","steps":["trace[1086471461] 'process raft request' (duration: 208.590447ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T11:20:33.559475Z","caller":"traceutil/trace.go:171","msg":"trace[581154810] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"369.997ms","start":"2026-05-05T11:20:33.189453Z","end":"2026-05-05T11:20:33.55945Z","steps":["trace[581154810] 'process raft request' (duration: 369.637615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:20:33.55968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:20:33.189442Z","time spent":"370.106371ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:20:33.604947Z","caller":"traceutil/trace.go:171","msg":"trace[1212129008] transaction","detail":"{read_only:false; response_revision:1352; number_of_response:1; }","duration":"413.739836ms","start":"2026-05-05T11:20:33.191182Z","end":"2026-05-05T11:20:33.604921Z","steps":["trace[1212129008] 'process raft request' (duration: 413.536993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:20:33.60514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:20:33.191161Z","time spent":"413.859787ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:20:33.60523Z","caller":"traceutil/trace.go:171","msg":"trace[496345455] transaction","detail":"{read_only:false; response_revision:1353; number_of_response:1; }","duration":"412.161072ms","start":"2026-05-05T11:20:33.193047Z","end":"2026-05-05T11:20:33.605208Z","steps":["trace[496345455] 'process raft request' (duration: 411.825967ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:20:33.605354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:20:33.193034Z","time spent":"412.259003ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T11:20:33.605434Z","caller":"traceutil/trace.go:171","msg":"trace[1030984910] transaction","detail":"{read_only:false; response_revision:1354; number_of_response:1; }","duration":"410.280975ms","start":"2026-05-05T11:20:33.195123Z","end":"2026-05-05T11:20:33.605404Z","steps":["trace[1030984910] 'process raft request' (duration: 409.995631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T11:20:33.605576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T11:20:33.19511Z","time spent":"410.396266ms","remote":"127.0.0.1:53762","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"}