{"level":"warn","ts":"2026-07-17T05:03:56.271777Z","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-17T05:03:56.271881Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-17T05:03:56.271967Z","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-17T05:03:56.271984Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-07-17T05:03:56.272009Z","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-17T05:03:56.272875Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-07-17T05:03:56.272974Z","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.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-17T05:03:56.277324Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.060678ms"} {"level":"info","ts":"2026-07-17T05:03:56.285464Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-07-17T05:03:56.285607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-07-17T05:03:56.285647Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-07-17T05:03:56.285669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-17T05:03:56.285683Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-07-17T05:03:56.285746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-07-17T05:03:56.293634Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-17T05:03:56.299356Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-17T05:03:56.300693Z","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-17T05:03:56.302874Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-17T05:03:56.302988Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-17T05:03:56.303349Z","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-17T05:03:56.305162Z","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-17T05:03:56.305513Z","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-17T05:03:56.305759Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-17T05:03:56.305791Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-17T05:03:56.305889Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-07-17T05:03:56.305906Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-07-17T05:03:56.30707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-07-17T05:03:56.307404Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-07-17T05:03:56.305193Z","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-17T05:03:56.486793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-07-17T05:03:56.486848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-17T05:03:56.486875Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-07-17T05:03:56.486887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-07-17T05:03:56.486892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-07-17T05:03:56.486901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-07-17T05:03:56.486908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-07-17T05:03:56.488Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T05:03:56.490157Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-07-17T05:03:56.490173Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T05:03:56.490374Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-17T05:03:56.491102Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-17T05:03:56.491131Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-17T05:03:56.492134Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T05:03:56.492207Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T05:03:56.492225Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-17T05:03:56.493868Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-07-17T05:03:56.494274Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-17T05:04:06.687957Z","caller":"traceutil/trace.go:171","msg":"trace[678706597] transaction","detail":"{read_only:false; response_revision:351; number_of_response:1; }","duration":"127.892887ms","start":"2026-07-17T05:04:06.560043Z","end":"2026-07-17T05:04:06.687936Z","steps":["trace[678706597] 'process raft request' (duration: 91.026839ms)","trace[678706597] 'compare' (duration: 36.715253ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:04:12.619447Z","caller":"traceutil/trace.go:171","msg":"trace[66576592] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"292.854596ms","start":"2026-07-17T05:04:12.326565Z","end":"2026-07-17T05:04:12.619419Z","steps":["trace[66576592] 'process raft request' (duration: 292.670111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:13.560667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.865075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:04:13.560811Z","caller":"traceutil/trace.go:171","msg":"trace[768690779] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:410; }","duration":"468.059441ms","start":"2026-07-17T05:04:13.092731Z","end":"2026-07-17T05:04:13.56079Z","steps":["trace[768690779] 'range keys from in-memory index tree' (duration: 467.793122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:13.560854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:13.092692Z","time spent":"468.151353ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T05:04:13.560846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.819083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T05:04:13.560942Z","caller":"traceutil/trace.go:171","msg":"trace[1451073926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:410; }","duration":"169.952548ms","start":"2026-07-17T05:04:13.390966Z","end":"2026-07-17T05:04:13.560919Z","steps":["trace[1451073926] 'range keys from in-memory index tree' (duration: 169.71295ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:14.073819Z","caller":"traceutil/trace.go:171","msg":"trace[1716588309] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"508.976545ms","start":"2026-07-17T05:04:13.564808Z","end":"2026-07-17T05:04:14.073784Z","steps":["trace[1716588309] 'process raft request' (duration: 508.786759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:14.074656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:13.564773Z","time spent":"509.168391ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:04:14.084326Z","caller":"traceutil/trace.go:171","msg":"trace[350953851] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"518.429512ms","start":"2026-07-17T05:04:13.565871Z","end":"2026-07-17T05:04:14.0843Z","steps":["trace[350953851] 'process raft request' (duration: 518.226996ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:14.084315Z","caller":"traceutil/trace.go:171","msg":"trace[1134892178] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:421; }","duration":"437.545732ms","start":"2026-07-17T05:04:13.646712Z","end":"2026-07-17T05:04:14.084258Z","steps":["trace[1134892178] 'read index received' (duration: 426.889478ms)","trace[1134892178] 'applied index is now lower than readState.Index' (duration: 10.655304ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:04:14.084435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:13.565852Z","time spent":"518.525796ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T05:04:14.084493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.758389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T05:04:14.084532Z","caller":"traceutil/trace.go:171","msg":"trace[73235471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:412; }","duration":"437.821871ms","start":"2026-07-17T05:04:13.646696Z","end":"2026-07-17T05:04:14.084518Z","steps":["trace[73235471] 'agreement among raft nodes before linearized reading' (duration: 437.730098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:14.084529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.664322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T05:04:14.084582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:13.64664Z","time spent":"437.929814ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T05:04:14.084607Z","caller":"traceutil/trace.go:171","msg":"trace[683062172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"292.771955ms","start":"2026-07-17T05:04:13.791815Z","end":"2026-07-17T05:04:14.084587Z","steps":["trace[683062172] 'agreement among raft nodes before linearized reading' (duration: 292.662042ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:15.673198Z","caller":"traceutil/trace.go:171","msg":"trace[723716202] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"189.793041ms","start":"2026-07-17T05:04:15.483376Z","end":"2026-07-17T05:04:15.673169Z","steps":["trace[723716202] 'process raft request' (duration: 189.648887ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:36.241997Z","caller":"traceutil/trace.go:171","msg":"trace[147230849] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"484.037298ms","start":"2026-07-17T05:04:35.757926Z","end":"2026-07-17T05:04:36.241963Z","steps":["trace[147230849] 'process raft request' (duration: 483.79908ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:36.242254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:35.757904Z","time spent":"484.198793ms","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T05:04:36.367574Z","caller":"traceutil/trace.go:171","msg":"trace[138963893] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"607.297981ms","start":"2026-07-17T05:04:35.760208Z","end":"2026-07-17T05:04:36.367506Z","steps":["trace[138963893] 'process raft request' (duration: 607.074604ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:36.367684Z","caller":"traceutil/trace.go:171","msg":"trace[1789927110] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"599.494364ms","start":"2026-07-17T05:04:35.768156Z","end":"2026-07-17T05:04:36.36765Z","steps":["trace[1789927110] 'process raft request' (duration: 599.303508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:36.367841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:35.768148Z","time spent":"599.589047ms","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T05:04:36.367851Z","caller":"traceutil/trace.go:171","msg":"trace[672606574] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:544; }","duration":"187.237424ms","start":"2026-07-17T05:04:36.180588Z","end":"2026-07-17T05:04:36.367826Z","steps":["trace[672606574] 'read index received' (duration: 61.124821ms)","trace[672606574] 'applied index is now lower than readState.Index' (duration: 126.111953ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:04:36.367912Z","caller":"traceutil/trace.go:171","msg":"trace[1644410664] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"588.420194ms","start":"2026-07-17T05:04:35.779479Z","end":"2026-07-17T05:04:36.367899Z","steps":["trace[1644410664] 'process raft request' (duration: 588.130265ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:36.367951Z","caller":"traceutil/trace.go:171","msg":"trace[361578350] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"571.724137ms","start":"2026-07-17T05:04:35.796218Z","end":"2026-07-17T05:04:36.367942Z","steps":["trace[361578350] 'process raft request' (duration: 571.432667ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:36.367969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:35.779454Z","time spent":"588.489817ms","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T05:04:36.368052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:35.796197Z","time spent":"571.785329ms","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T05:04:36.368097Z","caller":"traceutil/trace.go:171","msg":"trace[2110145441] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"417.748524ms","start":"2026-07-17T05:04:35.950307Z","end":"2026-07-17T05:04:36.368055Z","steps":["trace[2110145441] 'process raft request' (duration: 417.475606ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:36.368142Z","caller":"traceutil/trace.go:171","msg":"trace[2099327053] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"552.299774ms","start":"2026-07-17T05:04:35.815794Z","end":"2026-07-17T05:04:36.368094Z","steps":["trace[2099327053] 'process raft request' (duration: 551.896872ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:36.368179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:35.950273Z","time spent":"417.869348ms","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T05:04:36.368233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:35.815771Z","time spent":"552.424978ms","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T05:04:36.368254Z","caller":"traceutil/trace.go:171","msg":"trace[1345765885] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"606.466125ms","start":"2026-07-17T05:04:35.761769Z","end":"2026-07-17T05:04:36.368235Z","steps":["trace[1345765885] 'process raft request' (duration: 605.634689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:36.36831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:35.761745Z","time spent":"606.539966ms","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T05:04:36.368251Z","caller":"traceutil/trace.go:171","msg":"trace[235725108] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"600.982131ms","start":"2026-07-17T05:04:35.767193Z","end":"2026-07-17T05:04:36.368175Z","steps":["trace[235725108] 'process raft request' (duration: 600.239547ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:36.367845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:35.760201Z","time spent":"607.511327ms","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T05:04:36.368396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.814352ms","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-17T05:04:36.368433Z","caller":"traceutil/trace.go:171","msg":"trace[812109374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:539; }","duration":"187.881195ms","start":"2026-07-17T05:04:36.180536Z","end":"2026-07-17T05:04:36.368418Z","steps":["trace[812109374] 'agreement among raft nodes before linearized reading' (duration: 187.787012ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:36.368464Z","caller":"traceutil/trace.go:171","msg":"trace[27346518] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"593.02783ms","start":"2026-07-17T05:04:35.775427Z","end":"2026-07-17T05:04:36.368455Z","steps":["trace[27346518] 'process raft request' (duration: 592.123111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:36.368504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:35.775416Z","time spent":"593.069542ms","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T05:04:36.368514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:35.767184Z","time spent":"601.208669ms","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T05:04:36.368577Z","caller":"traceutil/trace.go:171","msg":"trace[888908210] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"594.782975ms","start":"2026-07-17T05:04:35.773787Z","end":"2026-07-17T05:04:36.36857Z","steps":["trace[888908210] 'process raft request' (duration: 593.700181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:36.368621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:35.773776Z","time spent":"594.826206ms","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T05:04:36.368698Z","caller":"traceutil/trace.go:171","msg":"trace[695892819] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"591.495231ms","start":"2026-07-17T05:04:35.777195Z","end":"2026-07-17T05:04:36.36869Z","steps":["trace[695892819] 'process raft request' (duration: 590.383636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:36.368744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:35.777185Z","time spent":"591.538513ms","remote":"127.0.0.1:58308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-17T05:04:36.449482Z","caller":"traceutil/trace.go:171","msg":"trace[526028233] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"201.113062ms","start":"2026-07-17T05:04:36.248334Z","end":"2026-07-17T05:04:36.449447Z","steps":["trace[526028233] 'process raft request' (duration: 200.986298ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:36.449566Z","caller":"traceutil/trace.go:171","msg":"trace[175289404] transaction","detail":"{read_only:false; number_of_response:1; response_revision:541; }","duration":"200.815913ms","start":"2026-07-17T05:04:36.248698Z","end":"2026-07-17T05:04:36.449514Z","steps":["trace[175289404] 'process raft request' (duration: 200.683119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:36.449595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.893329ms","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-17T05:04:36.449641Z","caller":"traceutil/trace.go:171","msg":"trace[135108188] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:541; }","duration":"228.955181ms","start":"2026-07-17T05:04:36.220669Z","end":"2026-07-17T05:04:36.449624Z","steps":["trace[135108188] 'agreement among raft nodes before linearized reading' (duration: 228.90122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:36.449507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.723488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T05:04:36.44973Z","caller":"traceutil/trace.go:171","msg":"trace[1903425617] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"268.984066ms","start":"2026-07-17T05:04:36.180708Z","end":"2026-07-17T05:04:36.449692Z","steps":["trace[1903425617] 'agreement among raft nodes before linearized reading' (duration: 268.715627ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:36.449867Z","caller":"traceutil/trace.go:171","msg":"trace[627841686] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"202.452684ms","start":"2026-07-17T05:04:36.247321Z","end":"2026-07-17T05:04:36.449773Z","steps":["trace[627841686] 'process raft request' (duration: 201.853625ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:36.514894Z","caller":"traceutil/trace.go:171","msg":"trace[324949851] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"137.691479ms","start":"2026-07-17T05:04:36.377174Z","end":"2026-07-17T05:04:36.514866Z","steps":["trace[324949851] 'process raft request' (duration: 137.475122ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:36.515045Z","caller":"traceutil/trace.go:171","msg":"trace[1378085436] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"129.346185ms","start":"2026-07-17T05:04:36.385673Z","end":"2026-07-17T05:04:36.515019Z","steps":["trace[1378085436] 'process raft request' (duration: 129.260432ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:36.51507Z","caller":"traceutil/trace.go:171","msg":"trace[826514582] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"133.125254ms","start":"2026-07-17T05:04:36.381909Z","end":"2026-07-17T05:04:36.515034Z","steps":["trace[826514582] 'process raft request' (duration: 132.887217ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:36.515278Z","caller":"traceutil/trace.go:171","msg":"trace[621604936] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"133.259008ms","start":"2026-07-17T05:04:36.381999Z","end":"2026-07-17T05:04:36.515258Z","steps":["trace[621604936] 'process raft request' (duration: 132.849105ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:36.515571Z","caller":"traceutil/trace.go:171","msg":"trace[917894372] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"126.797385ms","start":"2026-07-17T05:04:36.388757Z","end":"2026-07-17T05:04:36.515555Z","steps":["trace[917894372] 'process raft request' (duration: 126.215966ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:36.515581Z","caller":"traceutil/trace.go:171","msg":"trace[49121585] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"126.030891ms","start":"2026-07-17T05:04:36.389539Z","end":"2026-07-17T05:04:36.51557Z","steps":["trace[49121585] 'process raft request' (duration: 125.784733ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:36.515611Z","caller":"traceutil/trace.go:171","msg":"trace[2057888818] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"117.367447ms","start":"2026-07-17T05:04:36.398227Z","end":"2026-07-17T05:04:36.515594Z","steps":["trace[2057888818] 'process raft request' (duration: 117.176831ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:36.515656Z","caller":"traceutil/trace.go:171","msg":"trace[206019647] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"117.051317ms","start":"2026-07-17T05:04:36.398592Z","end":"2026-07-17T05:04:36.515643Z","steps":["trace[206019647] 'process raft request' (duration: 116.908073ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:36.515235Z","caller":"traceutil/trace.go:171","msg":"trace[1841952900] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"129.699156ms","start":"2026-07-17T05:04:36.385522Z","end":"2026-07-17T05:04:36.515221Z","steps":["trace[1841952900] 'process raft request' (duration: 129.383156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:42.718959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.103876ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-17T05:04:42.71903Z","caller":"traceutil/trace.go:171","msg":"trace[864806634] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"180.20718ms","start":"2026-07-17T05:04:42.538813Z","end":"2026-07-17T05:04:42.719021Z","steps":["trace[864806634] 'read index received' (duration: 56.661µs)","trace[864806634] 'applied index is now lower than readState.Index' (duration: 180.149859ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:04:42.719061Z","caller":"traceutil/trace.go:171","msg":"trace[1862374400] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"814.030769ms","start":"2026-07-17T05:04:41.905002Z","end":"2026-07-17T05:04:42.719032Z","steps":["trace[1862374400] 'process raft request' (duration: 46.714516ms)","trace[1862374400] 'compare' (duration: 767.002453ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:04:42.719147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.340495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T05:04:42.719162Z","caller":"traceutil/trace.go:171","msg":"trace[2104583920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"180.397067ms","start":"2026-07-17T05:04:42.53876Z","end":"2026-07-17T05:04:42.719158Z","steps":["trace[2104583920] 'agreement among raft nodes before linearized reading' (duration: 180.303524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:42.719152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:41.904915Z","time spent":"814.201434ms","remote":"127.0.0.1:58648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T05:04:42.719269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.704897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T05:04:42.719338Z","caller":"traceutil/trace.go:171","msg":"trace[1794482236] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"142.770479ms","start":"2026-07-17T05:04:42.576548Z","end":"2026-07-17T05:04:42.719318Z","steps":["trace[1794482236] 'agreement among raft nodes before linearized reading' (duration: 142.606984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:42.719366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.592006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T05:04:42.719443Z","caller":"traceutil/trace.go:171","msg":"trace[435428884] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"144.71125ms","start":"2026-07-17T05:04:42.57471Z","end":"2026-07-17T05:04:42.719421Z","steps":["trace[435428884] 'agreement among raft nodes before linearized reading' (duration: 144.414661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:43.228973Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727670830793276,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T05:04:43.240236Z","caller":"traceutil/trace.go:171","msg":"trace[1764394534] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"517.016868ms","start":"2026-07-17T05:04:42.723196Z","end":"2026-07-17T05:04:43.240213Z","steps":["trace[1764394534] 'process raft request' (duration: 516.421359ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:43.240457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:42.723174Z","time spent":"517.196643ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:04:43.243021Z","caller":"traceutil/trace.go:171","msg":"trace[444573175] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"516.892473ms","start":"2026-07-17T05:04:42.725077Z","end":"2026-07-17T05:04:43.241969Z","steps":["trace[444573175] 'process raft request' (duration: 516.639685ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:43.243014Z","caller":"traceutil/trace.go:171","msg":"trace[1665636587] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:656; }","duration":"513.411044ms","start":"2026-07-17T05:04:42.728483Z","end":"2026-07-17T05:04:43.241894Z","steps":["trace[1665636587] 'read index received' (duration: 511.144442ms)","trace[1665636587] 'applied index is now lower than readState.Index' (duration: 2.265282ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:04:43.242999Z","caller":"traceutil/trace.go:171","msg":"trace[905488300] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"517.532144ms","start":"2026-07-17T05:04:42.725178Z","end":"2026-07-17T05:04:43.24271Z","steps":["trace[905488300] 'process raft request' (duration: 516.676527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:43.243107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:42.725057Z","time spent":"518.014049ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T05:04:43.243193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.687394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T05:04:43.243225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.643226ms","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-17T05:04:43.243253Z","caller":"traceutil/trace.go:171","msg":"trace[388760565] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"446.709018ms","start":"2026-07-17T05:04:42.796535Z","end":"2026-07-17T05:04:43.243244Z","steps":["trace[388760565] 'agreement among raft nodes before linearized reading' (duration: 446.643516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:43.243275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:42.796512Z","time spent":"446.757259ms","remote":"127.0.0.1:58544","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-17T05:04:43.243249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.365512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T05:04:43.243264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:42.725171Z","time spent":"517.974188ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:04:43.243249Z","caller":"traceutil/trace.go:171","msg":"trace[640270701] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:634; }","duration":"514.768167ms","start":"2026-07-17T05:04:42.728455Z","end":"2026-07-17T05:04:43.243223Z","steps":["trace[640270701] 'agreement among raft nodes before linearized reading' (duration: 514.681664ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:43.243367Z","caller":"traceutil/trace.go:171","msg":"trace[823661555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"152.523117ms","start":"2026-07-17T05:04:43.090826Z","end":"2026-07-17T05:04:43.243349Z","steps":["trace[823661555] 'agreement among raft nodes before linearized reading' (duration: 152.373282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:43.243469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:42.728447Z","time spent":"515.005664ms","remote":"127.0.0.1:58802","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" "} {"level":"info","ts":"2026-07-17T05:04:44.161535Z","caller":"traceutil/trace.go:171","msg":"trace[1483332800] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"367.408014ms","start":"2026-07-17T05:04:43.794103Z","end":"2026-07-17T05:04:44.161511Z","steps":["trace[1483332800] 'read index received' (duration: 361.394704ms)","trace[1483332800] 'applied index is now lower than readState.Index' (duration: 6.01229ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:04:44.161701Z","caller":"traceutil/trace.go:171","msg":"trace[993096796] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"521.703807ms","start":"2026-07-17T05:04:43.639961Z","end":"2026-07-17T05:04:44.161665Z","steps":["trace[993096796] 'process raft request' (duration: 515.964965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:44.16178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.663611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:04:44.161817Z","caller":"traceutil/trace.go:171","msg":"trace[1324324597] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"367.759185ms","start":"2026-07-17T05:04:43.794047Z","end":"2026-07-17T05:04:44.161806Z","steps":["trace[1324324597] 'agreement among raft nodes before linearized reading' (duration: 367.59442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:44.161853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:43.794028Z","time spent":"367.817476ms","remote":"127.0.0.1:58276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T05:04:44.161858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:43.639942Z","time spent":"521.83663ms","remote":"127.0.0.1:58364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:04:44.865072Z","caller":"traceutil/trace.go:171","msg":"trace[740216105] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"635.794209ms","start":"2026-07-17T05:04:44.229242Z","end":"2026-07-17T05:04:44.865036Z","steps":["trace[740216105] 'process raft request' (duration: 546.796778ms)","trace[740216105] 'compare' (duration: 88.700051ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:04:44.865239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:44.229223Z","time spent":"635.935333ms","remote":"127.0.0.1:58802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T05:04:45.083682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.253001ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-17T05:04:45.083777Z","caller":"traceutil/trace.go:171","msg":"trace[453016898] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"217.649514ms","start":"2026-07-17T05:04:44.866111Z","end":"2026-07-17T05:04:45.08376Z","steps":["trace[453016898] 'read index received' (duration: 228.478µs)","trace[453016898] 'applied index is now lower than readState.Index' (duration: 217.419966ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:04:45.083868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.747077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:04:45.083897Z","caller":"traceutil/trace.go:171","msg":"trace[2090831862] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"217.803839ms","start":"2026-07-17T05:04:44.866085Z","end":"2026-07-17T05:04:45.083889Z","steps":["trace[2090831862] 'agreement among raft nodes before linearized reading' (duration: 217.748027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:45.083996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.392659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:04:45.084144Z","caller":"traceutil/trace.go:171","msg":"trace[418026825] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:641; }","duration":"206.604465ms","start":"2026-07-17T05:04:44.877513Z","end":"2026-07-17T05:04:45.084117Z","steps":["trace[418026825] 'agreement among raft nodes before linearized reading' (duration: 206.364167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:45.756273Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727670830793299,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T05:04:46.25761Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727670830793299,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-17T05:04:46.400768Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.150415955s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T05:04:46.401255Z","caller":"traceutil/trace.go:171","msg":"trace[202693063] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"1.150979302s","start":"2026-07-17T05:04:45.250252Z","end":"2026-07-17T05:04:46.401231Z","steps":["trace[202693063] 'process raft request' (duration: 1.150764935s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:46.401395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:45.250237Z","time spent":"1.151085116s","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:04:46.450067Z","caller":"traceutil/trace.go:171","msg":"trace[869279527] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"1.198164002s","start":"2026-07-17T05:04:45.251881Z","end":"2026-07-17T05:04:46.450045Z","steps":["trace[869279527] 'process raft request' (duration: 1.198025548s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:46.450775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:45.251859Z","time spent":"1.198811122s","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:04:46.450066Z","caller":"traceutil/trace.go:171","msg":"trace[4526441] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.198205913s","start":"2026-07-17T05:04:45.251828Z","end":"2026-07-17T05:04:46.450034Z","steps":["trace[4526441] 'process raft request' (duration: 1.197949985s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:46.451599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:45.251808Z","time spent":"1.199709451s","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:04:46.459024Z","caller":"traceutil/trace.go:171","msg":"trace[1089089628] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"972.781434ms","start":"2026-07-17T05:04:45.486224Z","end":"2026-07-17T05:04:46.459005Z","steps":["trace[1089089628] 'process raft request' (duration: 970.73864ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:46.459146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:45.486199Z","time spent":"972.880118ms","remote":"127.0.0.1:58364","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:04:46.459019Z","caller":"traceutil/trace.go:171","msg":"trace[1506604201] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:667; }","duration":"1.207042832s","start":"2026-07-17T05:04:45.251926Z","end":"2026-07-17T05:04:46.458969Z","steps":["trace[1506604201] 'read index received' (duration: 1.149144894s)","trace[1506604201] 'applied index is now lower than readState.Index' (duration: 57.897078ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:04:46.459455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.573667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:04:46.459524Z","caller":"traceutil/trace.go:171","msg":"trace[1782905172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"662.68933ms","start":"2026-07-17T05:04:45.796819Z","end":"2026-07-17T05:04:46.459509Z","steps":["trace[1782905172] 'agreement among raft nodes before linearized reading' (duration: 662.552966ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:46.459557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:45.796803Z","time spent":"662.747852ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T05:04:46.459263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.207327821s","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-17T05:04:46.461344Z","caller":"traceutil/trace.go:171","msg":"trace[574994951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"1.209421988s","start":"2026-07-17T05:04:45.251909Z","end":"2026-07-17T05:04:46.461331Z","steps":["trace[574994951] 'agreement among raft nodes before linearized reading' (duration: 1.207151176s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:04:46.46138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:04:45.251899Z","time spent":"1.209467329s","remote":"127.0.0.1:58544","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-17T05:04:47.984234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.247274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:04:47.984306Z","caller":"traceutil/trace.go:171","msg":"trace[1397258500] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:658; }","duration":"193.351207ms","start":"2026-07-17T05:04:47.79094Z","end":"2026-07-17T05:04:47.984291Z","steps":["trace[1397258500] 'range keys from in-memory index tree' (duration: 193.168461ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:51.691159Z","caller":"traceutil/trace.go:171","msg":"trace[1445595323] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"111.407719ms","start":"2026-07-17T05:04:51.57972Z","end":"2026-07-17T05:04:51.691128Z","steps":["trace[1445595323] 'process raft request' (duration: 110.81522ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:04:59.593595Z","caller":"traceutil/trace.go:171","msg":"trace[1744741989] transaction","detail":"{read_only:false; response_revision:786; number_of_response:1; }","duration":"107.911828ms","start":"2026-07-17T05:04:59.48566Z","end":"2026-07-17T05:04:59.593572Z","steps":["trace[1744741989] 'process raft request' (duration: 107.437633ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:05:02.625732Z","caller":"traceutil/trace.go:171","msg":"trace[1888992874] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"497.006696ms","start":"2026-07-17T05:05:02.128662Z","end":"2026-07-17T05:05:02.625669Z","steps":["trace[1888992874] 'process raft request' (duration: 496.851901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:02.626013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:05:02.128634Z","time spent":"497.182272ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:05:02.783216Z","caller":"traceutil/trace.go:171","msg":"trace[1203019348] linearizableReadLoop","detail":"{readStateIndex:839; appliedIndex:836; }","duration":"278.435203ms","start":"2026-07-17T05:05:02.504756Z","end":"2026-07-17T05:05:02.783191Z","steps":["trace[1203019348] 'read index received' (duration: 120.762964ms)","trace[1203019348] 'applied index is now lower than readState.Index' (duration: 157.670708ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:05:02.783348Z","caller":"traceutil/trace.go:171","msg":"trace[2135077463] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"279.689663ms","start":"2026-07-17T05:05:02.50364Z","end":"2026-07-17T05:05:02.783329Z","steps":["trace[2135077463] 'process raft request' (duration: 279.493147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:02.78363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.861557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T05:05:02.783688Z","caller":"traceutil/trace.go:171","msg":"trace[1786458791] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:808; }","duration":"278.97213ms","start":"2026-07-17T05:05:02.504703Z","end":"2026-07-17T05:05:02.783675Z","steps":["trace[1786458791] 'agreement among raft nodes before linearized reading' (duration: 278.807325ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:05:02.783709Z","caller":"traceutil/trace.go:171","msg":"trace[2075986370] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"586.840063ms","start":"2026-07-17T05:05:02.196838Z","end":"2026-07-17T05:05:02.783678Z","steps":["trace[2075986370] 'process raft request' (duration: 586.11585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:02.783867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:05:02.196811Z","time spent":"586.962337ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T05:05:02.79048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.387831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T05:05:02.790546Z","caller":"traceutil/trace.go:171","msg":"trace[496200844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:809; }","duration":"253.523176ms","start":"2026-07-17T05:05:02.537004Z","end":"2026-07-17T05:05:02.790528Z","steps":["trace[496200844] 'agreement among raft nodes before linearized reading' (duration: 253.390672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:02.790523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.703583ms","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-17T05:05:02.79059Z","caller":"traceutil/trace.go:171","msg":"trace[1950704209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:809; }","duration":"254.781086ms","start":"2026-07-17T05:05:02.535792Z","end":"2026-07-17T05:05:02.790573Z","steps":["trace[1950704209] 'agreement among raft nodes before linearized reading' (duration: 254.581019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:02.790726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.777334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T05:05:02.790779Z","caller":"traceutil/trace.go:171","msg":"trace[1669837907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:809; }","duration":"253.850006ms","start":"2026-07-17T05:05:02.536912Z","end":"2026-07-17T05:05:02.790762Z","steps":["trace[1669837907] 'agreement among raft nodes before linearized reading' (duration: 253.691141ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:05:38.106143Z","caller":"traceutil/trace.go:171","msg":"trace[112465012] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1061; }","duration":"309.575905ms","start":"2026-07-17T05:05:37.796549Z","end":"2026-07-17T05:05:38.106125Z","steps":["trace[112465012] 'read index received' (duration: 309.440761ms)","trace[112465012] 'applied index is now lower than readState.Index' (duration: 134.194µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:05:38.106246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.679389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:05:38.106267Z","caller":"traceutil/trace.go:171","msg":"trace[1892165867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1023; }","duration":"309.750211ms","start":"2026-07-17T05:05:37.79651Z","end":"2026-07-17T05:05:38.10626Z","steps":["trace[1892165867] 'agreement among raft nodes before linearized reading' (duration: 309.692329ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:05:38.106247Z","caller":"traceutil/trace.go:171","msg":"trace[1473110774] transaction","detail":"{read_only:false; response_revision:1023; number_of_response:1; }","duration":"377.786371ms","start":"2026-07-17T05:05:37.728422Z","end":"2026-07-17T05:05:38.106209Z","steps":["trace[1473110774] 'process raft request' (duration: 377.566384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:38.106291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:05:37.796489Z","time spent":"309.795562ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T05:05:38.106436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:05:37.728402Z","time spent":"377.931154ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T05:05:38.87702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.901759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:05:38.877092Z","caller":"traceutil/trace.go:171","msg":"trace[406957721] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1023; }","duration":"577.366624ms","start":"2026-07-17T05:05:38.299708Z","end":"2026-07-17T05:05:38.877075Z","steps":["trace[406957721] 'count revisions from in-memory index tree' (duration: 576.789945ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:38.877138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:05:38.29969Z","time spent":"577.432166ms","remote":"127.0.0.1:58570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":29,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-07-17T05:05:39.413048Z","caller":"traceutil/trace.go:171","msg":"trace[398574572] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1062; }","duration":"451.462509ms","start":"2026-07-17T05:05:38.961568Z","end":"2026-07-17T05:05:39.413031Z","steps":["trace[398574572] 'read index received' (duration: 451.353355ms)","trace[398574572] 'applied index is now lower than readState.Index' (duration: 108.304µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:05:39.413133Z","caller":"traceutil/trace.go:171","msg":"trace[959819739] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"532.345896ms","start":"2026-07-17T05:05:38.88078Z","end":"2026-07-17T05:05:39.413126Z","steps":["trace[959819739] 'process raft request' (duration: 532.136219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:39.413204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:05:38.880762Z","time spent":"532.387318ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T05:05:39.413269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.388768ms","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-17T05:05:39.413312Z","caller":"traceutil/trace.go:171","msg":"trace[166951399] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1024; }","duration":"423.46728ms","start":"2026-07-17T05:05:38.989833Z","end":"2026-07-17T05:05:39.4133Z","steps":["trace[166951399] 'agreement among raft nodes before linearized reading' (duration: 423.308425ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:39.413338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:05:38.989818Z","time spent":"423.514371ms","remote":"127.0.0.1:58544","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-17T05:05:39.413435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.848461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-17T05:05:39.413454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.375916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T05:05:39.413528Z","caller":"traceutil/trace.go:171","msg":"trace[491853840] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1024; }","duration":"451.987225ms","start":"2026-07-17T05:05:38.961518Z","end":"2026-07-17T05:05:39.413505Z","steps":["trace[491853840] 'agreement among raft nodes before linearized reading' (duration: 451.742737ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:05:39.413539Z","caller":"traceutil/trace.go:171","msg":"trace[1039280649] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1024; }","duration":"411.50089ms","start":"2026-07-17T05:05:39.002017Z","end":"2026-07-17T05:05:39.413517Z","steps":["trace[1039280649] 'agreement among raft nodes before linearized reading' (duration: 411.228342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:39.413687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:05:39.002001Z","time spent":"411.673115ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T05:05:39.413732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.494597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T05:05:39.413791Z","caller":"traceutil/trace.go:171","msg":"trace[260549708] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1024; }","duration":"409.566628ms","start":"2026-07-17T05:05:39.004213Z","end":"2026-07-17T05:05:39.41378Z","steps":["trace[260549708] 'agreement among raft nodes before linearized reading' (duration: 409.429954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:05:39.413819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:05:39.004206Z","time spent":"409.60611ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T05:05:39.413597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:05:38.961446Z","time spent":"452.14154ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T05:06:05.237497Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.023786193s","expected-duration":"1s"} {"level":"info","ts":"2026-07-17T05:06:05.237835Z","caller":"traceutil/trace.go:171","msg":"trace[457060659] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"1.024208817s","start":"2026-07-17T05:06:04.213607Z","end":"2026-07-17T05:06:05.237816Z","steps":["trace[457060659] 'process raft request' (duration: 1.024074792s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:05.237969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:04.213587Z","time spent":"1.024313879s","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:06:05.609125Z","caller":"traceutil/trace.go:171","msg":"trace[1137062830] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"1.205526961s","start":"2026-07-17T05:06:04.403568Z","end":"2026-07-17T05:06:05.609095Z","steps":["trace[1137062830] 'process raft request' (duration: 1.177338007s)","trace[1137062830] 'compare' (duration: 28.024909ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:06:05.609499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:04.403535Z","time spent":"1.205710707s","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:06:05.636547Z","caller":"traceutil/trace.go:171","msg":"trace[163987132] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1152; }","duration":"846.768605ms","start":"2026-07-17T05:06:04.789757Z","end":"2026-07-17T05:06:05.636526Z","steps":["trace[163987132] 'read index received' (duration: 448.015479ms)","trace[163987132] 'applied index is now lower than readState.Index' (duration: 398.751476ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:06:05.63669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.92431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:06:05.636726Z","caller":"traceutil/trace.go:171","msg":"trace[2047447166] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1110; }","duration":"846.999092ms","start":"2026-07-17T05:06:04.789718Z","end":"2026-07-17T05:06:05.636717Z","steps":["trace[2047447166] 'agreement among raft nodes before linearized reading' (duration: 846.93069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:05.636763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:04.789701Z","time spent":"847.054785ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T05:06:05.637161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.573957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:06:05.637265Z","caller":"traceutil/trace.go:171","msg":"trace[757914584] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1110; }","duration":"149.764213ms","start":"2026-07-17T05:06:05.487484Z","end":"2026-07-17T05:06:05.637248Z","steps":["trace[757914584] 'agreement among raft nodes before linearized reading' (duration: 149.518715ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:05.763484Z","caller":"traceutil/trace.go:171","msg":"trace[567988880] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"121.462444ms","start":"2026-07-17T05:06:05.641989Z","end":"2026-07-17T05:06:05.763451Z","steps":["trace[567988880] 'process raft request' (duration: 79.168092ms)","trace[567988880] 'compare' (duration: 42.062835ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:06:05.763499Z","caller":"traceutil/trace.go:171","msg":"trace[59173300] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"121.433994ms","start":"2026-07-17T05:06:05.642044Z","end":"2026-07-17T05:06:05.763478Z","steps":["trace[59173300] 'process raft request' (duration: 121.361702ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:05.763746Z","caller":"traceutil/trace.go:171","msg":"trace[689021490] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"120.442643ms","start":"2026-07-17T05:06:05.643296Z","end":"2026-07-17T05:06:05.763739Z","steps":["trace[689021490] 'process raft request' (duration: 120.36999ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:05.763795Z","caller":"traceutil/trace.go:171","msg":"trace[1205186049] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"121.255618ms","start":"2026-07-17T05:06:05.642467Z","end":"2026-07-17T05:06:05.763723Z","steps":["trace[1205186049] 'process raft request' (duration: 120.98901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:07.811604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.006336ms","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-17T05:06:07.811702Z","caller":"traceutil/trace.go:171","msg":"trace[1066563597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1116; }","duration":"130.174632ms","start":"2026-07-17T05:06:07.681503Z","end":"2026-07-17T05:06:07.811677Z","steps":["trace[1066563597] 'range keys from in-memory index tree' (duration: 129.79517ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:07.975321Z","caller":"traceutil/trace.go:171","msg":"trace[332442140] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"157.971763ms","start":"2026-07-17T05:06:07.817327Z","end":"2026-07-17T05:06:07.975299Z","steps":["trace[332442140] 'process raft request' (duration: 157.777637ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:07.975675Z","caller":"traceutil/trace.go:171","msg":"trace[1122676640] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"158.301244ms","start":"2026-07-17T05:06:07.817349Z","end":"2026-07-17T05:06:07.97565Z","steps":["trace[1122676640] 'process raft request' (duration: 157.896921ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:07.97599Z","caller":"traceutil/trace.go:171","msg":"trace[1175579495] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"158.348716ms","start":"2026-07-17T05:06:07.817625Z","end":"2026-07-17T05:06:07.975974Z","steps":["trace[1175579495] 'process raft request' (duration: 157.946763ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:07.975997Z","caller":"traceutil/trace.go:171","msg":"trace[2051625769] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"157.444597ms","start":"2026-07-17T05:06:07.818506Z","end":"2026-07-17T05:06:07.975951Z","steps":["trace[2051625769] 'process raft request' (duration: 157.297423ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:09.780166Z","caller":"traceutil/trace.go:171","msg":"trace[1902930573] linearizableReadLoop","detail":"{readStateIndex:1169; appliedIndex:1168; }","duration":"105.454127ms","start":"2026-07-17T05:06:09.67469Z","end":"2026-07-17T05:06:09.780144Z","steps":["trace[1902930573] 'read index received' (duration: 40.32411ms)","trace[1902930573] 'applied index is now lower than readState.Index' (duration: 65.129377ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:06:09.780225Z","caller":"traceutil/trace.go:171","msg":"trace[1203946944] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"144.695542ms","start":"2026-07-17T05:06:09.63548Z","end":"2026-07-17T05:06:09.780176Z","steps":["trace[1203946944] 'process raft request' (duration: 79.608366ms)","trace[1203946944] 'compare' (duration: 64.960672ms)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:06:09.780352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.628362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:06:09.780464Z","caller":"traceutil/trace.go:171","msg":"trace[1833560697] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1123; }","duration":"105.756346ms","start":"2026-07-17T05:06:09.67468Z","end":"2026-07-17T05:06:09.780436Z","steps":["trace[1833560697] 'agreement among raft nodes before linearized reading' (duration: 105.537409ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:10.187863Z","caller":"traceutil/trace.go:171","msg":"trace[1209342472] transaction","detail":"{read_only:false; response_revision:1126; number_of_response:1; }","duration":"195.518116ms","start":"2026-07-17T05:06:09.992321Z","end":"2026-07-17T05:06:10.187839Z","steps":["trace[1209342472] 'process raft request' (duration: 195.436473ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:10.188201Z","caller":"traceutil/trace.go:171","msg":"trace[648732085] transaction","detail":"{read_only:false; response_revision:1125; number_of_response:1; }","duration":"195.96295ms","start":"2026-07-17T05:06:09.992224Z","end":"2026-07-17T05:06:10.188187Z","steps":["trace[648732085] 'process raft request' (duration: 149.121263ms)","trace[648732085] 'compare' (duration: 46.30442ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:06:10.188631Z","caller":"traceutil/trace.go:171","msg":"trace[664062311] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"196.111744ms","start":"2026-07-17T05:06:09.992505Z","end":"2026-07-17T05:06:10.188616Z","steps":["trace[664062311] 'process raft request' (duration: 195.302909ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:10.188826Z","caller":"traceutil/trace.go:171","msg":"trace[1554996292] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"196.169327ms","start":"2026-07-17T05:06:09.992639Z","end":"2026-07-17T05:06:10.188808Z","steps":["trace[1554996292] 'process raft request' (duration: 195.579128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:26.692935Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727670830794626,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T05:06:26.998756Z","caller":"traceutil/trace.go:171","msg":"trace[758405336] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1277; }","duration":"806.885349ms","start":"2026-07-17T05:06:26.191853Z","end":"2026-07-17T05:06:26.998739Z","steps":["trace[758405336] 'read index received' (duration: 806.737114ms)","trace[758405336] 'applied index is now lower than readState.Index' (duration: 147.605µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:06:26.998944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"807.082486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-17T05:06:26.998977Z","caller":"traceutil/trace.go:171","msg":"trace[968909220] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1227; }","duration":"807.163578ms","start":"2026-07-17T05:06:26.191804Z","end":"2026-07-17T05:06:26.998968Z","steps":["trace[968909220] 'agreement among raft nodes before linearized reading' (duration: 807.037124ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:26.999014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:26.191777Z","time spent":"807.22699ms","remote":"127.0.0.1:58544","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-17T05:06:26.999097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.354494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-17T05:06:26.999161Z","caller":"traceutil/trace.go:171","msg":"trace[1238240958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1227; }","duration":"744.477289ms","start":"2026-07-17T05:06:26.254667Z","end":"2026-07-17T05:06:26.999145Z","steps":["trace[1238240958] 'agreement among raft nodes before linearized reading' (duration: 744.281032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:26.999163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.313479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-07-17T05:06:26.999192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:26.254644Z","time spent":"744.54281ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-17T05:06:26.999201Z","caller":"traceutil/trace.go:171","msg":"trace[792415583] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1227; }","duration":"736.371601ms","start":"2026-07-17T05:06:26.26282Z","end":"2026-07-17T05:06:26.999192Z","steps":["trace[792415583] 'agreement among raft nodes before linearized reading' (duration: 736.270668ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:26.99919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.439153ms","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-17T05:06:26.999115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.29572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:06:26.999238Z","caller":"traceutil/trace.go:171","msg":"trace[2036342354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1227; }","duration":"736.521635ms","start":"2026-07-17T05:06:26.262709Z","end":"2026-07-17T05:06:26.99923Z","steps":["trace[2036342354] 'agreement among raft nodes before linearized reading' (duration: 736.433303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:26.999243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:26.262813Z","time spent":"736.420883ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T05:06:26.999265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:26.262685Z","time spent":"736.573217ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-17T05:06:26.999283Z","caller":"traceutil/trace.go:171","msg":"trace[1114269904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1227; }","duration":"207.490285ms","start":"2026-07-17T05:06:26.791765Z","end":"2026-07-17T05:06:26.999255Z","steps":["trace[1114269904] 'agreement among raft nodes before linearized reading' (duration: 207.32905ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:26.998891Z","caller":"traceutil/trace.go:171","msg":"trace[411336350] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"905.965933ms","start":"2026-07-17T05:06:26.092911Z","end":"2026-07-17T05:06:26.998877Z","steps":["trace[411336350] 'process raft request' (duration: 905.718475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:26.999354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.508485ms","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-17T05:06:26.999448Z","caller":"traceutil/trace.go:171","msg":"trace[1964647579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1227; }","duration":"736.6408ms","start":"2026-07-17T05:06:26.262784Z","end":"2026-07-17T05:06:26.999425Z","steps":["trace[1964647579] 'agreement among raft nodes before linearized reading' (duration: 736.34478ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:26.99951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:26.262775Z","time spent":"736.724652ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T05:06:26.999659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:26.092891Z","time spent":"906.48498ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:06:27.585352Z","caller":"traceutil/trace.go:171","msg":"trace[1318125386] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"582.078644ms","start":"2026-07-17T05:06:27.003259Z","end":"2026-07-17T05:06:27.585338Z","steps":["trace[1318125386] 'process raft request' (duration: 581.969441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:27.58546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:27.003243Z","time spent":"582.159796ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:06:27.586255Z","caller":"traceutil/trace.go:171","msg":"trace[1606163266] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"582.001262ms","start":"2026-07-17T05:06:27.004225Z","end":"2026-07-17T05:06:27.586227Z","steps":["trace[1606163266] 'process raft request' (duration: 581.805475ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:27.586473Z","caller":"traceutil/trace.go:171","msg":"trace[927918316] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"582.078274ms","start":"2026-07-17T05:06:27.004385Z","end":"2026-07-17T05:06:27.586463Z","steps":["trace[927918316] 'process raft request' (duration: 581.791035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:27.586468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:27.004219Z","time spent":"582.151616ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T05:06:27.586524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:27.00438Z","time spent":"582.118585ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:06:27.586645Z","caller":"traceutil/trace.go:171","msg":"trace[1440436862] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"581.97769ms","start":"2026-07-17T05:06:27.004651Z","end":"2026-07-17T05:06:27.586629Z","steps":["trace[1440436862] 'process raft request' (duration: 581.772264ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:27.586695Z","caller":"traceutil/trace.go:171","msg":"trace[160026902] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"581.729153ms","start":"2026-07-17T05:06:27.004959Z","end":"2026-07-17T05:06:27.586688Z","steps":["trace[160026902] 'process raft request' (duration: 581.62883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:27.58672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:27.004646Z","time spent":"582.032882ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T05:06:27.586734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:27.004938Z","time spent":"581.774834ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:06:27.586918Z","caller":"traceutil/trace.go:171","msg":"trace[1704195958] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"167.249168ms","start":"2026-07-17T05:06:27.419659Z","end":"2026-07-17T05:06:27.586908Z","steps":["trace[1704195958] 'process raft request' (duration: 166.99813ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:39.784071Z","caller":"traceutil/trace.go:171","msg":"trace[1983446910] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"113.897403ms","start":"2026-07-17T05:06:39.670153Z","end":"2026-07-17T05:06:39.78405Z","steps":["trace[1983446910] 'process raft request' (duration: 113.868142ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:39.784103Z","caller":"traceutil/trace.go:171","msg":"trace[1931324682] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"115.056471ms","start":"2026-07-17T05:06:39.669032Z","end":"2026-07-17T05:06:39.784088Z","steps":["trace[1931324682] 'process raft request' (duration: 95.489038ms)","trace[1931324682] 'compare' (duration: 19.404727ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:06:44.138457Z","caller":"traceutil/trace.go:171","msg":"trace[1607085359] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1342; }","duration":"462.838774ms","start":"2026-07-17T05:06:43.675593Z","end":"2026-07-17T05:06:44.138431Z","steps":["trace[1607085359] 'read index received' (duration: 462.603466ms)","trace[1607085359] 'applied index is now lower than readState.Index' (duration: 233.768µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:06:44.13854Z","caller":"traceutil/trace.go:171","msg":"trace[1237536207] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"464.438965ms","start":"2026-07-17T05:06:43.674062Z","end":"2026-07-17T05:06:44.138501Z","steps":["trace[1237536207] 'process raft request' (duration: 464.187587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:44.138745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.110812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T05:06:44.138799Z","caller":"traceutil/trace.go:171","msg":"trace[1479670387] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1289; }","duration":"463.223466ms","start":"2026-07-17T05:06:43.675561Z","end":"2026-07-17T05:06:44.138784Z","steps":["trace[1479670387] 'agreement among raft nodes before linearized reading' (duration: 463.03429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:44.138832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:43.675546Z","time spent":"463.276608ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T05:06:44.13893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:43.674042Z","time spent":"464.709863ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:06:44.144731Z","caller":"traceutil/trace.go:171","msg":"trace[1362960403] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"465.592482ms","start":"2026-07-17T05:06:43.679113Z","end":"2026-07-17T05:06:44.144706Z","steps":["trace[1362960403] 'process raft request' (duration: 465.469388ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:44.144888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:43.679099Z","time spent":"465.702765ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T05:06:44.144996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.389259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:06:44.145052Z","caller":"traceutil/trace.go:171","msg":"trace[900918276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1290; }","duration":"357.449091ms","start":"2026-07-17T05:06:43.787593Z","end":"2026-07-17T05:06:44.145042Z","steps":["trace[900918276] 'agreement among raft nodes before linearized reading' (duration: 357.369079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:44.145054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.525748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-17T05:06:44.145036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.910112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-07-17T05:06:44.145083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:43.787554Z","time spent":"357.523723ms","remote":"127.0.0.1:58276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T05:06:44.145094Z","caller":"traceutil/trace.go:171","msg":"trace[836801793] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1290; }","duration":"343.770577ms","start":"2026-07-17T05:06:43.801315Z","end":"2026-07-17T05:06:44.145085Z","steps":["trace[836801793] 'agreement among raft nodes before linearized reading' (duration: 343.659533ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:44.145104Z","caller":"traceutil/trace.go:171","msg":"trace[1041576622] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1290; }","duration":"466.004845ms","start":"2026-07-17T05:06:43.679089Z","end":"2026-07-17T05:06:44.145094Z","steps":["trace[1041576622] 'agreement among raft nodes before linearized reading' (duration: 465.828149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:44.145118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:43.801298Z","time spent":"343.814558ms","remote":"127.0.0.1:58544","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-17T05:06:44.145139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:43.679075Z","time spent":"466.054926ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-17T05:06:44.14521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.807288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T05:06:44.145275Z","caller":"traceutil/trace.go:171","msg":"trace[98589342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1290; }","duration":"343.925042ms","start":"2026-07-17T05:06:43.801344Z","end":"2026-07-17T05:06:44.145269Z","steps":["trace[98589342] 'agreement among raft nodes before linearized reading' (duration: 343.587061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:44.145299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:43.801326Z","time spent":"343.968152ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-17T05:06:45.3665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.74165ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-07-17T05:06:45.366759Z","caller":"traceutil/trace.go:171","msg":"trace[1184222812] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"337.961261ms","start":"2026-07-17T05:06:45.028784Z","end":"2026-07-17T05:06:45.366745Z","steps":["trace[1184222812] 'process raft request' (duration: 337.807497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:45.366831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:45.028764Z","time spent":"338.028273ms","remote":"127.0.0.1:58346","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":823,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-17T05:06:46.17088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.212936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-07-17T05:06:46.170978Z","caller":"traceutil/trace.go:171","msg":"trace[1014526870] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1298; }","duration":"745.347091ms","start":"2026-07-17T05:06:45.425611Z","end":"2026-07-17T05:06:46.170958Z","steps":["trace[1014526870] 'range keys from in-memory index tree' (duration: 745.04714ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:46.170955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.161632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T05:06:46.171018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:45.425586Z","time spent":"745.423483ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5355,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-17T05:06:46.171056Z","caller":"traceutil/trace.go:171","msg":"trace[922119329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1298; }","duration":"379.281815ms","start":"2026-07-17T05:06:45.791736Z","end":"2026-07-17T05:06:46.171018Z","steps":["trace[922119329] 'range keys from in-memory index tree' (duration: 379.020577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:46.171115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:45.791719Z","time spent":"379.382308ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T05:06:46.588291Z","caller":"traceutil/trace.go:171","msg":"trace[997325529] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"444.025844ms","start":"2026-07-17T05:06:46.144241Z","end":"2026-07-17T05:06:46.588267Z","steps":["trace[997325529] 'process raft request' (duration: 443.862489ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:46.588438Z","caller":"traceutil/trace.go:171","msg":"trace[1607324608] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1354; }","duration":"441.686549ms","start":"2026-07-17T05:06:46.14672Z","end":"2026-07-17T05:06:46.588406Z","steps":["trace[1607324608] 'read index received' (duration: 441.670919ms)","trace[1607324608] 'applied index is now lower than readState.Index' (duration: 13.09µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:06:46.588462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:46.14421Z","time spent":"444.154758ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T05:06:46.588541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.821544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-17T05:06:46.588579Z","caller":"traceutil/trace.go:171","msg":"trace[1282313331] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1299; }","duration":"441.887936ms","start":"2026-07-17T05:06:46.146679Z","end":"2026-07-17T05:06:46.588567Z","steps":["trace[1282313331] 'agreement among raft nodes before linearized reading' (duration: 441.826335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:46.588618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:46.14666Z","time spent":"441.949298ms","remote":"127.0.0.1:52248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":31,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-17T05:06:46.651647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.133182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T05:06:46.651733Z","caller":"traceutil/trace.go:171","msg":"trace[1768308061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1300; }","duration":"502.262257ms","start":"2026-07-17T05:06:46.149445Z","end":"2026-07-17T05:06:46.651707Z","steps":["trace[1768308061] 'agreement among raft nodes before linearized reading' (duration: 502.04391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:46.651809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:46.149422Z","time spent":"502.37329ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-17T05:06:46.65195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.803577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T05:06:46.651983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.255985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:06:46.652065Z","caller":"traceutil/trace.go:171","msg":"trace[910374822] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"472.81501ms","start":"2026-07-17T05:06:46.179239Z","end":"2026-07-17T05:06:46.652054Z","steps":["trace[910374822] 'process raft request' (duration: 472.119408ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:46.652067Z","caller":"traceutil/trace.go:171","msg":"trace[615263369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1300; }","duration":"479.379589ms","start":"2026-07-17T05:06:46.172669Z","end":"2026-07-17T05:06:46.652049Z","steps":["trace[615263369] 'agreement among raft nodes before linearized reading' (duration: 479.252345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:46.652111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:46.172652Z","time spent":"479.451242ms","remote":"127.0.0.1:58276","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T05:06:46.652129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.628594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T05:06:46.652168Z","caller":"traceutil/trace.go:171","msg":"trace[1988928173] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1300; }","duration":"494.681506ms","start":"2026-07-17T05:06:46.157473Z","end":"2026-07-17T05:06:46.652154Z","steps":["trace[1988928173] 'agreement among raft nodes before linearized reading' (duration: 494.584953ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:46.652163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:46.179217Z","time spent":"472.885203ms","remote":"127.0.0.1:58452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5457,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T05:06:46.652208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:46.157464Z","time spent":"494.736028ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-17T05:06:46.652389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.879433ms","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-17T05:06:46.652429Z","caller":"traceutil/trace.go:171","msg":"trace[1676186998] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1300; }","duration":"472.945035ms","start":"2026-07-17T05:06:46.179474Z","end":"2026-07-17T05:06:46.652419Z","steps":["trace[1676186998] 'agreement among raft nodes before linearized reading' (duration: 472.817371ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:46.652021Z","caller":"traceutil/trace.go:171","msg":"trace[1062661366] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1300; }","duration":"233.920861ms","start":"2026-07-17T05:06:46.41808Z","end":"2026-07-17T05:06:46.652001Z","steps":["trace[1062661366] 'agreement among raft nodes before linearized reading' (duration: 233.781506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:46.652472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:46.179463Z","time spent":"473.002046ms","remote":"127.0.0.1:58544","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-17T05:06:46.652744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.185691ms","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-17T05:06:46.652804Z","caller":"traceutil/trace.go:171","msg":"trace[951713539] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1300; }","duration":"472.272853ms","start":"2026-07-17T05:06:46.180519Z","end":"2026-07-17T05:06:46.652792Z","steps":["trace[951713539] 'agreement among raft nodes before linearized reading' (duration: 472.163499ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:46.652818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.196532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-17T05:06:46.652837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:46.180503Z","time spent":"472.327185ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-17T05:06:46.652864Z","caller":"traceutil/trace.go:171","msg":"trace[714477138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1300; }","duration":"473.256405ms","start":"2026-07-17T05:06:46.179596Z","end":"2026-07-17T05:06:46.652852Z","steps":["trace[714477138] 'agreement among raft nodes before linearized reading' (duration: 473.10618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:46.6529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:46.17959Z","time spent":"473.303756ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-17T05:06:48.10154Z","caller":"traceutil/trace.go:171","msg":"trace[1673417734] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"511.888934ms","start":"2026-07-17T05:06:47.589627Z","end":"2026-07-17T05:06:48.101516Z","steps":["trace[1673417734] 'process raft request' (duration: 511.742379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:48.101834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:47.589575Z","time spent":"512.179493ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:06:48.102283Z","caller":"traceutil/trace.go:171","msg":"trace[909082883] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1364; }","duration":"308.428262ms","start":"2026-07-17T05:06:47.793845Z","end":"2026-07-17T05:06:48.102273Z","steps":["trace[909082883] 'read index received' (duration: 308.424482ms)","trace[909082883] 'applied index is now lower than readState.Index' (duration: 3.08µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:06:48.102414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.98777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-xkts5\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-07-17T05:06:48.102439Z","caller":"traceutil/trace.go:171","msg":"trace[247427791] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-xkts5; range_end:; response_count:1; response_revision:1309; }","duration":"265.044802ms","start":"2026-07-17T05:06:47.837387Z","end":"2026-07-17T05:06:48.102432Z","steps":["trace[247427791] 'agreement among raft nodes before linearized reading' (duration: 264.940549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:48.102594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.748842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:06:48.102616Z","caller":"traceutil/trace.go:171","msg":"trace[2125491766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1309; }","duration":"308.799884ms","start":"2026-07-17T05:06:47.793809Z","end":"2026-07-17T05:06:48.102609Z","steps":["trace[2125491766] 'agreement among raft nodes before linearized reading' (duration: 308.764113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:48.102636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:47.793792Z","time spent":"308.838865ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-17T05:06:48.418506Z","caller":"traceutil/trace.go:171","msg":"trace[344768479] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"305.85257ms","start":"2026-07-17T05:06:48.112636Z","end":"2026-07-17T05:06:48.418488Z","steps":["trace[344768479] 'process raft request' (duration: 305.744256ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:48.418615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:48.112615Z","time spent":"305.944013ms","remote":"127.0.0.1:58462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11375,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T05:06:58.291606Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8471727670830794954,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-17T05:06:58.674756Z","caller":"traceutil/trace.go:171","msg":"trace[687216730] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"885.725306ms","start":"2026-07-17T05:06:57.78901Z","end":"2026-07-17T05:06:58.674735Z","steps":["trace[687216730] 'process raft request' (duration: 885.584941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:58.674898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:57.78898Z","time spent":"885.84356ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-17T05:06:58.991878Z","caller":"traceutil/trace.go:171","msg":"trace[773873932] linearizableReadLoop","detail":"{readStateIndex:1447; appliedIndex:1445; }","duration":"1.200554671s","start":"2026-07-17T05:06:57.791301Z","end":"2026-07-17T05:06:58.991856Z","steps":["trace[773873932] 'read index received' (duration: 883.358361ms)","trace[773873932] 'applied index is now lower than readState.Index' (duration: 317.19549ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:06:58.992074Z","caller":"traceutil/trace.go:171","msg":"trace[1238410807] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"365.539118ms","start":"2026-07-17T05:06:58.626522Z","end":"2026-07-17T05:06:58.992061Z","steps":["trace[1238410807] 'process raft request' (duration: 365.27239ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:58.991894Z","caller":"traceutil/trace.go:171","msg":"trace[1598649612] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"795.712382ms","start":"2026-07-17T05:06:58.196156Z","end":"2026-07-17T05:06:58.991869Z","steps":["trace[1598649612] 'process raft request' (duration: 793.653537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:58.992297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:58.196131Z","time spent":"796.106605ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T05:06:58.992503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201194871s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-17T05:06:58.99257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.741408ms","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-17T05:06:58.992612Z","caller":"traceutil/trace.go:171","msg":"trace[988575868] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1391; }","duration":"174.79756ms","start":"2026-07-17T05:06:58.817803Z","end":"2026-07-17T05:06:58.992601Z","steps":["trace[988575868] 'agreement among raft nodes before linearized reading' (duration: 174.689007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:58.992648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.439385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-17T05:06:58.992716Z","caller":"traceutil/trace.go:171","msg":"trace[2114383605] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1391; }","duration":"277.542599ms","start":"2026-07-17T05:06:58.715158Z","end":"2026-07-17T05:06:58.992701Z","steps":["trace[2114383605] 'agreement among raft nodes before linearized reading' (duration: 277.349462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:58.992746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.904981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-17T05:06:58.992754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.04202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-17T05:06:58.992802Z","caller":"traceutil/trace.go:171","msg":"trace[401573833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1391; }","duration":"187.123512ms","start":"2026-07-17T05:06:58.805668Z","end":"2026-07-17T05:06:58.992791Z","steps":["trace[401573833] 'agreement among raft nodes before linearized reading' (duration: 186.997168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:58.992834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.789967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-17T05:06:58.992861Z","caller":"traceutil/trace.go:171","msg":"trace[1980579928] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1391; }","duration":"459.855198ms","start":"2026-07-17T05:06:58.532999Z","end":"2026-07-17T05:06:58.992855Z","steps":["trace[1980579928] 'agreement among raft nodes before linearized reading' (duration: 459.787486ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:58.992769Z","caller":"traceutil/trace.go:171","msg":"trace[692501487] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1391; }","duration":"371.967673ms","start":"2026-07-17T05:06:58.620794Z","end":"2026-07-17T05:06:58.992762Z","steps":["trace[692501487] 'agreement among raft nodes before linearized reading' (duration: 371.922691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:58.99289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:58.532979Z","time spent":"459.90557ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-17T05:06:58.992905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:58.620773Z","time spent":"372.119738ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":13,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-07-17T05:06:58.992576Z","caller":"traceutil/trace.go:171","msg":"trace[1599786231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1391; }","duration":"1.201280203s","start":"2026-07-17T05:06:57.791283Z","end":"2026-07-17T05:06:58.992563Z","steps":["trace[1599786231] 'agreement among raft nodes before linearized reading' (duration: 1.20118375s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:58.992982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:57.791276Z","time spent":"1.201698997s","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-17T05:06:58.993006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.148831235s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:90218"} {"level":"info","ts":"2026-07-17T05:06:58.993029Z","caller":"traceutil/trace.go:171","msg":"trace[1215291335] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1391; }","duration":"1.148853916s","start":"2026-07-17T05:06:57.844168Z","end":"2026-07-17T05:06:58.993022Z","steps":["trace[1215291335] 'agreement among raft nodes before linearized reading' (duration: 1.148777363s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:58.993055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:57.844133Z","time spent":"1.148917478s","remote":"127.0.0.1:58314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":90242,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-07-17T05:06:58.992951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.079861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-17T05:06:58.993143Z","caller":"traceutil/trace.go:171","msg":"trace[195361641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1391; }","duration":"187.331509ms","start":"2026-07-17T05:06:58.805799Z","end":"2026-07-17T05:06:58.993131Z","steps":["trace[195361641] 'agreement among raft nodes before linearized reading' (duration: 186.859834ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:58.992296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:58.626494Z","time spent":"365.704214ms","remote":"127.0.0.1:58544","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-17T05:06:58.992305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.14961117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-17T05:06:58.99351Z","caller":"traceutil/trace.go:171","msg":"trace[1020061538] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1391; }","duration":"1.15086126s","start":"2026-07-17T05:06:57.842636Z","end":"2026-07-17T05:06:58.993497Z","steps":["trace[1020061538] 'agreement among raft nodes before linearized reading' (duration: 1.14961448s)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:58.993566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-17T05:06:57.842609Z","time spent":"1.150931622s","remote":"127.0.0.1:58330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":142,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-07-17T05:06:58.99679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.471075ms","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-17T05:06:58.996868Z","caller":"traceutil/trace.go:171","msg":"trace[1388998759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1391; }","duration":"182.574158ms","start":"2026-07-17T05:06:58.81428Z","end":"2026-07-17T05:06:58.996854Z","steps":["trace[1388998759] 'agreement among raft nodes before linearized reading' (duration: 179.056736ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:59.178671Z","caller":"traceutil/trace.go:171","msg":"trace[1094194296] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"180.976467ms","start":"2026-07-17T05:06:58.997674Z","end":"2026-07-17T05:06:59.178651Z","steps":["trace[1094194296] 'process raft request' (duration: 178.612972ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:59.182438Z","caller":"traceutil/trace.go:171","msg":"trace[1502693735] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"184.478569ms","start":"2026-07-17T05:06:58.997946Z","end":"2026-07-17T05:06:59.182425Z","steps":["trace[1502693735] 'process raft request' (duration: 184.348625ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:59.182664Z","caller":"traceutil/trace.go:171","msg":"trace[181558147] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"184.483019ms","start":"2026-07-17T05:06:58.998157Z","end":"2026-07-17T05:06:59.18264Z","steps":["trace[181558147] 'process raft request' (duration: 184.233201ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:59.183004Z","caller":"traceutil/trace.go:171","msg":"trace[307925724] linearizableReadLoop","detail":"{readStateIndex:1452; appliedIndex:1448; }","duration":"183.488967ms","start":"2026-07-17T05:06:58.9995Z","end":"2026-07-17T05:06:59.182989Z","steps":["trace[307925724] 'read index received' (duration: 177.62408ms)","trace[307925724] 'applied index is now lower than readState.Index' (duration: 5.863137ms)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:06:59.183164Z","caller":"traceutil/trace.go:171","msg":"trace[548025005] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"183.527268ms","start":"2026-07-17T05:06:58.999585Z","end":"2026-07-17T05:06:59.183112Z","steps":["trace[548025005] 'process raft request' (duration: 183.2625ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:59.183157Z","caller":"traceutil/trace.go:171","msg":"trace[1688998032] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"184.166208ms","start":"2026-07-17T05:06:58.998924Z","end":"2026-07-17T05:06:59.183091Z","steps":["trace[1688998032] 'process raft request' (duration: 183.622701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:06:59.183497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.989693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:90218"} {"level":"warn","ts":"2026-07-17T05:06:59.183319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.918557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:06:59.18355Z","caller":"traceutil/trace.go:171","msg":"trace[2001817215] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1397; }","duration":"184.054625ms","start":"2026-07-17T05:06:58.999477Z","end":"2026-07-17T05:06:59.183532Z","steps":["trace[2001817215] 'agreement among raft nodes before linearized reading' (duration: 183.907441ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:59.183566Z","caller":"traceutil/trace.go:171","msg":"trace[568443468] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1397; }","duration":"182.190666ms","start":"2026-07-17T05:06:59.001363Z","end":"2026-07-17T05:06:59.183554Z","steps":["trace[568443468] 'agreement among raft nodes before linearized reading' (duration: 181.914957ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:06:59.183367Z","caller":"traceutil/trace.go:171","msg":"trace[110059206] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"183.719275ms","start":"2026-07-17T05:06:58.999637Z","end":"2026-07-17T05:06:59.183356Z","steps":["trace[110059206] 'process raft request' (duration: 183.26964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:11:09.670382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.144309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-17T05:11:09.670568Z","caller":"traceutil/trace.go:171","msg":"trace[110797165] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:1; response_revision:2569; }","duration":"132.381627ms","start":"2026-07-17T05:11:09.538163Z","end":"2026-07-17T05:11:09.670545Z","steps":["trace[110797165] 'range keys from in-memory index tree' (duration: 131.929243ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:11:09.95054Z","caller":"traceutil/trace.go:171","msg":"trace[764420665] linearizableReadLoop","detail":"{readStateIndex:2678; appliedIndex:2677; }","duration":"158.245328ms","start":"2026-07-17T05:11:09.792268Z","end":"2026-07-17T05:11:09.950514Z","steps":["trace[764420665] 'read index received' (duration: 158.099413ms)","trace[764420665] 'applied index is now lower than readState.Index' (duration: 144.525µs)"],"step_count":2} {"level":"warn","ts":"2026-07-17T05:11:09.951164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.882218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:11:09.952439Z","caller":"traceutil/trace.go:171","msg":"trace[283903197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2569; }","duration":"160.20064ms","start":"2026-07-17T05:11:09.792211Z","end":"2026-07-17T05:11:09.952412Z","steps":["trace[283903197] 'agreement among raft nodes before linearized reading' (duration: 158.440424ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:11:10.085644Z","caller":"traceutil/trace.go:171","msg":"trace[237030084] linearizableReadLoop","detail":"{readStateIndex:2679; appliedIndex:2678; }","duration":"135.015789ms","start":"2026-07-17T05:11:09.95061Z","end":"2026-07-17T05:11:10.085626Z","steps":["trace[237030084] 'read index received' (duration: 134.869655ms)","trace[237030084] 'applied index is now lower than readState.Index' (duration: 145.524µs)"],"step_count":2} {"level":"info","ts":"2026-07-17T05:11:10.085722Z","caller":"traceutil/trace.go:171","msg":"trace[157767153] transaction","detail":"{read_only:false; response_revision:2570; number_of_response:1; }","duration":"282.699561ms","start":"2026-07-17T05:11:09.802994Z","end":"2026-07-17T05:11:10.085693Z","steps":["trace[157767153] 'process raft request' (duration: 282.496125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:11:10.085777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.260399ms","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-17T05:11:10.085811Z","caller":"traceutil/trace.go:171","msg":"trace[865083202] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:2570; }","duration":"204.340592ms","start":"2026-07-17T05:11:09.88146Z","end":"2026-07-17T05:11:10.085801Z","steps":["trace[865083202] 'agreement among raft nodes before linearized reading' (duration: 204.248809ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:11:10.085874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.400355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-17T05:11:10.085926Z","caller":"traceutil/trace.go:171","msg":"trace[1991666416] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2570; }","duration":"182.493747ms","start":"2026-07-17T05:11:09.903419Z","end":"2026-07-17T05:11:10.085912Z","steps":["trace[1991666416] 'agreement among raft nodes before linearized reading' (duration: 182.289381ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:11:10.086048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.811998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-07-17T05:11:10.08606Z","caller":"traceutil/trace.go:171","msg":"trace[1950862922] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2570; }","duration":"159.856879ms","start":"2026-07-17T05:11:09.926199Z","end":"2026-07-17T05:11:10.086056Z","steps":["trace[1950862922] 'agreement among raft nodes before linearized reading' (duration: 159.809338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-17T05:11:10.086763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.073378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-17T05:11:10.08711Z","caller":"traceutil/trace.go:171","msg":"trace[1626934166] transaction","detail":"{read_only:false; response_revision:2571; number_of_response:1; }","duration":"135.34833ms","start":"2026-07-17T05:11:09.951608Z","end":"2026-07-17T05:11:10.086957Z","steps":["trace[1626934166] 'process raft request' (duration: 135.001149ms)"],"step_count":1} {"level":"info","ts":"2026-07-17T05:11:10.086811Z","caller":"traceutil/trace.go:171","msg":"trace[1643831618] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2571; }","duration":"133.13985ms","start":"2026-07-17T05:11:09.953659Z","end":"2026-07-17T05:11:10.086799Z","steps":["trace[1643831618] 'agreement among raft nodes before linearized reading' (duration: 133.023806ms)"],"step_count":1}