{"level":"warn","ts":"2026-07-16T15:48:33.127531Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T15:48:33.127642Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-16T15:48:33.127714Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T15:48:33.127727Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-16T15:48:33.127747Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-16T15:48:33.128369Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-07-16T15:48:33.128445Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-16T15:48:33.140241Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.736674ms"} {"level":"info","ts":"2026-07-16T15:48:33.146974Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-07-16T15:48:33.147091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T15:48:33.147119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-07-16T15:48:33.147141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T15:48:33.147319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-07-16T15:48:33.14739Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-07-16T15:48:33.161274Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T15:48:33.162632Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T15:48:33.165142Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-16T15:48:33.166841Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T15:48:33.167418Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T15:48:33.167497Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T15:48:33.167482Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T15:48:33.167512Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-16T15:48:33.174639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-07-16T15:48:33.174828Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-07-16T15:48:33.1771Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-16T15:48:33.177206Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-16T15:48:33.177255Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-07-16T15:48:33.177403Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T15:48:33.177445Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T15:48:33.247665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T15:48:33.247745Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T15:48:33.24784Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-07-16T15:48:33.247891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-07-16T15:48:33.247904Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-16T15:48:33.24792Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-07-16T15:48:33.247939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-07-16T15:48:33.249136Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T15:48:33.249113Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T15:48:33.249086Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T15:48:33.249605Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T15:48:33.25014Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T15:48:33.250266Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T15:48:33.250931Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T15:48:33.25105Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T15:48:33.251123Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T15:48:33.251669Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-07-16T15:48:33.253336Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-16T15:48:39.406812Z","caller":"traceutil/trace.go:171","msg":"trace[1109553739] linearizableReadLoop","detail":"{readStateIndex:270; appliedIndex:269; }","duration":"249.776747ms","start":"2026-07-16T15:48:39.156999Z","end":"2026-07-16T15:48:39.406776Z","steps":["trace[1109553739] 'read index received' (duration: 249.586353ms)","trace[1109553739] 'applied index is now lower than readState.Index' (duration: 189.024µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:48:39.406904Z","caller":"traceutil/trace.go:171","msg":"trace[959645528] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"261.959651ms","start":"2026-07-16T15:48:39.144918Z","end":"2026-07-16T15:48:39.406878Z","steps":["trace[959645528] 'process raft request' (duration: 261.674174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:48:39.407106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.087424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-16T15:48:39.407231Z","caller":"traceutil/trace.go:171","msg":"trace[2001291790] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:262; }","duration":"250.238268ms","start":"2026-07-16T15:48:39.156968Z","end":"2026-07-16T15:48:39.407206Z","steps":["trace[2001291790] 'agreement among raft nodes before linearized reading' (duration: 250.007392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:48:39.4092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.974031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-07-16T15:48:39.409234Z","caller":"traceutil/trace.go:171","msg":"trace[1828028593] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:262; }","duration":"103.063693ms","start":"2026-07-16T15:48:39.306162Z","end":"2026-07-16T15:48:39.409226Z","steps":["trace[1828028593] 'agreement among raft nodes before linearized reading' (duration: 103.002011ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:48:39.409371Z","caller":"traceutil/trace.go:171","msg":"trace[1427656947] transaction","detail":"{read_only:false; number_of_response:0; response_revision:262; }","duration":"103.024001ms","start":"2026-07-16T15:48:39.306341Z","end":"2026-07-16T15:48:39.409365Z","steps":["trace[1427656947] 'process raft request' (duration: 102.722654ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:48:39.409475Z","caller":"traceutil/trace.go:171","msg":"trace[895258311] transaction","detail":"{read_only:false; number_of_response:0; response_revision:262; }","duration":"102.491079ms","start":"2026-07-16T15:48:39.306979Z","end":"2026-07-16T15:48:39.40947Z","steps":["trace[895258311] 'process raft request' (duration: 102.134571ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:48:39.409551Z","caller":"traceutil/trace.go:171","msg":"trace[972079656] transaction","detail":"{read_only:false; number_of_response:0; response_revision:262; }","duration":"102.405947ms","start":"2026-07-16T15:48:39.307141Z","end":"2026-07-16T15:48:39.409547Z","steps":["trace[972079656] 'process raft request' (duration: 101.989747ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:48:39.40962Z","caller":"traceutil/trace.go:171","msg":"trace[2128845217] transaction","detail":"{read_only:false; number_of_response:0; response_revision:262; }","duration":"101.529636ms","start":"2026-07-16T15:48:39.308085Z","end":"2026-07-16T15:48:39.409615Z","steps":["trace[2128845217] 'process raft request' (duration: 101.061986ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:48:40.648423Z","caller":"traceutil/trace.go:171","msg":"trace[2105771102] linearizableReadLoop","detail":"{readStateIndex:291; appliedIndex:290; }","duration":"378.755271ms","start":"2026-07-16T15:48:40.26964Z","end":"2026-07-16T15:48:40.648395Z","steps":["trace[2105771102] 'read index received' (duration: 378.565927ms)","trace[2105771102] 'applied index is now lower than readState.Index' (duration: 188.244µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:48:40.648745Z","caller":"traceutil/trace.go:171","msg":"trace[2125706352] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"640.480508ms","start":"2026-07-16T15:48:40.008254Z","end":"2026-07-16T15:48:40.648735Z","steps":["trace[2125706352] 'process raft request' (duration: 639.976737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:48:40.64916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:48:40.008236Z","time spent":"640.543549ms","remote":"127.0.0.1:55738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":219,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T15:48:40.649431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.793496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-16T15:48:40.649471Z","caller":"traceutil/trace.go:171","msg":"trace[437685392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:279; }","duration":"379.874879ms","start":"2026-07-16T15:48:40.269586Z","end":"2026-07-16T15:48:40.649461Z","steps":["trace[437685392] 'agreement among raft nodes before linearized reading' (duration: 379.754556ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:48:40.649506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:48:40.269564Z","time spent":"379.935049ms","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T15:48:40.649696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.251276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-16T15:48:40.649761Z","caller":"traceutil/trace.go:171","msg":"trace[53511508] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:279; }","duration":"367.365959ms","start":"2026-07-16T15:48:40.282382Z","end":"2026-07-16T15:48:40.649748Z","steps":["trace[53511508] 'agreement among raft nodes before linearized reading' (duration: 367.226426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:48:40.649793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:48:40.282367Z","time spent":"367.42191ms","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T15:48:41.159173Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514875874442647,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T15:48:42.724961Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.361013277s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-16T15:48:43.382202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.017666557s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T15:48:43.382433Z","caller":"traceutil/trace.go:171","msg":"trace[38778274] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"2.728886669s","start":"2026-07-16T15:48:40.653521Z","end":"2026-07-16T15:48:43.382408Z","steps":["trace[38778274] 'process raft request' (duration: 710.454895ms)","trace[38778274] 'compare' (duration: 2.017541504s)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:48:43.382497Z","caller":"traceutil/trace.go:171","msg":"trace[1893862553] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"2.723661859s","start":"2026-07-16T15:48:40.658804Z","end":"2026-07-16T15:48:43.382466Z","steps":["trace[1893862553] 'process raft request' (duration: 2.723518566s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:48:43.382559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:48:40.653508Z","time spent":"2.729000321s","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T15:48:43.382657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:48:40.658784Z","time spent":"2.723788353s","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T15:48:43.382924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.724063329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-16T15:48:43.38298Z","caller":"traceutil/trace.go:171","msg":"trace[261022783] linearizableReadLoop","detail":"{readStateIndex:293; appliedIndex:291; }","duration":"2.723584898s","start":"2026-07-16T15:48:40.658782Z","end":"2026-07-16T15:48:43.382367Z","steps":["trace[261022783] 'read index received' (duration: 705.198305ms)","trace[261022783] 'applied index is now lower than readState.Index' (duration: 2.018385833s)"],"step_count":2} {"level":"warn","ts":"2026-07-16T15:48:43.383305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.960313565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-16T15:48:43.383328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.963017917s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-16T15:48:43.383364Z","caller":"traceutil/trace.go:171","msg":"trace[856984987] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:281; }","duration":"1.960372447s","start":"2026-07-16T15:48:41.422976Z","end":"2026-07-16T15:48:43.383348Z","steps":["trace[856984987] 'agreement among raft nodes before linearized reading' (duration: 1.960273704s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:48:43.383413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:48:41.422905Z","time spent":"1.96049709s","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-16T15:48:43.383407Z","caller":"traceutil/trace.go:171","msg":"trace[367774444] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:281; }","duration":"1.9631469s","start":"2026-07-16T15:48:41.420237Z","end":"2026-07-16T15:48:43.383384Z","steps":["trace[367774444] 'agreement among raft nodes before linearized reading' (duration: 1.962995707s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:48:43.383501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:48:41.420213Z","time spent":"1.963276614s","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-16T15:48:43.384234Z","caller":"traceutil/trace.go:171","msg":"trace[417097994] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:281; }","duration":"2.724330714s","start":"2026-07-16T15:48:40.65875Z","end":"2026-07-16T15:48:43.383081Z","steps":["trace[417097994] 'agreement among raft nodes before linearized reading' (duration: 2.723862194s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:48:43.384329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:48:40.658734Z","time spent":"2.725577143s","remote":"127.0.0.1:55652","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":"info","ts":"2026-07-16T15:48:43.661175Z","caller":"traceutil/trace.go:171","msg":"trace[792132398] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"273.733828ms","start":"2026-07-16T15:48:43.387375Z","end":"2026-07-16T15:48:43.661109Z","steps":["trace[792132398] 'process raft request' (duration: 273.515444ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:48:43.662192Z","caller":"traceutil/trace.go:171","msg":"trace[1102957702] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"274.31749ms","start":"2026-07-16T15:48:43.387852Z","end":"2026-07-16T15:48:43.66217Z","steps":["trace[1102957702] 'process raft request' (duration: 274.268508ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:48:43.662291Z","caller":"traceutil/trace.go:171","msg":"trace[311979510] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"274.562967ms","start":"2026-07-16T15:48:43.387698Z","end":"2026-07-16T15:48:43.662261Z","steps":["trace[311979510] 'process raft request' (duration: 274.313731ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:01.58012Z","caller":"traceutil/trace.go:171","msg":"trace[15849289] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"109.17963ms","start":"2026-07-16T15:49:01.470912Z","end":"2026-07-16T15:49:01.580092Z","steps":["trace[15849289] 'process raft request' (duration: 109.077968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:02.405489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.543966ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-16T15:49:02.405584Z","caller":"traceutil/trace.go:171","msg":"trace[1983457522] linearizableReadLoop","detail":"{readStateIndex:440; appliedIndex:439; }","duration":"661.060444ms","start":"2026-07-16T15:49:01.744509Z","end":"2026-07-16T15:49:02.40557Z","steps":["trace[1983457522] 'read index received' (duration: 359.493558ms)","trace[1983457522] 'applied index is now lower than readState.Index' (duration: 301.565866ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T15:49:02.405691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.175217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T15:49:02.405721Z","caller":"traceutil/trace.go:171","msg":"trace[131015614] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:423; }","duration":"661.243728ms","start":"2026-07-16T15:49:01.744469Z","end":"2026-07-16T15:49:02.405713Z","steps":["trace[131015614] 'agreement among raft nodes before linearized reading' (duration: 661.173817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:02.405745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:01.744425Z","time spent":"661.315149ms","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T15:49:02.405812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.919781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-16T15:49:02.405901Z","caller":"traceutil/trace.go:171","msg":"trace[909108179] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:423; }","duration":"660.074675ms","start":"2026-07-16T15:49:01.745806Z","end":"2026-07-16T15:49:02.405881Z","steps":["trace[909108179] 'agreement among raft nodes before linearized reading' (duration: 659.882561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:02.405952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:01.7458Z","time spent":"660.144097ms","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-16T15:49:12.74707Z","caller":"traceutil/trace.go:171","msg":"trace[23144382] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"764.164361ms","start":"2026-07-16T15:49:11.982879Z","end":"2026-07-16T15:49:12.747044Z","steps":["trace[23144382] 'process raft request' (duration: 764.054929ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:12.747051Z","caller":"traceutil/trace.go:171","msg":"trace[469550792] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"776.213675ms","start":"2026-07-16T15:49:11.970797Z","end":"2026-07-16T15:49:12.74701Z","steps":["trace[469550792] 'process raft request' (duration: 775.991021ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:12.747221Z","caller":"traceutil/trace.go:171","msg":"trace[1644756758] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"730.249694ms","start":"2026-07-16T15:49:12.016923Z","end":"2026-07-16T15:49:12.747172Z","steps":["trace[1644756758] 'process raft request' (duration: 730.09565ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:12.747265Z","caller":"traceutil/trace.go:171","msg":"trace[80592387] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"726.539295ms","start":"2026-07-16T15:49:12.020713Z","end":"2026-07-16T15:49:12.747252Z","steps":["trace[80592387] 'process raft request' (duration: 726.435743ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:12.747287Z","caller":"traceutil/trace.go:171","msg":"trace[496606885] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"726.641267ms","start":"2026-07-16T15:49:12.02061Z","end":"2026-07-16T15:49:12.747251Z","steps":["trace[496606885] 'process raft request' (duration: 726.498644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:12.747321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:12.020679Z","time spent":"726.622496ms","remote":"127.0.0.1:55598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T15:49:12.74738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:12.016893Z","time spent":"730.416187ms","remote":"127.0.0.1:55598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T15:49:12.747442Z","caller":"traceutil/trace.go:171","msg":"trace[1612316275] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"730.075981ms","start":"2026-07-16T15:49:12.017356Z","end":"2026-07-16T15:49:12.747432Z","steps":["trace[1612316275] 'process raft request' (duration: 729.710013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:12.747492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:12.017348Z","time spent":"730.115112ms","remote":"127.0.0.1:55598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T15:49:12.747379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:12.020596Z","time spent":"726.737439ms","remote":"127.0.0.1:55598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T15:49:12.747484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:11.970776Z","time spent":"776.503222ms","remote":"127.0.0.1:55598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T15:49:12.747605Z","caller":"traceutil/trace.go:171","msg":"trace[2110671482] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:500; }","duration":"322.705629ms","start":"2026-07-16T15:49:12.424872Z","end":"2026-07-16T15:49:12.747577Z","steps":["trace[2110671482] 'read index received' (duration: 301.336575ms)","trace[2110671482] 'applied index is now lower than readState.Index' (duration: 21.367894ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:49:12.747663Z","caller":"traceutil/trace.go:171","msg":"trace[716199252] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"711.109381ms","start":"2026-07-16T15:49:12.036545Z","end":"2026-07-16T15:49:12.747654Z","steps":["trace[716199252] 'process raft request' (duration: 710.745034ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:12.747679Z","caller":"traceutil/trace.go:171","msg":"trace[2091825513] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"726.904512ms","start":"2026-07-16T15:49:12.020724Z","end":"2026-07-16T15:49:12.747628Z","steps":["trace[2091825513] 'process raft request' (duration: 726.463304ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:12.747734Z","caller":"traceutil/trace.go:171","msg":"trace[1916009685] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"600.547159ms","start":"2026-07-16T15:49:12.147176Z","end":"2026-07-16T15:49:12.747723Z","steps":["trace[1916009685] 'process raft request' (duration: 600.340955ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:12.747524Z","caller":"traceutil/trace.go:171","msg":"trace[572792340] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"723.209684ms","start":"2026-07-16T15:49:12.024242Z","end":"2026-07-16T15:49:12.747452Z","steps":["trace[572792340] 'process raft request' (duration: 722.99091ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:12.747159Z","caller":"traceutil/trace.go:171","msg":"trace[725051440] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"764.165451ms","start":"2026-07-16T15:49:11.982945Z","end":"2026-07-16T15:49:12.747111Z","steps":["trace[725051440] 'process raft request' (duration: 764.031349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:12.747827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:12.024232Z","time spent":"723.56655ms","remote":"127.0.0.1:55598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T15:49:12.747833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:12.147147Z","time spent":"600.620071ms","remote":"127.0.0.1:55598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T15:49:12.747208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:11.982859Z","time spent":"764.278983ms","remote":"127.0.0.1:55598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T15:49:12.747896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.546783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T15:49:12.747861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:12.02069Z","time spent":"727.076245ms","remote":"127.0.0.1:55598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T15:49:12.747963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.091896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:12.747928Z","caller":"traceutil/trace.go:171","msg":"trace[686950722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"272.605583ms","start":"2026-07-16T15:49:12.475313Z","end":"2026-07-16T15:49:12.747919Z","steps":["trace[686950722] 'agreement among raft nodes before linearized reading' (duration: 272.44063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:12.747962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:11.982937Z","time spent":"764.930225ms","remote":"127.0.0.1:55598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T15:49:12.747995Z","caller":"traceutil/trace.go:171","msg":"trace[386896167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:493; }","duration":"323.154567ms","start":"2026-07-16T15:49:12.424834Z","end":"2026-07-16T15:49:12.747988Z","steps":["trace[386896167] 'agreement among raft nodes before linearized reading' (duration: 323.111887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:12.748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.107763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-16T15:49:12.748023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:12.424809Z","time spent":"323.208448ms","remote":"127.0.0.1:55552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T15:49:12.748036Z","caller":"traceutil/trace.go:171","msg":"trace[691509452] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:493; }","duration":"267.168843ms","start":"2026-07-16T15:49:12.480854Z","end":"2026-07-16T15:49:12.748022Z","steps":["trace[691509452] 'agreement among raft nodes before linearized reading' (duration: 267.105962ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:12.747626Z","caller":"traceutil/trace.go:171","msg":"trace[1619365366] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"698.397066ms","start":"2026-07-16T15:49:12.049215Z","end":"2026-07-16T15:49:12.747612Z","steps":["trace[1619365366] 'process raft request' (duration: 698.137931ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:12.748171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:12.049194Z","time spent":"698.935775ms","remote":"127.0.0.1:55598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T15:49:12.747711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:12.036516Z","time spent":"711.169121ms","remote":"127.0.0.1:55598","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T15:49:16.155082Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514875874443243,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T15:49:16.656199Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514875874443243,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T15:49:16.969952Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.325960126s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T15:49:16.970393Z","caller":"traceutil/trace.go:171","msg":"trace[873208817] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"1.326477525s","start":"2026-07-16T15:49:15.643895Z","end":"2026-07-16T15:49:16.970372Z","steps":["trace[873208817] 'process raft request' (duration: 1.326276332s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:16.970525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.643886Z","time spent":"1.326583188s","remote":"127.0.0.1:55652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T15:49:17.164696Z","caller":"traceutil/trace.go:171","msg":"trace[1579984029] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"1.518586264s","start":"2026-07-16T15:49:15.646076Z","end":"2026-07-16T15:49:17.164663Z","steps":["trace[1579984029] 'process raft request' (duration: 1.516445915s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.164882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.646054Z","time spent":"1.518727977s","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T15:49:17.165371Z","caller":"traceutil/trace.go:171","msg":"trace[549414027] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:586; }","duration":"1.510362794s","start":"2026-07-16T15:49:15.654983Z","end":"2026-07-16T15:49:17.165346Z","steps":["trace[549414027] 'read index received' (duration: 1.315337393s)","trace[549414027] 'applied index is now lower than readState.Index' (duration: 195.024571ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:49:17.165433Z","caller":"traceutil/trace.go:171","msg":"trace[971544533] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.522589056s","start":"2026-07-16T15:49:15.64283Z","end":"2026-07-16T15:49:17.165419Z","steps":["trace[971544533] 'process raft request' (duration: 1.521714s)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:17.165483Z","caller":"traceutil/trace.go:171","msg":"trace[1058389668] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.233335318s","start":"2026-07-16T15:49:15.932133Z","end":"2026-07-16T15:49:17.165468Z","steps":["trace[1058389668] 'process raft request' (duration: 1.233033073s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.165514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.642801Z","time spent":"1.522677517s","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T15:49:17.165613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.932107Z","time spent":"1.233414199s","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T15:49:17.165676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.51066795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.165733Z","caller":"traceutil/trace.go:171","msg":"trace[1781044568] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:566; }","duration":"1.510759442s","start":"2026-07-16T15:49:15.654962Z","end":"2026-07-16T15:49:17.165722Z","steps":["trace[1781044568] 'agreement among raft nodes before linearized reading' (duration: 1.51065885s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.165772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.654951Z","time spent":"1.510812873s","remote":"127.0.0.1:46430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-16T15:49:17.169917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207237204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.169959Z","caller":"traceutil/trace.go:171","msg":"trace[111967841] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:568; }","duration":"1.207309675s","start":"2026-07-16T15:49:15.962636Z","end":"2026-07-16T15:49:17.169945Z","steps":["trace[111967841] 'agreement among raft nodes before linearized reading' (duration: 1.207252464s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.16999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.962619Z","time spent":"1.207362536s","remote":"127.0.0.1:39824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-16T15:49:17.170137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147140645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.170165Z","caller":"traceutil/trace.go:171","msg":"trace[1385764279] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:568; }","duration":"1.147176096s","start":"2026-07-16T15:49:16.02298Z","end":"2026-07-16T15:49:17.170156Z","steps":["trace[1385764279] 'agreement among raft nodes before linearized reading' (duration: 1.147136536s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.170185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:16.02297Z","time spent":"1.147210548s","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"warn","ts":"2026-07-16T15:49:17.170216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.233268006s","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":"info","ts":"2026-07-16T15:49:17.17028Z","caller":"traceutil/trace.go:171","msg":"trace[718963217] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:568; }","duration":"1.233350607s","start":"2026-07-16T15:49:15.936916Z","end":"2026-07-16T15:49:17.170266Z","steps":["trace[718963217] 'agreement among raft nodes before linearized reading' (duration: 1.233256095s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.17031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.936907Z","time spent":"1.233392749s","remote":"127.0.0.1:39814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-07-16T15:49:17.17029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271322196s","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-07-16T15:49:17.170382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.147971791s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.170396Z","caller":"traceutil/trace.go:171","msg":"trace[1893326393] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:568; }","duration":"1.271474209s","start":"2026-07-16T15:49:15.898903Z","end":"2026-07-16T15:49:17.170377Z","steps":["trace[1893326393] 'agreement among raft nodes before linearized reading' (duration: 1.271335367s)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:17.170433Z","caller":"traceutil/trace.go:171","msg":"trace[1681492337] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:568; }","duration":"1.148047132s","start":"2026-07-16T15:49:16.022367Z","end":"2026-07-16T15:49:17.170414Z","steps":["trace[1681492337] 'agreement among raft nodes before linearized reading' (duration: 1.148001991s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.170434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.898881Z","time spent":"1.27154434s","remote":"127.0.0.1:39800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 "} {"level":"warn","ts":"2026-07-16T15:49:17.170468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:16.022346Z","time spent":"1.148116544s","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-07-16T15:49:17.170464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.079936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.170518Z","caller":"traceutil/trace.go:171","msg":"trace[2037287322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:568; }","duration":"744.160337ms","start":"2026-07-16T15:49:16.426348Z","end":"2026-07-16T15:49:17.170509Z","steps":["trace[2037287322] 'agreement among raft nodes before linearized reading' (duration: 744.102096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.170541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:16.426313Z","time spent":"744.223198ms","remote":"127.0.0.1:55552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T15:49:17.170674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148315916s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.170693Z","caller":"traceutil/trace.go:171","msg":"trace[1717031355] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:568; }","duration":"1.148348558s","start":"2026-07-16T15:49:16.022339Z","end":"2026-07-16T15:49:17.170688Z","steps":["trace[1717031355] 'agreement among raft nodes before linearized reading' (duration: 1.148318537s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.170709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:16.022331Z","time spent":"1.148374838s","remote":"127.0.0.1:39846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"warn","ts":"2026-07-16T15:49:17.17068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.675623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-16T15:49:17.170804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169208613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.170826Z","caller":"traceutil/trace.go:171","msg":"trace[1981274911] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:568; }","duration":"1.169264775s","start":"2026-07-16T15:49:16.001555Z","end":"2026-07-16T15:49:17.170819Z","steps":["trace[1981274911] 'agreement among raft nodes before linearized reading' (duration: 1.169235714s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.170821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314449009s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T15:49:17.170841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:16.001534Z","time spent":"1.169303005s","remote":"127.0.0.1:39836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"info","ts":"2026-07-16T15:49:17.170855Z","caller":"traceutil/trace.go:171","msg":"trace[736053897] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:568; }","duration":"1.31450222s","start":"2026-07-16T15:49:15.856337Z","end":"2026-07-16T15:49:17.17084Z","steps":["trace[736053897] 'agreement among raft nodes before linearized reading' (duration: 1.314459649s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.170876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.856329Z","time spent":"1.314540391s","remote":"127.0.0.1:46444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 "} {"level":"warn","ts":"2026-07-16T15:49:17.170936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169843225s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.170955Z","caller":"traceutil/trace.go:171","msg":"trace[2100785695] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:568; }","duration":"1.169881445s","start":"2026-07-16T15:49:16.00107Z","end":"2026-07-16T15:49:17.170951Z","steps":["trace[2100785695] 'agreement among raft nodes before linearized reading' (duration: 1.169849284s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.170969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:16.001056Z","time spent":"1.169908207s","remote":"127.0.0.1:39840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-16T15:49:17.170433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.275534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-07-16T15:49:17.171029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.208510747s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.171049Z","caller":"traceutil/trace.go:171","msg":"trace[563092507] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:568; }","duration":"522.922076ms","start":"2026-07-16T15:49:16.648122Z","end":"2026-07-16T15:49:17.171044Z","steps":["trace[563092507] 'agreement among raft nodes before linearized reading' (duration: 522.276055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.171067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:16.648102Z","time spent":"522.959616ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"warn","ts":"2026-07-16T15:49:17.1711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.234031619s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.171157Z","caller":"traceutil/trace.go:171","msg":"trace[1427182579] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:568; }","duration":"1.234099651s","start":"2026-07-16T15:49:15.937024Z","end":"2026-07-16T15:49:17.171124Z","steps":["trace[1427182579] 'agreement among raft nodes before linearized reading' (duration: 1.2340521s)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:17.171163Z","caller":"traceutil/trace.go:171","msg":"trace[1356870987] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:568; }","duration":"1.208573008s","start":"2026-07-16T15:49:15.962482Z","end":"2026-07-16T15:49:17.171055Z","steps":["trace[1356870987] 'agreement among raft nodes before linearized reading' (duration: 1.208510047s)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:17.17115Z","caller":"traceutil/trace.go:171","msg":"trace[1023824493] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"197.16212ms","start":"2026-07-16T15:49:16.973938Z","end":"2026-07-16T15:49:17.1711Z","steps":["trace[1023824493] 'process raft request' (duration: 195.734684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.171284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23344788s","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":"info","ts":"2026-07-16T15:49:17.171319Z","caller":"traceutil/trace.go:171","msg":"trace[854455062] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:568; }","duration":"1.23349394s","start":"2026-07-16T15:49:15.937807Z","end":"2026-07-16T15:49:17.171301Z","steps":["trace[854455062] 'agreement among raft nodes before linearized reading' (duration: 1.233438389s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.171339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.937795Z","time spent":"1.233537531s","remote":"127.0.0.1:39800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 "} {"level":"warn","ts":"2026-07-16T15:49:17.169845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.514783834s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.17143Z","caller":"traceutil/trace.go:171","msg":"trace[1047245927] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:568; }","duration":"1.516410133s","start":"2026-07-16T15:49:15.655008Z","end":"2026-07-16T15:49:17.171418Z","steps":["trace[1047245927] 'agreement among raft nodes before linearized reading' (duration: 1.514780264s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.171464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.654996Z","time spent":"1.516458334s","remote":"127.0.0.1:46430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"warn","ts":"2026-07-16T15:49:17.171598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.342527588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.171624Z","caller":"traceutil/trace.go:171","msg":"trace[1687244345] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:568; }","duration":"1.342557488s","start":"2026-07-16T15:49:15.829058Z","end":"2026-07-16T15:49:17.171616Z","steps":["trace[1687244345] 'agreement among raft nodes before linearized reading' (duration: 1.342513097s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.171647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.82902Z","time spent":"1.34262037s","remote":"127.0.0.1:46444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"info","ts":"2026-07-16T15:49:17.171835Z","caller":"traceutil/trace.go:171","msg":"trace[1016718412] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"197.156829ms","start":"2026-07-16T15:49:16.97466Z","end":"2026-07-16T15:49:17.171817Z","steps":["trace[1016718412] 'process raft request' (duration: 195.065301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.172153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.258693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T15:49:17.172609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343577946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.172637Z","caller":"traceutil/trace.go:171","msg":"trace[871012631] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:568; }","duration":"1.343639888s","start":"2026-07-16T15:49:15.82899Z","end":"2026-07-16T15:49:17.17263Z","steps":["trace[871012631] 'agreement among raft nodes before linearized reading' (duration: 1.343601187s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.172659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.828975Z","time spent":"1.343678268s","remote":"127.0.0.1:46444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-16T15:49:17.17274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.371966992s","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-07-16T15:49:17.172761Z","caller":"traceutil/trace.go:171","msg":"trace[1512571166] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:568; }","duration":"1.372016433s","start":"2026-07-16T15:49:15.800738Z","end":"2026-07-16T15:49:17.172755Z","steps":["trace[1512571166] 'agreement among raft nodes before linearized reading' (duration: 1.371964872s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.172542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.273502415s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.173159Z","caller":"traceutil/trace.go:171","msg":"trace[1377874083] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:568; }","duration":"1.274162447s","start":"2026-07-16T15:49:15.898988Z","end":"2026-07-16T15:49:17.17315Z","steps":["trace[1377874083] 'agreement among raft nodes before linearized reading' (duration: 1.273530445s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.173208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.898965Z","time spent":"1.274213148s","remote":"127.0.0.1:39800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-16T15:49:17.172778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.800724Z","time spent":"1.372049653s","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"info","ts":"2026-07-16T15:49:17.17262Z","caller":"traceutil/trace.go:171","msg":"trace[1244747948] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:568; }","duration":"349.750801ms","start":"2026-07-16T15:49:16.822852Z","end":"2026-07-16T15:49:17.172603Z","steps":["trace[1244747948] 'agreement among raft nodes before linearized reading' (duration: 349.208792ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.173417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.373131383s","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":"info","ts":"2026-07-16T15:49:17.173445Z","caller":"traceutil/trace.go:171","msg":"trace[1910544068] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:568; }","duration":"1.373188614s","start":"2026-07-16T15:49:15.800245Z","end":"2026-07-16T15:49:17.173433Z","steps":["trace[1910544068] 'agreement among raft nodes before linearized reading' (duration: 1.373147383s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.173482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.800222Z","time spent":"1.373250315s","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"warn","ts":"2026-07-16T15:49:17.173545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:16.822829Z","time spent":"350.596538ms","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T15:49:17.173587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.373335256s","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":"info","ts":"2026-07-16T15:49:17.173609Z","caller":"traceutil/trace.go:171","msg":"trace[1065761857] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:568; }","duration":"1.373395868s","start":"2026-07-16T15:49:15.800207Z","end":"2026-07-16T15:49:17.173603Z","steps":["trace[1065761857] 'agreement among raft nodes before linearized reading' (duration: 1.373361267s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.173627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.800185Z","time spent":"1.373436938s","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-07-16T15:49:17.173713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391545567s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.173736Z","caller":"traceutil/trace.go:171","msg":"trace[916845967] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:568; }","duration":"1.391583798s","start":"2026-07-16T15:49:15.782146Z","end":"2026-07-16T15:49:17.17373Z","steps":["trace[916845967] 'agreement among raft nodes before linearized reading' (duration: 1.391548907s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.173754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.782139Z","time spent":"1.391609258s","remote":"127.0.0.1:46430","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 "} {"level":"info","ts":"2026-07-16T15:49:17.170891Z","caller":"traceutil/trace.go:171","msg":"trace[1534833258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:568; }","duration":"358.839915ms","start":"2026-07-16T15:49:16.811957Z","end":"2026-07-16T15:49:17.170797Z","steps":["trace[1534833258] 'agreement among raft nodes before linearized reading' (duration: 358.426268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.173819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:16.811932Z","time spent":"361.87684ms","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T15:49:17.173866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391925924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.173887Z","caller":"traceutil/trace.go:171","msg":"trace[304291392] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:568; }","duration":"1.391975954s","start":"2026-07-16T15:49:15.781906Z","end":"2026-07-16T15:49:17.173882Z","steps":["trace[304291392] 'agreement among raft nodes before linearized reading' (duration: 1.391945264s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.173908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.781889Z","time spent":"1.392014035s","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-07-16T15:49:17.172277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189184216s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.174324Z","caller":"traceutil/trace.go:171","msg":"trace[341596515] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:568; }","duration":"1.191253773s","start":"2026-07-16T15:49:15.983045Z","end":"2026-07-16T15:49:17.174299Z","steps":["trace[341596515] 'agreement among raft nodes before linearized reading' (duration: 1.189198456s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.173839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.391744291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T15:49:17.170327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.169212864s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T15:49:17.17234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.209115698s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T15:49:17.17123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.936999Z","time spent":"1.234186922s","remote":"127.0.0.1:39814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-16T15:49:17.174818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.318751546s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T15:49:17.172311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189260907s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.176588Z","caller":"traceutil/trace.go:171","msg":"trace[515796025] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:568; }","duration":"1.394545811s","start":"2026-07-16T15:49:15.782034Z","end":"2026-07-16T15:49:17.17658Z","steps":["trace[515796025] 'agreement among raft nodes before linearized reading' (duration: 1.391785221s)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:17.176646Z","caller":"traceutil/trace.go:171","msg":"trace[1301692314] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:568; }","duration":"1.175587649s","start":"2026-07-16T15:49:16.001054Z","end":"2026-07-16T15:49:17.176642Z","steps":["trace[1301692314] 'agreement among raft nodes before linearized reading' (duration: 1.169149574s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.176667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:16.00104Z","time spent":"1.175622759s","remote":"127.0.0.1:39840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-07-16T15:49:17.176709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.782003Z","time spent":"1.394686533s","remote":"127.0.0.1:46436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 "} {"level":"info","ts":"2026-07-16T15:49:17.176793Z","caller":"traceutil/trace.go:171","msg":"trace[1970015669] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:568; }","duration":"1.193794049s","start":"2026-07-16T15:49:15.982993Z","end":"2026-07-16T15:49:17.176787Z","steps":["trace[1970015669] 'agreement among raft nodes before linearized reading' (duration: 1.189302348s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.17682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.982979Z","time spent":"1.193831819s","remote":"127.0.0.1:39836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"info","ts":"2026-07-16T15:49:17.176707Z","caller":"traceutil/trace.go:171","msg":"trace[82866502] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:568; }","duration":"1.32067311s","start":"2026-07-16T15:49:15.856012Z","end":"2026-07-16T15:49:17.176685Z","steps":["trace[82866502] 'agreement among raft nodes before linearized reading' (duration: 1.318758766s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.176932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.855978Z","time spent":"1.320947015s","remote":"127.0.0.1:46448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-16T15:49:17.176613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.983022Z","time spent":"1.193582434s","remote":"127.0.0.1:39836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"info","ts":"2026-07-16T15:49:17.176632Z","caller":"traceutil/trace.go:171","msg":"trace[881497429] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:568; }","duration":"1.213417845s","start":"2026-07-16T15:49:15.963209Z","end":"2026-07-16T15:49:17.176626Z","steps":["trace[881497429] 'agreement among raft nodes before linearized reading' (duration: 1.209113428s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.177016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.963203Z","time spent":"1.213806371s","remote":"127.0.0.1:39824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-07-16T15:49:17.171215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.962466Z","time spent":"1.20873842s","remote":"127.0.0.1:39824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-07-16T15:49:17.178611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.314296116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:17.180116Z","caller":"traceutil/trace.go:171","msg":"trace[1311842089] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:568; }","duration":"1.324225125s","start":"2026-07-16T15:49:15.855878Z","end":"2026-07-16T15:49:17.180103Z","steps":["trace[1311842089] 'agreement among raft nodes before linearized reading' (duration: 1.314262625s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:17.180166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:15.855769Z","time spent":"1.324381817s","remote":"127.0.0.1:46448","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 "} {"level":"info","ts":"2026-07-16T15:49:20.349846Z","caller":"traceutil/trace.go:171","msg":"trace[1391925163] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"493.686442ms","start":"2026-07-16T15:49:19.856134Z","end":"2026-07-16T15:49:20.34982Z","steps":["trace[1391925163] 'process raft request' (duration: 493.202303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:20.350044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:19.856114Z","time spent":"493.852956ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T15:49:21.118775Z","caller":"traceutil/trace.go:171","msg":"trace[83061161] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"218.989123ms","start":"2026-07-16T15:49:20.89976Z","end":"2026-07-16T15:49:21.118749Z","steps":["trace[83061161] 'process raft request' (duration: 218.440513ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:21.827226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.122691ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-16T15:49:21.82742Z","caller":"traceutil/trace.go:171","msg":"trace[924789799] linearizableReadLoop","detail":"{readStateIndex:616; appliedIndex:614; }","duration":"650.019964ms","start":"2026-07-16T15:49:21.177385Z","end":"2026-07-16T15:49:21.827405Z","steps":["trace[924789799] 'read index received' (duration: 50.829411ms)","trace[924789799] 'applied index is now lower than readState.Index' (duration: 599.189643ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:49:21.827512Z","caller":"traceutil/trace.go:171","msg":"trace[1579748799] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"672.426397ms","start":"2026-07-16T15:49:21.15507Z","end":"2026-07-16T15:49:21.827497Z","steps":["trace[1579748799] 'process raft request' (duration: 72.96461ms)","trace[1579748799] 'compare' (duration: 598.918897ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T15:49:21.827583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:21.155052Z","time spent":"672.484078ms","remote":"127.0.0.1:55632","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T15:49:21.827658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.285869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-16T15:49:21.827743Z","caller":"traceutil/trace.go:171","msg":"trace[1258564484] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:591; }","duration":"650.410531ms","start":"2026-07-16T15:49:21.177314Z","end":"2026-07-16T15:49:21.827725Z","steps":["trace[1258564484] 'agreement among raft nodes before linearized reading' (duration: 650.227928ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:21.827784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:21.177292Z","time spent":"650.483313ms","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T15:49:21.827815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"589.677585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-07-16T15:49:21.827846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.997753ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-16T15:49:21.828065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.520692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-16T15:49:21.828085Z","caller":"traceutil/trace.go:171","msg":"trace[1027740677] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:591; }","duration":"589.971201ms","start":"2026-07-16T15:49:21.238093Z","end":"2026-07-16T15:49:21.828064Z","steps":["trace[1027740677] 'agreement among raft nodes before linearized reading' (duration: 589.626015ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:21.82811Z","caller":"traceutil/trace.go:171","msg":"trace[266853937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:591; }","duration":"634.293338ms","start":"2026-07-16T15:49:21.193794Z","end":"2026-07-16T15:49:21.828087Z","steps":["trace[266853937] 'agreement among raft nodes before linearized reading' (duration: 633.933122ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:21.828116Z","caller":"traceutil/trace.go:171","msg":"trace[1390317872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:591; }","duration":"634.594773ms","start":"2026-07-16T15:49:21.193502Z","end":"2026-07-16T15:49:21.828097Z","steps":["trace[1390317872] 'agreement among raft nodes before linearized reading' (duration: 634.352239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:21.828137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:21.238082Z","time spent":"590.043232ms","remote":"127.0.0.1:55652","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-07-16T15:49:21.828157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:21.193783Z","time spent":"634.366189ms","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T15:49:21.828161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:21.19348Z","time spent":"634.673484ms","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-16T15:49:21.828212Z","caller":"traceutil/trace.go:171","msg":"trace[928506342] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"654.470672ms","start":"2026-07-16T15:49:21.173729Z","end":"2026-07-16T15:49:21.8282Z","steps":["trace[928506342] 'process raft request' (duration: 653.623888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:21.827804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.127993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-16T15:49:21.828422Z","caller":"traceutil/trace.go:171","msg":"trace[1249844690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:591; }","duration":"647.766604ms","start":"2026-07-16T15:49:21.180625Z","end":"2026-07-16T15:49:21.828392Z","steps":["trace[1249844690] 'agreement among raft nodes before linearized reading' (duration: 647.064262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:21.828295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:21.173705Z","time spent":"654.543593ms","remote":"127.0.0.1:56062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T15:49:21.828609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:21.180616Z","time spent":"647.914638ms","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T15:49:21.828302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.452895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:21.828683Z","caller":"traceutil/trace.go:171","msg":"trace[1156721881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:591; }","duration":"404.857333ms","start":"2026-07-16T15:49:21.423808Z","end":"2026-07-16T15:49:21.828665Z","steps":["trace[1156721881] 'agreement among raft nodes before linearized reading' (duration: 404.449715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:21.828738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:21.423796Z","time spent":"404.925454ms","remote":"127.0.0.1:55552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T15:49:22.925912Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514875874443303,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T15:49:23.045039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.327617ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-16T15:49:23.130305Z","caller":"traceutil/trace.go:171","msg":"trace[1711754815] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"705.428518ms","start":"2026-07-16T15:49:22.424806Z","end":"2026-07-16T15:49:23.130234Z","steps":["trace[1711754815] 'read index received' (duration: 704.650125ms)","trace[1711754815] 'applied index is now lower than readState.Index' (duration: 776.943µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:49:23.130493Z","caller":"traceutil/trace.go:171","msg":"trace[917089974] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"887.32284ms","start":"2026-07-16T15:49:22.243055Z","end":"2026-07-16T15:49:23.130378Z","steps":["trace[917089974] 'process raft request' (duration: 886.654019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:23.130692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:22.243026Z","time spent":"887.569236ms","remote":"127.0.0.1:55640","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T15:49:23.130733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"705.857576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T15:49:23.130926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.113536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9n7pp\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-16T15:49:23.131006Z","caller":"traceutil/trace.go:171","msg":"trace[10721330] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9n7pp; range_end:; response_count:1; response_revision:597; }","duration":"386.245277ms","start":"2026-07-16T15:49:22.74474Z","end":"2026-07-16T15:49:23.130986Z","steps":["trace[10721330] 'agreement among raft nodes before linearized reading' (duration: 385.960243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:23.131062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:22.744709Z","time spent":"386.339159ms","remote":"127.0.0.1:55712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-9n7pp\" "} {"level":"info","ts":"2026-07-16T15:49:23.130978Z","caller":"traceutil/trace.go:171","msg":"trace[2029864217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"706.225831ms","start":"2026-07-16T15:49:22.424704Z","end":"2026-07-16T15:49:23.13093Z","steps":["trace[2029864217] 'agreement among raft nodes before linearized reading' (duration: 705.778233ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:23.131264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:22.424678Z","time spent":"706.571408ms","remote":"127.0.0.1:55552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T15:49:24.345517Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514875874443308,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T15:49:24.846656Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514875874443308,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T15:49:25.347704Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514875874443308,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T15:49:25.711813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.299164ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:25.712381Z","caller":"traceutil/trace.go:171","msg":"trace[1153034092] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:597; }","duration":"778.875154ms","start":"2026-07-16T15:49:24.933488Z","end":"2026-07-16T15:49:25.712364Z","steps":["trace[1153034092] 'range keys from in-memory index tree' (duration: 778.280604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:25.71992Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.567596703s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T15:49:25.72039Z","caller":"traceutil/trace.go:171","msg":"trace[937763279] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"2.568161362s","start":"2026-07-16T15:49:23.152203Z","end":"2026-07-16T15:49:25.720365Z","steps":["trace[937763279] 'process raft request' (duration: 2.567929078s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:25.720677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:23.152178Z","time spent":"2.568359255s","remote":"127.0.0.1:55712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T15:49:25.808028Z","caller":"traceutil/trace.go:171","msg":"trace[1846666416] linearizableReadLoop","detail":"{readStateIndex:625; appliedIndex:623; }","duration":"1.963046584s","start":"2026-07-16T15:49:23.844957Z","end":"2026-07-16T15:49:25.808004Z","steps":["trace[1846666416] 'read index received' (duration: 1.875160854s)","trace[1846666416] 'applied index is now lower than readState.Index' (duration: 87.88465ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:49:25.80808Z","caller":"traceutil/trace.go:171","msg":"trace[2143644141] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"2.619291974s","start":"2026-07-16T15:49:23.188758Z","end":"2026-07-16T15:49:25.80805Z","steps":["trace[2143644141] 'process raft request' (duration: 2.61909079s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:25.80833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:23.188732Z","time spent":"2.619527898s","remote":"127.0.0.1:55784","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T15:49:25.808958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.38451365s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:25.809043Z","caller":"traceutil/trace.go:171","msg":"trace[1300072574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"1.384639464s","start":"2026-07-16T15:49:24.42438Z","end":"2026-07-16T15:49:25.809019Z","steps":["trace[1300072574] 'agreement among raft nodes before linearized reading' (duration: 1.383766018s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:25.809088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:24.42436Z","time spent":"1.384720495s","remote":"127.0.0.1:55552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-16T15:49:25.809613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.941710314s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-16T15:49:25.809667Z","caller":"traceutil/trace.go:171","msg":"trace[681836743] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"1.941805365s","start":"2026-07-16T15:49:23.86785Z","end":"2026-07-16T15:49:25.809655Z","steps":["trace[681836743] 'agreement among raft nodes before linearized reading' (duration: 1.940280929s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:25.809697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:23.867828Z","time spent":"1.941862567s","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-16T15:49:25.809596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.93923541s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-16T15:49:25.809795Z","caller":"traceutil/trace.go:171","msg":"trace[764219574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"1.939463534s","start":"2026-07-16T15:49:23.870306Z","end":"2026-07-16T15:49:25.809769Z","steps":["trace[764219574] 'agreement among raft nodes before linearized reading' (duration: 1.937892057s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:25.809911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:23.870299Z","time spent":"1.939540185s","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-16T15:49:25.810102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.940295919s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-16T15:49:25.810215Z","caller":"traceutil/trace.go:171","msg":"trace[2000256657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"1.940457732s","start":"2026-07-16T15:49:23.869744Z","end":"2026-07-16T15:49:25.810202Z","steps":["trace[2000256657] 'agreement among raft nodes before linearized reading' (duration: 1.938941806s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:25.810346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:23.869722Z","time spent":"1.940611884s","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-16T15:49:25.810569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.965609088s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-16T15:49:25.81146Z","caller":"traceutil/trace.go:171","msg":"trace[1325803915] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"1.966501173s","start":"2026-07-16T15:49:23.844911Z","end":"2026-07-16T15:49:25.811413Z","steps":["trace[1325803915] 'agreement among raft nodes before linearized reading' (duration: 1.963200467s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:25.811708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:23.844891Z","time spent":"1.966799478s","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-16T15:49:25.948102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.77849ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T15:49:25.948465Z","caller":"traceutil/trace.go:171","msg":"trace[229927308] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"134.53956ms","start":"2026-07-16T15:49:25.813897Z","end":"2026-07-16T15:49:25.948436Z","steps":["trace[229927308] 'process raft request' (duration: 15.338973ms)","trace[229927308] 'compare' (duration: 118.614418ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:49:25.948638Z","caller":"traceutil/trace.go:171","msg":"trace[1730623920] linearizableReadLoop","detail":"{readStateIndex:626; appliedIndex:625; }","duration":"131.692132ms","start":"2026-07-16T15:49:25.816854Z","end":"2026-07-16T15:49:25.948546Z","steps":["trace[1730623920] 'read index received' (duration: 12.344843ms)","trace[1730623920] 'applied index is now lower than readState.Index' (duration: 119.345429ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T15:49:25.948689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.827764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-16T15:49:25.948742Z","caller":"traceutil/trace.go:171","msg":"trace[606740522] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:600; }","duration":"131.908745ms","start":"2026-07-16T15:49:25.816816Z","end":"2026-07-16T15:49:25.948724Z","steps":["trace[606740522] 'agreement among raft nodes before linearized reading' (duration: 131.822894ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:25.949235Z","caller":"traceutil/trace.go:171","msg":"trace[329128563] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"131.884216ms","start":"2026-07-16T15:49:25.817339Z","end":"2026-07-16T15:49:25.949223Z","steps":["trace[329128563] 'process raft request' (duration: 131.842266ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:25.949405Z","caller":"traceutil/trace.go:171","msg":"trace[1484730422] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"132.105179ms","start":"2026-07-16T15:49:25.817289Z","end":"2026-07-16T15:49:25.949394Z","steps":["trace[1484730422] 'process raft request' (duration: 131.843595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:25.949762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.42725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:25.94981Z","caller":"traceutil/trace.go:171","msg":"trace[540607854] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"130.490721ms","start":"2026-07-16T15:49:25.819309Z","end":"2026-07-16T15:49:25.949799Z","steps":["trace[540607854] 'agreement among raft nodes before linearized reading' (duration: 130.42158ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:25.949232Z","caller":"traceutil/trace.go:171","msg":"trace[737640002] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"132.021708ms","start":"2026-07-16T15:49:25.817195Z","end":"2026-07-16T15:49:25.949216Z","steps":["trace[737640002] 'process raft request' (duration: 131.382117ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:26.964841Z","caller":"traceutil/trace.go:171","msg":"trace[1466977679] linearizableReadLoop","detail":"{readStateIndex:635; appliedIndex:634; }","duration":"203.996066ms","start":"2026-07-16T15:49:26.760812Z","end":"2026-07-16T15:49:26.964808Z","steps":["trace[1466977679] 'read index received' (duration: 203.881384ms)","trace[1466977679] 'applied index is now lower than readState.Index' (duration: 112.762µs)"],"step_count":2} {"level":"warn","ts":"2026-07-16T15:49:26.964884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:26.649666Z","time spent":"315.204685ms","remote":"127.0.0.1:55576","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-16T15:49:26.965429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.595996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9n7pp\" ","response":"range_response_count:1 size:17145"} {"level":"info","ts":"2026-07-16T15:49:26.965479Z","caller":"traceutil/trace.go:171","msg":"trace[2019818594] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9n7pp; range_end:; response_count:1; response_revision:608; }","duration":"204.725788ms","start":"2026-07-16T15:49:26.760742Z","end":"2026-07-16T15:49:26.965468Z","steps":["trace[2019818594] 'agreement among raft nodes before linearized reading' (duration: 204.192679ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:26.96776Z","caller":"traceutil/trace.go:171","msg":"trace[197492785] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"203.337684ms","start":"2026-07-16T15:49:26.764406Z","end":"2026-07-16T15:49:26.967744Z","steps":["trace[197492785] 'process raft request' (duration: 203.221412ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:28.068102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.777406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-16T15:49:28.068121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.73747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9n7pp\" ","response":"range_response_count:1 size:17243"} {"level":"info","ts":"2026-07-16T15:49:28.068164Z","caller":"traceutil/trace.go:171","msg":"trace[997387442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:614; }","duration":"114.890287ms","start":"2026-07-16T15:49:27.95326Z","end":"2026-07-16T15:49:28.06815Z","steps":["trace[997387442] 'range keys from in-memory index tree' (duration: 114.579483ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:28.068199Z","caller":"traceutil/trace.go:171","msg":"trace[1812398500] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9n7pp; range_end:; response_count:1; response_revision:614; }","duration":"299.886822ms","start":"2026-07-16T15:49:27.768294Z","end":"2026-07-16T15:49:28.068181Z","steps":["trace[1812398500] 'range keys from in-memory index tree' (duration: 299.518896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:28.068283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.979199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T15:49:28.068335Z","caller":"traceutil/trace.go:171","msg":"trace[871388284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:614; }","duration":"115.072ms","start":"2026-07-16T15:49:27.953248Z","end":"2026-07-16T15:49:28.06832Z","steps":["trace[871388284] 'range keys from in-memory index tree' (duration: 114.784526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:28.068408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.42072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-16T15:49:28.068428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.479311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-16T15:49:28.06844Z","caller":"traceutil/trace.go:171","msg":"trace[539248158] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:614; }","duration":"114.477051ms","start":"2026-07-16T15:49:27.953955Z","end":"2026-07-16T15:49:28.068432Z","steps":["trace[539248158] 'range keys from in-memory index tree' (duration: 114.320797ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:28.068447Z","caller":"traceutil/trace.go:171","msg":"trace[677422451] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"114.520821ms","start":"2026-07-16T15:49:27.95392Z","end":"2026-07-16T15:49:28.068441Z","steps":["trace[677422451] 'range keys from in-memory index tree' (duration: 114.410419ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:28.22781Z","caller":"traceutil/trace.go:171","msg":"trace[1639338265] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"155.472749ms","start":"2026-07-16T15:49:28.072311Z","end":"2026-07-16T15:49:28.227783Z","steps":["trace[1639338265] 'process raft request' (duration: 155.399498ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:28.228249Z","caller":"traceutil/trace.go:171","msg":"trace[456059473] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"155.906147ms","start":"2026-07-16T15:49:28.072304Z","end":"2026-07-16T15:49:28.22821Z","steps":["trace[456059473] 'process raft request' (duration: 155.233996ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:28.329299Z","caller":"traceutil/trace.go:171","msg":"trace[1341060354] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"256.660641ms","start":"2026-07-16T15:49:28.072615Z","end":"2026-07-16T15:49:28.329275Z","steps":["trace[1341060354] 'process raft request' (duration: 256.285814ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:28.329797Z","caller":"traceutil/trace.go:171","msg":"trace[1718914976] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"256.822204ms","start":"2026-07-16T15:49:28.072955Z","end":"2026-07-16T15:49:28.329777Z","steps":["trace[1718914976] 'process raft request' (duration: 256.073581ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:28.329781Z","caller":"traceutil/trace.go:171","msg":"trace[695974951] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"251.395612ms","start":"2026-07-16T15:49:28.078346Z","end":"2026-07-16T15:49:28.329742Z","steps":["trace[695974951] 'process raft request' (duration: 251.188219ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:30.471446Z","caller":"traceutil/trace.go:171","msg":"trace[1088373148] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"144.248275ms","start":"2026-07-16T15:49:30.327139Z","end":"2026-07-16T15:49:30.471388Z","steps":["trace[1088373148] 'process raft request' (duration: 143.967451ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:30.471509Z","caller":"traceutil/trace.go:171","msg":"trace[399755444] linearizableReadLoop","detail":"{readStateIndex:679; appliedIndex:678; }","duration":"137.786168ms","start":"2026-07-16T15:49:30.333697Z","end":"2026-07-16T15:49:30.471484Z","steps":["trace[399755444] 'read index received' (duration: 37.210263ms)","trace[399755444] 'applied index is now lower than readState.Index' (duration: 100.574675ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:49:30.471638Z","caller":"traceutil/trace.go:171","msg":"trace[623074514] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"125.174987ms","start":"2026-07-16T15:49:30.346453Z","end":"2026-07-16T15:49:30.471628Z","steps":["trace[623074514] 'process raft request' (duration: 124.80919ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:30.471963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.92199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T15:49:30.472175Z","caller":"traceutil/trace.go:171","msg":"trace[1114616187] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:653; }","duration":"138.158693ms","start":"2026-07-16T15:49:30.333996Z","end":"2026-07-16T15:49:30.472155Z","steps":["trace[1114616187] 'agreement among raft nodes before linearized reading' (duration: 137.760817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:30.471985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.253405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-16T15:49:30.472776Z","caller":"traceutil/trace.go:171","msg":"trace[1769423324] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:653; }","duration":"139.113559ms","start":"2026-07-16T15:49:30.333648Z","end":"2026-07-16T15:49:30.472762Z","steps":["trace[1769423324] 'agreement among raft nodes before linearized reading' (duration: 138.082383ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:31.011972Z","caller":"traceutil/trace.go:171","msg":"trace[1974456320] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"210.557206ms","start":"2026-07-16T15:49:30.801387Z","end":"2026-07-16T15:49:31.011944Z","steps":["trace[1974456320] 'process raft request' (duration: 210.395952ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:31.379864Z","caller":"traceutil/trace.go:171","msg":"trace[276011043] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"116.944078ms","start":"2026-07-16T15:49:31.262899Z","end":"2026-07-16T15:49:31.379843Z","steps":["trace[276011043] 'process raft request' (duration: 116.815266ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:31.506646Z","caller":"traceutil/trace.go:171","msg":"trace[399166948] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"143.871597ms","start":"2026-07-16T15:49:31.36275Z","end":"2026-07-16T15:49:31.506622Z","steps":["trace[399166948] 'process raft request' (duration: 142.612856ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:31.541184Z","caller":"traceutil/trace.go:171","msg":"trace[1816030814] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:693; }","duration":"116.98798ms","start":"2026-07-16T15:49:31.424168Z","end":"2026-07-16T15:49:31.541156Z","steps":["trace[1816030814] 'read index received' (duration: 81.199333ms)","trace[1816030814] 'applied index is now lower than readState.Index' (duration: 35.787387ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:49:31.54123Z","caller":"traceutil/trace.go:171","msg":"trace[1668835030] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"147.153963ms","start":"2026-07-16T15:49:31.394047Z","end":"2026-07-16T15:49:31.541201Z","steps":["trace[1668835030] 'process raft request' (duration: 146.964539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:31.541346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.165183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:31.541402Z","caller":"traceutil/trace.go:171","msg":"trace[1455728461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"117.276615ms","start":"2026-07-16T15:49:31.424111Z","end":"2026-07-16T15:49:31.541388Z","steps":["trace[1455728461] 'agreement among raft nodes before linearized reading' (duration: 117.151193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:31.784702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.228546ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T15:49:31.784812Z","caller":"traceutil/trace.go:171","msg":"trace[1946379723] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"241.481204ms","start":"2026-07-16T15:49:31.543312Z","end":"2026-07-16T15:49:31.784793Z","steps":["trace[1946379723] 'read index received' (duration: 86.081744ms)","trace[1946379723] 'applied index is now lower than readState.Index' (duration: 155.39784ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T15:49:31.784949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.648897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T15:49:31.784984Z","caller":"traceutil/trace.go:171","msg":"trace[1948234797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:669; }","duration":"241.720998ms","start":"2026-07-16T15:49:31.543253Z","end":"2026-07-16T15:49:31.784974Z","steps":["trace[1948234797] 'agreement among raft nodes before linearized reading' (duration: 241.660147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:31.785425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.617023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-16T15:49:31.785498Z","caller":"traceutil/trace.go:171","msg":"trace[1992372807] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:669; }","duration":"233.737805ms","start":"2026-07-16T15:49:31.551744Z","end":"2026-07-16T15:49:31.785482Z","steps":["trace[1992372807] 'agreement among raft nodes before linearized reading' (duration: 233.536581ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:31.785958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.87578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-07-16T15:49:31.786528Z","caller":"traceutil/trace.go:171","msg":"trace[2030185906] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:669; }","duration":"242.4933ms","start":"2026-07-16T15:49:31.544006Z","end":"2026-07-16T15:49:31.786499Z","steps":["trace[2030185906] 'agreement among raft nodes before linearized reading' (duration: 241.085516ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:31.785269Z","caller":"traceutil/trace.go:171","msg":"trace[92710330] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"262.21659ms","start":"2026-07-16T15:49:31.5227Z","end":"2026-07-16T15:49:31.784917Z","steps":["trace[92710330] 'process raft request' (duration: 106.689098ms)","trace[92710330] 'compare' (duration: 155.095864ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:49:31.973022Z","caller":"traceutil/trace.go:171","msg":"trace[352755429] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"119.733755ms","start":"2026-07-16T15:49:31.853269Z","end":"2026-07-16T15:49:31.973002Z","steps":["trace[352755429] 'read index received' (duration: 119.490242ms)","trace[352755429] 'applied index is now lower than readState.Index' (duration: 242.393µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:49:31.973353Z","caller":"traceutil/trace.go:171","msg":"trace[490252906] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"173.885547ms","start":"2026-07-16T15:49:31.799452Z","end":"2026-07-16T15:49:31.973337Z","steps":["trace[490252906] 'process raft request' (duration: 173.368589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:31.973561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.251184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9n7pp\" ","response":"range_response_count:1 size:18063"} {"level":"info","ts":"2026-07-16T15:49:31.973604Z","caller":"traceutil/trace.go:171","msg":"trace[1999902268] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9n7pp; range_end:; response_count:1; response_revision:671; }","duration":"120.363876ms","start":"2026-07-16T15:49:31.853229Z","end":"2026-07-16T15:49:31.973592Z","steps":["trace[1999902268] 'agreement among raft nodes before linearized reading' (duration: 120.155903ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:32.037686Z","caller":"traceutil/trace.go:171","msg":"trace[1702316413] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"183.780789ms","start":"2026-07-16T15:49:31.853874Z","end":"2026-07-16T15:49:32.037655Z","steps":["trace[1702316413] 'process raft request' (duration: 183.612867ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:49:32.388456Z","caller":"traceutil/trace.go:171","msg":"trace[873868986] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"169.598502ms","start":"2026-07-16T15:49:32.218839Z","end":"2026-07-16T15:49:32.388437Z","steps":["trace[873868986] 'process raft request' (duration: 169.45323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:43.925452Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514875874443649,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T15:49:44.426203Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1011514875874443649,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-16T15:49:44.552698Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.634477402s","expected-duration":"1s"} {"level":"info","ts":"2026-07-16T15:49:44.553151Z","caller":"traceutil/trace.go:171","msg":"trace[1387649778] linearizableReadLoop","detail":"{readStateIndex:800; appliedIndex:799; }","duration":"1.128669497s","start":"2026-07-16T15:49:43.424454Z","end":"2026-07-16T15:49:44.553123Z","steps":["trace[1387649778] 'read index received' (duration: 1.128393673s)","trace[1387649778] 'applied index is now lower than readState.Index' (duration: 274.714µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:49:44.553269Z","caller":"traceutil/trace.go:171","msg":"trace[1040062763] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"1.635176633s","start":"2026-07-16T15:49:42.918063Z","end":"2026-07-16T15:49:44.55324Z","steps":["trace[1040062763] 'process raft request' (duration: 1.634891418s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:44.553362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.12891642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T15:49:44.553471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:42.91803Z","time spent":"1.635288924s","remote":"127.0.0.1:55712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-16T15:49:44.553629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.501278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-16T15:49:44.55374Z","caller":"traceutil/trace.go:171","msg":"trace[1058573560] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:768; }","duration":"616.687661ms","start":"2026-07-16T15:49:43.937027Z","end":"2026-07-16T15:49:44.553714Z","steps":["trace[1058573560] 'agreement among raft nodes before linearized reading' (duration: 616.313555ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:44.55379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:43.937003Z","time spent":"616.777682ms","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-16T15:49:44.553402Z","caller":"traceutil/trace.go:171","msg":"trace[1122694915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:768; }","duration":"1.129023152s","start":"2026-07-16T15:49:43.424368Z","end":"2026-07-16T15:49:44.553391Z","steps":["trace[1122694915] 'agreement among raft nodes before linearized reading' (duration: 1.12890473s)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:49:44.55432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:49:43.424347Z","time spent":"1.129959537s","remote":"127.0.0.1:55552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-16T15:49:45.066549Z","caller":"traceutil/trace.go:171","msg":"trace[1417870335] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"138.35712ms","start":"2026-07-16T15:49:44.928159Z","end":"2026-07-16T15:49:45.066516Z","steps":["trace[1417870335] 'process raft request' (duration: 138.198997ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:51:45.47281Z","caller":"traceutil/trace.go:171","msg":"trace[896255834] linearizableReadLoop","detail":"{readStateIndex:1395; appliedIndex:1394; }","duration":"188.341514ms","start":"2026-07-16T15:51:45.284435Z","end":"2026-07-16T15:51:45.472777Z","steps":["trace[896255834] 'read index received' (duration: 188.082911ms)","trace[896255834] 'applied index is now lower than readState.Index' (duration: 257.173µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:51:45.472874Z","caller":"traceutil/trace.go:171","msg":"trace[312224603] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"191.344319ms","start":"2026-07-16T15:51:45.281493Z","end":"2026-07-16T15:51:45.472837Z","steps":["trace[312224603] 'process raft request' (duration: 191.078656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:51:45.473268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.069704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-16T15:51:45.473265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.699858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-16T15:51:45.473321Z","caller":"traceutil/trace.go:171","msg":"trace[965597645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1336; }","duration":"156.183625ms","start":"2026-07-16T15:51:45.317123Z","end":"2026-07-16T15:51:45.473306Z","steps":["trace[965597645] 'agreement among raft nodes before linearized reading' (duration: 155.872741ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:51:45.473371Z","caller":"traceutil/trace.go:171","msg":"trace[1941946012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1336; }","duration":"150.87111ms","start":"2026-07-16T15:51:45.322475Z","end":"2026-07-16T15:51:45.473346Z","steps":["trace[1941946012] 'agreement among raft nodes before linearized reading' (duration: 150.590857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:51:45.473272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.333264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-16T15:51:45.473482Z","caller":"traceutil/trace.go:171","msg":"trace[1967182124] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1336; }","duration":"150.597757ms","start":"2026-07-16T15:51:45.322829Z","end":"2026-07-16T15:51:45.473427Z","steps":["trace[1967182124] 'agreement among raft nodes before linearized reading' (duration: 150.188193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:51:45.47351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.074743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-16T15:51:45.473571Z","caller":"traceutil/trace.go:171","msg":"trace[760399264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1336; }","duration":"189.149824ms","start":"2026-07-16T15:51:45.284403Z","end":"2026-07-16T15:51:45.473553Z","steps":["trace[760399264] 'agreement among raft nodes before linearized reading' (duration: 188.965112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:51:45.657257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.524853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T15:51:45.657484Z","caller":"traceutil/trace.go:171","msg":"trace[683703131] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"178.426693ms","start":"2026-07-16T15:51:45.47904Z","end":"2026-07-16T15:51:45.657466Z","steps":["trace[683703131] 'process raft request' (duration: 178.324592ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T15:51:45.657695Z","caller":"traceutil/trace.go:171","msg":"trace[1531577577] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"178.669726ms","start":"2026-07-16T15:51:45.479016Z","end":"2026-07-16T15:51:45.657686Z","steps":["trace[1531577577] 'process raft request' (duration: 33.617187ms)","trace[1531577577] 'compare' (duration: 144.27043ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T15:51:46.084393Z","caller":"traceutil/trace.go:171","msg":"trace[434547391] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"604.996365ms","start":"2026-07-16T15:51:45.479372Z","end":"2026-07-16T15:51:46.084368Z","steps":["trace[434547391] 'process raft request' (duration: 604.924104ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:51:46.084565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:51:45.479358Z","time spent":"605.124266ms","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T15:51:46.084703Z","caller":"traceutil/trace.go:171","msg":"trace[501939087] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"605.40269ms","start":"2026-07-16T15:51:45.479229Z","end":"2026-07-16T15:51:46.084631Z","steps":["trace[501939087] 'process raft request' (duration: 587.704886ms)","trace[501939087] 'compare' (duration: 17.098577ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T15:51:46.084871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:51:45.479156Z","time spent":"605.626002ms","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T15:51:46.108731Z","caller":"traceutil/trace.go:171","msg":"trace[1037313411] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"542.111903ms","start":"2026-07-16T15:51:45.566591Z","end":"2026-07-16T15:51:46.108703Z","steps":["trace[1037313411] 'process raft request' (duration: 541.94082ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T15:51:46.109029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T15:51:45.566559Z","time spent":"542.255424ms","remote":"127.0.0.1:55814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}