{"level":"warn","ts":"2026-05-21T13:11:00.668328Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T13:11:00.668456Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153: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.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-21T13:11:00.668701Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T13:11:00.668723Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-05-21T13:11:00.668792Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T13:11:00.671007Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-05-21T13:11:00.671175Z","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.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-21T13:11:00.676084Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.59958ms"} {"level":"info","ts":"2026-05-21T13:11:00.68704Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-05-21T13:11:00.68724Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T13:11:00.687275Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-05-21T13:11:00.687342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T13:11:00.68736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-05-21T13:11:00.687461Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-05-21T13:11:00.702118Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T13:11:00.705624Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T13:11:00.712872Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-21T13:11:00.716424Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T13:11:00.716789Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T13:11:00.717035Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T13:11:00.717101Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T13:11:00.720215Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-21T13:11:00.721031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-05-21T13:11:00.721282Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-05-21T13:11:00.722548Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-21T13:11:00.722676Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-05-21T13:11:00.722725Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-05-21T13:11:00.723035Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T13:11:00.723136Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T13:11:00.888703Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T13:11:00.888776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T13:11:00.888819Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-05-21T13:11:00.888838Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-05-21T13:11:00.888846Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-05-21T13:11:00.888868Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-05-21T13:11:00.888879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-05-21T13:11:00.890001Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T13:11:00.890173Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T13:11:00.890284Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T13:11:00.890201Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T13:11:00.891164Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T13:11:00.891234Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T13:11:00.891286Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T13:11:00.891936Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-05-21T13:11:00.893539Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T13:11:00.895639Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T13:11:00.895661Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-05-21T13:11:06.47858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.619425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T13:11:06.478781Z","caller":"traceutil/trace.go:171","msg":"trace[28993713] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"332.711566ms","start":"2026-05-21T13:11:06.146044Z","end":"2026-05-21T13:11:06.478756Z","steps":["trace[28993713] 'process raft request' (duration: 25.326587ms)","trace[28993713] 'compare' (duration: 306.470014ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:11:06.47896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:06.145994Z","time spent":"332.921488ms","remote":"127.0.0.1:53288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3200,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:06.489674Z","caller":"traceutil/trace.go:171","msg":"trace[1793514416] transaction","detail":"{read_only:false; number_of_response:0; response_revision:233; }","duration":"319.057482ms","start":"2026-05-21T13:11:06.170596Z","end":"2026-05-21T13:11:06.489654Z","steps":["trace[1793514416] 'process raft request' (duration: 319.007012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:06.489796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:06.170563Z","time spent":"319.200533ms","remote":"127.0.0.1:52990","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:06.50923Z","caller":"traceutil/trace.go:171","msg":"trace[520889100] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"336.605291ms","start":"2026-05-21T13:11:06.172605Z","end":"2026-05-21T13:11:06.50921Z","steps":["trace[520889100] 'process raft request' (duration: 336.245299ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:06.50927Z","caller":"traceutil/trace.go:171","msg":"trace[473337682] linearizableReadLoop","detail":"{readStateIndex:246; appliedIndex:240; }","duration":"307.707272ms","start":"2026-05-21T13:11:06.201528Z","end":"2026-05-21T13:11:06.509235Z","steps":["trace[473337682] 'read index received' (duration: 288.166681ms)","trace[473337682] 'applied index is now lower than readState.Index' (duration: 19.539461ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:11:06.509366Z","caller":"traceutil/trace.go:171","msg":"trace[1714605521] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"318.202526ms","start":"2026-05-21T13:11:06.19114Z","end":"2026-05-21T13:11:06.509343Z","steps":["trace[1714605521] 'process raft request' (duration: 318.015955ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:06.50931Z","caller":"traceutil/trace.go:171","msg":"trace[2101678079] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"318.350688ms","start":"2026-05-21T13:11:06.190921Z","end":"2026-05-21T13:11:06.509272Z","steps":["trace[2101678079] 'process raft request' (duration: 318.197767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:06.50949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:06.191126Z","time spent":"318.320037ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:06.509524Z","caller":"traceutil/trace.go:171","msg":"trace[1406198040] transaction","detail":"{read_only:false; number_of_response:0; response_revision:235; }","duration":"318.91062ms","start":"2026-05-21T13:11:06.1906Z","end":"2026-05-21T13:11:06.509511Z","steps":["trace[1406198040] 'process raft request' (duration: 318.478198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:06.509622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.792717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:11:06.509596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.040064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:11:06.509623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:06.190911Z","time spent":"318.618289ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:06.509625Z","caller":"traceutil/trace.go:171","msg":"trace[1219210966] transaction","detail":"{read_only:false; number_of_response:0; response_revision:237; }","duration":"318.401068ms","start":"2026-05-21T13:11:06.191182Z","end":"2026-05-21T13:11:06.509583Z","steps":["trace[1219210966] 'process raft request' (duration: 318.022906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:06.509649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:06.190589Z","time spent":"318.985912ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:06.509694Z","caller":"traceutil/trace.go:171","msg":"trace[2144017295] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:237; }","duration":"308.173135ms","start":"2026-05-21T13:11:06.201492Z","end":"2026-05-21T13:11:06.509665Z","steps":["trace[2144017295] 'agreement among raft nodes before linearized reading' (duration: 307.973293ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:06.509396Z","caller":"traceutil/trace.go:171","msg":"trace[476740173] transaction","detail":"{read_only:false; response_revision:235; number_of_response:1; }","duration":"320.115449ms","start":"2026-05-21T13:11:06.189265Z","end":"2026-05-21T13:11:06.50938Z","steps":["trace[476740173] 'process raft request' (duration: 319.761027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:06.50974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:06.201481Z","time spent":"308.250017ms","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T13:11:06.50974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:06.191172Z","time spent":"318.519449ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:06.509657Z","caller":"traceutil/trace.go:171","msg":"trace[1524758801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:237; }","duration":"264.857308ms","start":"2026-05-21T13:11:06.244789Z","end":"2026-05-21T13:11:06.509646Z","steps":["trace[1524758801] 'agreement among raft nodes before linearized reading' (duration: 264.796157ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:06.50979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:06.189235Z","time spent":"320.510782ms","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T13:11:06.510039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:06.172576Z","time spent":"336.716341ms","remote":"127.0.0.1:52900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T13:11:08.977397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.503797807s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:5"} {"level":"info","ts":"2026-05-21T13:11:08.977504Z","caller":"traceutil/trace.go:171","msg":"trace[1486317533] linearizableReadLoop","detail":"{readStateIndex:279; appliedIndex:278; }","duration":"1.848700156s","start":"2026-05-21T13:11:07.128786Z","end":"2026-05-21T13:11:08.977487Z","steps":["trace[1486317533] 'read index received' (duration: 344.712119ms)","trace[1486317533] 'applied index is now lower than readState.Index' (duration: 1.503986947s)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:11:08.977595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.848800991s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:11:08.977594Z","caller":"traceutil/trace.go:171","msg":"trace[867057228] transaction","detail":"{read_only:false; number_of_response:0; response_revision:269; }","duration":"1.872054472s","start":"2026-05-21T13:11:07.105461Z","end":"2026-05-21T13:11:08.977515Z","steps":["trace[867057228] 'process raft request' (duration: 368.056754ms)","trace[867057228] 'compare' (duration: 1.503740545s)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:11:08.977629Z","caller":"traceutil/trace.go:171","msg":"trace[701797522] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:0; response_revision:269; }","duration":"1.848872523s","start":"2026-05-21T13:11:07.128747Z","end":"2026-05-21T13:11:08.977619Z","steps":["trace[701797522] 'agreement among raft nodes before linearized reading' (duration: 1.84880979s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:08.977677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:07.128731Z","time spent":"1.848938886s","remote":"127.0.0.1:53010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-05-21T13:11:08.977725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:07.105435Z","time spent":"1.872233891s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T13:11:09.478305Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440211294736750,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T13:11:09.979084Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440211294736750,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T13:11:10.251866Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.77833503s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T13:11:10.252345Z","caller":"traceutil/trace.go:171","msg":"trace[1201459923] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:279; }","duration":"1.274785885s","start":"2026-05-21T13:11:08.97753Z","end":"2026-05-21T13:11:10.252316Z","steps":["trace[1201459923] 'read index received' (duration: 1.274540012s)","trace[1201459923] 'applied index is now lower than readState.Index' (duration: 244.963µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:11:10.252581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274010585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5164"} {"level":"info","ts":"2026-05-21T13:11:10.25263Z","caller":"traceutil/trace.go:171","msg":"trace[1685275999] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:270; }","duration":"1.27411047s","start":"2026-05-21T13:11:08.978506Z","end":"2026-05-21T13:11:10.252617Z","steps":["trace[1685275999] 'agreement among raft nodes before linearized reading' (duration: 1.274015345s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:10.252673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.271862075s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-05-21T13:11:10.252675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:08.978488Z","time spent":"1.274178574s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5187,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-05-21T13:11:10.252675Z","caller":"traceutil/trace.go:171","msg":"trace[200229431] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"3.111594604s","start":"2026-05-21T13:11:07.141047Z","end":"2026-05-21T13:11:10.252641Z","steps":["trace[200229431] 'process raft request' (duration: 3.11111479s)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:10.252764Z","caller":"traceutil/trace.go:171","msg":"trace[145400412] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:270; }","duration":"1.27194936s","start":"2026-05-21T13:11:08.980801Z","end":"2026-05-21T13:11:10.252751Z","steps":["trace[145400412] 'agreement among raft nodes before linearized reading' (duration: 1.271842114s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:10.252676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.90693ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:11:10.252841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.671702447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T13:11:10.252928Z","caller":"traceutil/trace.go:171","msg":"trace[1009698708] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:270; }","duration":"1.671924918s","start":"2026-05-21T13:11:08.580987Z","end":"2026-05-21T13:11:10.252912Z","steps":["trace[1009698708] 'agreement among raft nodes before linearized reading' (duration: 1.671600002s)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:10.252859Z","caller":"traceutil/trace.go:171","msg":"trace[1352207624] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:270; }","duration":"241.10589ms","start":"2026-05-21T13:11:10.011725Z","end":"2026-05-21T13:11:10.252831Z","steps":["trace[1352207624] 'agreement among raft nodes before linearized reading' (duration: 240.847017ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:10.252998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:08.58095Z","time spent":"1.672036233s","remote":"127.0.0.1:53060","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-05-21T13:11:10.253049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:07.141027Z","time spent":"3.111741891s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T13:11:10.252792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:08.980784Z","time spent":"1.272003192s","remote":"127.0.0.1:52926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-05-21T13:11:10.253079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.436644563s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T13:11:10.253381Z","caller":"traceutil/trace.go:171","msg":"trace[716232850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:270; }","duration":"1.436996511s","start":"2026-05-21T13:11:08.816368Z","end":"2026-05-21T13:11:10.253365Z","steps":["trace[716232850] 'agreement among raft nodes before linearized reading' (duration: 1.436087016s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:10.253451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:08.816349Z","time spent":"1.437091494s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T13:11:10.252561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.684658943s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T13:11:10.2541Z","caller":"traceutil/trace.go:171","msg":"trace[2088740858] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:270; }","duration":"1.686199748s","start":"2026-05-21T13:11:08.567849Z","end":"2026-05-21T13:11:10.254048Z","steps":["trace[2088740858] 'agreement among raft nodes before linearized reading' (duration: 1.684634932s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:10.254225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:08.567833Z","time spent":"1.686331995s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T13:11:10.254322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.407473887s","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-05-21T13:11:10.255247Z","caller":"traceutil/trace.go:171","msg":"trace[378987725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:270; }","duration":"1.40855502s","start":"2026-05-21T13:11:08.845855Z","end":"2026-05-21T13:11:10.25441Z","steps":["trace[378987725] 'agreement among raft nodes before linearized reading' (duration: 1.406525281s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:10.255334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:08.845844Z","time spent":"1.409474226s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T13:11:10.757965Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440211294736752,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T13:11:11.623183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.64396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-21T13:11:11.623403Z","caller":"traceutil/trace.go:171","msg":"trace[1826406779] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"1.366358357s","start":"2026-05-21T13:11:10.25698Z","end":"2026-05-21T13:11:11.623338Z","steps":["trace[1826406779] 'process raft request' (duration: 853.457416ms)","trace[1826406779] 'compare' (duration: 512.392617ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:11:11.623501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:10.256962Z","time spent":"1.366505725s","remote":"127.0.0.1:53010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":180,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:11.625535Z","caller":"traceutil/trace.go:171","msg":"trace[1258825194] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"1.367741815s","start":"2026-05-21T13:11:10.257756Z","end":"2026-05-21T13:11:11.625498Z","steps":["trace[1258825194] 'process raft request' (duration: 1.367466242s)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:11.625569Z","caller":"traceutil/trace.go:171","msg":"trace[1815909077] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"1.366151466s","start":"2026-05-21T13:11:10.259388Z","end":"2026-05-21T13:11:11.62554Z","steps":["trace[1815909077] 'process raft request' (duration: 1.365979208s)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:11.625572Z","caller":"traceutil/trace.go:171","msg":"trace[1447832960] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"1.366107794s","start":"2026-05-21T13:11:10.259439Z","end":"2026-05-21T13:11:11.625547Z","steps":["trace[1447832960] 'process raft request' (duration: 1.366003799s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:11.625696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.368491732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-05-21T13:11:11.625724Z","caller":"traceutil/trace.go:171","msg":"trace[1016161021] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:275; }","duration":"1.368552724s","start":"2026-05-21T13:11:10.257164Z","end":"2026-05-21T13:11:11.625717Z","steps":["trace[1016161021] 'agreement among raft nodes before linearized reading' (duration: 1.36846678s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:11.625747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:10.257148Z","time spent":"1.368592867s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":3489,"request content":"key:\"/registry/pods/kube-system/kube-vip-instance\" "} {"level":"warn","ts":"2026-05-21T13:11:11.625726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:10.257738Z","time spent":"1.367877992s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T13:11:11.625733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:10.25938Z","time spent":"1.366260252s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T13:11:11.625747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:10.259427Z","time spent":"1.366228389s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:11:11.625603Z","caller":"traceutil/trace.go:171","msg":"trace[1638796900] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:280; }","duration":"1.368396767s","start":"2026-05-21T13:11:10.257192Z","end":"2026-05-21T13:11:11.625588Z","steps":["trace[1638796900] 'read index received' (duration: 853.405533ms)","trace[1638796900] 'applied index is now lower than readState.Index' (duration: 514.990584ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:11:11.625912Z","caller":"traceutil/trace.go:171","msg":"trace[712767163] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"1.364888843s","start":"2026-05-21T13:11:10.261007Z","end":"2026-05-21T13:11:11.625895Z","steps":["trace[712767163] 'process raft request' (duration: 1.364502984s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:11.625992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:10.261001Z","time spent":"1.364951986s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:11:12.073189Z","caller":"traceutil/trace.go:171","msg":"trace[429466134] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"124.848264ms","start":"2026-05-21T13:11:11.948308Z","end":"2026-05-21T13:11:12.073156Z","steps":["trace[429466134] 'process raft request' (duration: 124.614253ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:13.30805Z","caller":"traceutil/trace.go:171","msg":"trace[403096431] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:314; }","duration":"130.512756ms","start":"2026-05-21T13:11:13.177518Z","end":"2026-05-21T13:11:13.308031Z","steps":["trace[403096431] 'read index received' (duration: 130.352328ms)","trace[403096431] 'applied index is now lower than readState.Index' (duration: 159.528µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:11:13.308115Z","caller":"traceutil/trace.go:171","msg":"trace[1031534109] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"145.339331ms","start":"2026-05-21T13:11:13.162745Z","end":"2026-05-21T13:11:13.308084Z","steps":["trace[1031534109] 'process raft request' (duration: 145.171263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:13.308184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.650693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-05-21T13:11:13.308219Z","caller":"traceutil/trace.go:171","msg":"trace[96139066] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:305; }","duration":"130.725556ms","start":"2026-05-21T13:11:13.177484Z","end":"2026-05-21T13:11:13.308209Z","steps":["trace[96139066] 'agreement among raft nodes before linearized reading' (duration: 130.643262ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:25.79169Z","caller":"traceutil/trace.go:171","msg":"trace[1541071439] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"100.107891ms","start":"2026-05-21T13:11:25.691547Z","end":"2026-05-21T13:11:25.791655Z","steps":["trace[1541071439] 'read index received' (duration: 99.883322ms)","trace[1541071439] 'applied index is now lower than readState.Index' (duration: 223.619µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:11:25.79195Z","caller":"traceutil/trace.go:171","msg":"trace[1828639784] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"101.066522ms","start":"2026-05-21T13:11:25.69084Z","end":"2026-05-21T13:11:25.791907Z","steps":["trace[1828639784] 'process raft request' (duration: 100.625513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:25.792283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.691615ms","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-05-21T13:11:25.792344Z","caller":"traceutil/trace.go:171","msg":"trace[2096308726] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:388; }","duration":"100.8175ms","start":"2026-05-21T13:11:25.691508Z","end":"2026-05-21T13:11:25.792326Z","steps":["trace[2096308726] 'agreement among raft nodes before linearized reading' (duration: 100.35208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:26.789548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440211294737031,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T13:11:27.210262Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.410492793s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T13:11:27.213842Z","caller":"traceutil/trace.go:171","msg":"trace[1860982268] linearizableReadLoop","detail":"{readStateIndex:406; appliedIndex:404; }","duration":"969.650625ms","start":"2026-05-21T13:11:26.244129Z","end":"2026-05-21T13:11:27.21378Z","steps":["trace[1860982268] 'read index received' (duration: 966.425685ms)","trace[1860982268] 'applied index is now lower than readState.Index' (duration: 3.112575ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:11:27.214395Z","caller":"traceutil/trace.go:171","msg":"trace[975026051] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"1.415206763s","start":"2026-05-21T13:11:25.799147Z","end":"2026-05-21T13:11:27.214354Z","steps":["trace[975026051] 'process raft request' (duration: 1.41139603s)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:27.214465Z","caller":"traceutil/trace.go:171","msg":"trace[518235377] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"798.742751ms","start":"2026-05-21T13:11:26.415565Z","end":"2026-05-21T13:11:27.214308Z","steps":["trace[518235377] 'process raft request' (duration: 797.988901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:27.214839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:26.415531Z","time spent":"799.215379ms","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T13:11:27.215151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:25.799128Z","time spent":"1.41564608s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T13:11:27.216913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.778921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:11:27.216997Z","caller":"traceutil/trace.go:171","msg":"trace[573170511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:392; }","duration":"972.899876ms","start":"2026-05-21T13:11:26.244076Z","end":"2026-05-21T13:11:27.216976Z","steps":["trace[573170511] 'agreement among raft nodes before linearized reading' (duration: 970.01145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:27.217054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:26.244055Z","time spent":"972.985089ms","remote":"127.0.0.1:52828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T13:11:28.066507Z","caller":"traceutil/trace.go:171","msg":"trace[1616113196] linearizableReadLoop","detail":"{readStateIndex:407; appliedIndex:406; }","duration":"263.154864ms","start":"2026-05-21T13:11:27.803324Z","end":"2026-05-21T13:11:28.066479Z","steps":["trace[1616113196] 'read index received' (duration: 262.952946ms)","trace[1616113196] 'applied index is now lower than readState.Index' (duration: 200.958µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:11:28.066565Z","caller":"traceutil/trace.go:171","msg":"trace[1306350735] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"267.825121ms","start":"2026-05-21T13:11:27.798718Z","end":"2026-05-21T13:11:28.066543Z","steps":["trace[1306350735] 'process raft request' (duration: 267.615323ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:28.066711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.27613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-21T13:11:28.06674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.387154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T13:11:28.066845Z","caller":"traceutil/trace.go:171","msg":"trace[325822120] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:393; }","duration":"263.526139ms","start":"2026-05-21T13:11:27.803297Z","end":"2026-05-21T13:11:28.066823Z","steps":["trace[325822120] 'agreement among raft nodes before linearized reading' (duration: 263.302519ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:28.06677Z","caller":"traceutil/trace.go:171","msg":"trace[1274910661] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:393; }","duration":"263.363212ms","start":"2026-05-21T13:11:27.803392Z","end":"2026-05-21T13:11:28.066755Z","steps":["trace[1274910661] 'agreement among raft nodes before linearized reading' (duration: 263.247468ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:29.451854Z","caller":"traceutil/trace.go:171","msg":"trace[1653712209] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:410; }","duration":"208.44758ms","start":"2026-05-21T13:11:29.243372Z","end":"2026-05-21T13:11:29.451819Z","steps":["trace[1653712209] 'read index received' (duration: 34.404101ms)","trace[1653712209] 'applied index is now lower than readState.Index' (duration: 174.041689ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:11:29.451996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.597176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:11:29.452052Z","caller":"traceutil/trace.go:171","msg":"trace[903512321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:396; }","duration":"208.693379ms","start":"2026-05-21T13:11:29.243339Z","end":"2026-05-21T13:11:29.452033Z","steps":["trace[903512321] 'agreement among raft nodes before linearized reading' (duration: 208.586315ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:43.134908Z","caller":"traceutil/trace.go:171","msg":"trace[204188227] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"421.689541ms","start":"2026-05-21T13:11:42.713194Z","end":"2026-05-21T13:11:43.134884Z","steps":["trace[204188227] 'process raft request' (duration: 421.37626ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:43.135045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:42.713166Z","time spent":"421.814915ms","remote":"127.0.0.1:52918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:45.308087Z","caller":"traceutil/trace.go:171","msg":"trace[1089961425] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"523.831167ms","start":"2026-05-21T13:11:44.784221Z","end":"2026-05-21T13:11:45.308053Z","steps":["trace[1089961425] 'process raft request' (duration: 523.675202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:45.308383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:44.784194Z","time spent":"523.995932ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:45.321973Z","caller":"traceutil/trace.go:171","msg":"trace[278109439] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"534.130445ms","start":"2026-05-21T13:11:44.787824Z","end":"2026-05-21T13:11:45.321954Z","steps":["trace[278109439] 'process raft request' (duration: 533.853346ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:45.322053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:44.787804Z","time spent":"534.207518ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:45.322222Z","caller":"traceutil/trace.go:171","msg":"trace[884727625] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"533.549305ms","start":"2026-05-21T13:11:44.788663Z","end":"2026-05-21T13:11:45.322212Z","steps":["trace[884727625] 'process raft request' (duration: 533.268416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:45.322272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:44.788649Z","time spent":"533.600567ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:45.322314Z","caller":"traceutil/trace.go:171","msg":"trace[1466996894] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"524.737382ms","start":"2026-05-21T13:11:44.797533Z","end":"2026-05-21T13:11:45.322271Z","steps":["trace[1466996894] 'process raft request' (duration: 524.594558ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:45.322372Z","caller":"traceutil/trace.go:171","msg":"trace[545066739] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"524.584257ms","start":"2026-05-21T13:11:44.797775Z","end":"2026-05-21T13:11:45.32236Z","steps":["trace[545066739] 'process raft request' (duration: 524.452453ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:45.322373Z","caller":"traceutil/trace.go:171","msg":"trace[1199798425] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"524.754383ms","start":"2026-05-21T13:11:44.797589Z","end":"2026-05-21T13:11:45.322343Z","steps":["trace[1199798425] 'process raft request' (duration: 524.605058ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:45.322507Z","caller":"traceutil/trace.go:171","msg":"trace[1864553858] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"518.540597ms","start":"2026-05-21T13:11:44.803954Z","end":"2026-05-21T13:11:45.322495Z","steps":["trace[1864553858] 'process raft request' (duration: 518.322819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:45.322542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:44.803945Z","time spent":"518.581318ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:45.322546Z","caller":"traceutil/trace.go:171","msg":"trace[1403724343] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"513.820359ms","start":"2026-05-21T13:11:44.808688Z","end":"2026-05-21T13:11:45.322509Z","steps":["trace[1403724343] 'process raft request' (duration: 513.643343ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:45.322661Z","caller":"traceutil/trace.go:171","msg":"trace[864087033] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"510.985485ms","start":"2026-05-21T13:11:44.811667Z","end":"2026-05-21T13:11:45.322652Z","steps":["trace[864087033] 'process raft request' (duration: 510.761447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:45.322676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:44.808679Z","time spent":"513.923133ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T13:11:45.322701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:44.811659Z","time spent":"511.024816ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:45.322728Z","caller":"traceutil/trace.go:171","msg":"trace[86891614] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"491.227349ms","start":"2026-05-21T13:11:44.831488Z","end":"2026-05-21T13:11:45.322716Z","steps":["trace[86891614] 'process raft request' (duration: 490.999981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:45.322778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:44.831455Z","time spent":"491.303021ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T13:11:45.322415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:44.797768Z","time spent":"524.626348ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T13:11:45.322686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:44.797583Z","time spent":"524.927868ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:45.322892Z","caller":"traceutil/trace.go:171","msg":"trace[733980607] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"470.452449ms","start":"2026-05-21T13:11:44.85243Z","end":"2026-05-21T13:11:45.322882Z","steps":["trace[733980607] 'process raft request' (duration: 470.128279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:45.322456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:44.79752Z","time spent":"524.871176ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T13:11:45.322932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:44.852412Z","time spent":"470.50209ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:45.323047Z","caller":"traceutil/trace.go:171","msg":"trace[1829915547] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"373.727974ms","start":"2026-05-21T13:11:44.949311Z","end":"2026-05-21T13:11:45.323039Z","steps":["trace[1829915547] 'process raft request' (duration: 373.377073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:45.323093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:44.949275Z","time spent":"373.797756ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:52.105331Z","caller":"traceutil/trace.go:171","msg":"trace[895724348] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"380.931341ms","start":"2026-05-21T13:11:51.724374Z","end":"2026-05-21T13:11:52.105305Z","steps":["trace[895724348] 'process raft request' (duration: 380.24549ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:52.105507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:51.724315Z","time spent":"381.105675ms","remote":"127.0.0.1:52918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T13:11:52.688438Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440211294737431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T13:11:53.189017Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432440211294737431,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T13:11:53.354697Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.1830448s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-21T13:11:53.382669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.018157ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:11:53.382795Z","caller":"traceutil/trace.go:171","msg":"trace[1784453351] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:605; }","duration":"355.151111ms","start":"2026-05-21T13:11:53.027624Z","end":"2026-05-21T13:11:53.382775Z","steps":["trace[1784453351] 'range keys from in-memory index tree' (duration: 355.005327ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:11:53.382814Z","caller":"traceutil/trace.go:171","msg":"trace[1575999113] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"1.19513847s","start":"2026-05-21T13:11:52.187643Z","end":"2026-05-21T13:11:53.382782Z","steps":["trace[1575999113] 'read index received' (duration: 1.167214186s)","trace[1575999113] 'applied index is now lower than readState.Index' (duration: 27.923124ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:11:53.38289Z","caller":"traceutil/trace.go:171","msg":"trace[1779551266] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"1.211304534s","start":"2026-05-21T13:11:52.171535Z","end":"2026-05-21T13:11:53.38284Z","steps":["trace[1779551266] 'process raft request' (duration: 1.183306578s)","trace[1779551266] 'compare' (duration: 27.4629ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:11:53.383096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19543433s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-05-21T13:11:53.383103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:52.171514Z","time spent":"1.21150435s","remote":"127.0.0.1:53036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:53.383139Z","caller":"traceutil/trace.go:171","msg":"trace[423770155] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:606; }","duration":"1.195517752s","start":"2026-05-21T13:11:52.187606Z","end":"2026-05-21T13:11:53.383124Z","steps":["trace[423770155] 'agreement among raft nodes before linearized reading' (duration: 1.195317515s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:53.383177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:52.187591Z","time spent":"1.195578314s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T13:11:53.409022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.195909566s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-21T13:11:53.409084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.198808255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hld2z\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-21T13:11:53.409145Z","caller":"traceutil/trace.go:171","msg":"trace[1993291456] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hld2z; range_end:; response_count:1; response_revision:607; }","duration":"1.198922328s","start":"2026-05-21T13:11:52.210208Z","end":"2026-05-21T13:11:53.40913Z","steps":["trace[1993291456] 'agreement among raft nodes before linearized reading' (duration: 1.198755333s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:53.40919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:52.210195Z","time spent":"1.198987379s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-hld2z\" "} {"level":"info","ts":"2026-05-21T13:11:53.409096Z","caller":"traceutil/trace.go:171","msg":"trace[1702163171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"1.196003568s","start":"2026-05-21T13:11:52.213074Z","end":"2026-05-21T13:11:53.409078Z","steps":["trace[1702163171] 'agreement among raft nodes before linearized reading' (duration: 1.195855164s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:53.409379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:52.213065Z","time spent":"1.196260617s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T13:11:53.409544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.165279981s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:11:53.409644Z","caller":"traceutil/trace.go:171","msg":"trace[426769360] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"1.165399154s","start":"2026-05-21T13:11:52.244235Z","end":"2026-05-21T13:11:53.409634Z","steps":["trace[426769360] 'agreement among raft nodes before linearized reading' (duration: 1.165284511s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:53.409585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.1993382s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-21T13:11:53.409783Z","caller":"traceutil/trace.go:171","msg":"trace[377865926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"1.199547257s","start":"2026-05-21T13:11:52.210214Z","end":"2026-05-21T13:11:53.409761Z","steps":["trace[377865926] 'agreement among raft nodes before linearized reading' (duration: 1.19933442s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:53.409818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:52.210203Z","time spent":"1.199605698s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-21T13:11:53.409747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:52.244225Z","time spent":"1.165514057s","remote":"127.0.0.1:52828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T13:11:54.518601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.777993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T13:11:54.518785Z","caller":"traceutil/trace.go:171","msg":"trace[704100658] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"1.105176937s","start":"2026-05-21T13:11:53.413589Z","end":"2026-05-21T13:11:54.518766Z","steps":["trace[704100658] 'process raft request' (duration: 143.134356ms)","trace[704100658] 'compare' (duration: 961.577148ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:11:54.518855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:53.413572Z","time spent":"1.10525304s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:11:54.519351Z","caller":"traceutil/trace.go:171","msg":"trace[582499310] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.105631281s","start":"2026-05-21T13:11:53.413701Z","end":"2026-05-21T13:11:54.519332Z","steps":["trace[582499310] 'process raft request' (duration: 1.104994762s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:54.519465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:53.413693Z","time spent":"1.105707043s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:11:54.519713Z","caller":"traceutil/trace.go:171","msg":"trace[1351734709] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"1.096526685s","start":"2026-05-21T13:11:53.423179Z","end":"2026-05-21T13:11:54.519706Z","steps":["trace[1351734709] 'process raft request' (duration: 1.095901357s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:54.519753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:53.423155Z","time spent":"1.096576606s","remote":"127.0.0.1:52996","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:11:54.519936Z","caller":"traceutil/trace.go:171","msg":"trace[50001672] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:631; }","duration":"1.07453881s","start":"2026-05-21T13:11:53.445141Z","end":"2026-05-21T13:11:54.51968Z","steps":["trace[50001672] 'read index received' (duration: 111.665395ms)","trace[50001672] 'applied index is now lower than readState.Index' (duration: 962.872555ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:11:54.520098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.522207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.153\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-21T13:11:54.520127Z","caller":"traceutil/trace.go:171","msg":"trace[819086702] range","detail":"{range_begin:/registry/masterleases/199.204.45.153; range_end:; response_count:1; response_revision:611; }","duration":"309.551697ms","start":"2026-05-21T13:11:54.210567Z","end":"2026-05-21T13:11:54.520119Z","steps":["trace[819086702] 'agreement among raft nodes before linearized reading' (duration: 309.496146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:54.520094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.071736885s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-05-21T13:11:54.520175Z","caller":"traceutil/trace.go:171","msg":"trace[1870448669] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:611; }","duration":"1.071853798s","start":"2026-05-21T13:11:53.448298Z","end":"2026-05-21T13:11:54.520152Z","steps":["trace[1870448669] 'agreement among raft nodes before linearized reading' (duration: 1.071727075s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:54.520209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:54.210469Z","time spent":"309.676901ms","remote":"127.0.0.1:52852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.153\" "} {"level":"warn","ts":"2026-05-21T13:11:54.520232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:53.44826Z","time spent":"1.071959632s","remote":"127.0.0.1:53010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-21T13:11:54.52041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057034922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T13:11:54.52046Z","caller":"traceutil/trace.go:171","msg":"trace[2095355171] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:611; }","duration":"1.057121845s","start":"2026-05-21T13:11:53.463325Z","end":"2026-05-21T13:11:54.520447Z","steps":["trace[2095355171] 'agreement among raft nodes before linearized reading' (duration: 1.056941439s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:54.520498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:53.463313Z","time spent":"1.057177376s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T13:11:54.520696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.108818756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:11:54.520732Z","caller":"traceutil/trace.go:171","msg":"trace[594280093] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:611; }","duration":"1.10959996s","start":"2026-05-21T13:11:53.411123Z","end":"2026-05-21T13:11:54.520723Z","steps":["trace[594280093] 'agreement among raft nodes before linearized reading' (duration: 1.108811436s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:54.520826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:53.411111Z","time spent":"1.109637451s","remote":"127.0.0.1:52816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T13:11:55.945866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.465859ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-21T13:11:55.946138Z","caller":"traceutil/trace.go:171","msg":"trace[162340598] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"1.418156859s","start":"2026-05-21T13:11:54.527941Z","end":"2026-05-21T13:11:55.946097Z","steps":["trace[162340598] 'read index received' (duration: 449.451043ms)","trace[162340598] 'applied index is now lower than readState.Index' (duration: 968.704996ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:11:55.946246Z","caller":"traceutil/trace.go:171","msg":"trace[1009617182] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"1.420602924s","start":"2026-05-21T13:11:54.525634Z","end":"2026-05-21T13:11:55.946236Z","steps":["trace[1009617182] 'process raft request' (duration: 1.420339836s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:55.946357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:54.525619Z","time spent":"1.420668366s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T13:11:55.946573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418124279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:11:55.946597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:54.521289Z","time spent":"1.425302214s","remote":"127.0.0.1:52852","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-21T13:11:55.946645Z","caller":"traceutil/trace.go:171","msg":"trace[807324988] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:612; }","duration":"1.418211672s","start":"2026-05-21T13:11:54.528414Z","end":"2026-05-21T13:11:55.946625Z","steps":["trace[807324988] 'agreement among raft nodes before linearized reading' (duration: 1.418098778s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:55.946706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:54.528409Z","time spent":"1.418285654s","remote":"127.0.0.1:52828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T13:11:55.946786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.138348ms","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-05-21T13:11:55.946866Z","caller":"traceutil/trace.go:171","msg":"trace[3361704] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:612; }","duration":"497.268192ms","start":"2026-05-21T13:11:55.449579Z","end":"2026-05-21T13:11:55.946847Z","steps":["trace[3361704] 'agreement among raft nodes before linearized reading' (duration: 497.025095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:55.946902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:55.449559Z","time spent":"497.336575ms","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-21T13:11:55.946628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418656605s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:11:55.94703Z","caller":"traceutil/trace.go:171","msg":"trace[2052163597] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:612; }","duration":"1.419083398s","start":"2026-05-21T13:11:54.527921Z","end":"2026-05-21T13:11:55.947005Z","steps":["trace[2052163597] 'agreement among raft nodes before linearized reading' (duration: 1.418609444s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:55.947102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:54.527914Z","time spent":"1.41917101s","remote":"127.0.0.1:52952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-21T13:11:57.777324Z","caller":"traceutil/trace.go:171","msg":"trace[1166105504] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"371.568491ms","start":"2026-05-21T13:11:57.405732Z","end":"2026-05-21T13:11:57.777301Z","steps":["trace[1166105504] 'process raft request' (duration: 369.80624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:11:57.777465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:11:57.40571Z","time spent":"371.685274ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:11:57.781194Z","caller":"traceutil/trace.go:171","msg":"trace[1813381694] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"172.721711ms","start":"2026-05-21T13:11:57.608454Z","end":"2026-05-21T13:11:57.781176Z","steps":["trace[1813381694] 'process raft request' (duration: 172.596976ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:00.534852Z","caller":"traceutil/trace.go:171","msg":"trace[205078029] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"123.486917ms","start":"2026-05-21T13:12:00.411337Z","end":"2026-05-21T13:12:00.534824Z","steps":["trace[205078029] 'process raft request' (duration: 123.326262ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:01.572008Z","caller":"traceutil/trace.go:171","msg":"trace[293433684] linearizableReadLoop","detail":"{readStateIndex:722; appliedIndex:721; }","duration":"167.160521ms","start":"2026-05-21T13:12:01.404801Z","end":"2026-05-21T13:12:01.571962Z","steps":["trace[293433684] 'read index received' (duration: 166.591544ms)","trace[293433684] 'applied index is now lower than readState.Index' (duration: 567.257µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:12:01.572717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.889591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-05-21T13:12:01.572832Z","caller":"traceutil/trace.go:171","msg":"trace[479701710] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:697; }","duration":"168.033135ms","start":"2026-05-21T13:12:01.404751Z","end":"2026-05-21T13:12:01.572785Z","steps":["trace[479701710] 'agreement among raft nodes before linearized reading' (duration: 167.368346ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:01.575653Z","caller":"traceutil/trace.go:171","msg":"trace[855614444] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"250.506664ms","start":"2026-05-21T13:12:01.325114Z","end":"2026-05-21T13:12:01.575621Z","steps":["trace[855614444] 'process raft request' (duration: 246.385209ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:02.033133Z","caller":"traceutil/trace.go:171","msg":"trace[1881900240] linearizableReadLoop","detail":"{readStateIndex:723; appliedIndex:722; }","duration":"228.697788ms","start":"2026-05-21T13:12:01.804414Z","end":"2026-05-21T13:12:02.033112Z","steps":["trace[1881900240] 'read index received' (duration: 228.446341ms)","trace[1881900240] 'applied index is now lower than readState.Index' (duration: 250.577µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:12:02.033191Z","caller":"traceutil/trace.go:171","msg":"trace[755476420] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"375.965053ms","start":"2026-05-21T13:12:01.657198Z","end":"2026-05-21T13:12:02.033163Z","steps":["trace[755476420] 'process raft request' (duration: 375.697786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:02.033448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:01.657171Z","time spent":"376.081797ms","remote":"127.0.0.1:52878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T13:12:02.033469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.035027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"info","ts":"2026-05-21T13:12:02.033542Z","caller":"traceutil/trace.go:171","msg":"trace[710434582] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:698; }","duration":"229.15878ms","start":"2026-05-21T13:12:01.80435Z","end":"2026-05-21T13:12:02.033509Z","steps":["trace[710434582] 'agreement among raft nodes before linearized reading' (duration: 228.893062ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:02.278888Z","caller":"traceutil/trace.go:171","msg":"trace[12950274] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"241.457075ms","start":"2026-05-21T13:12:02.037406Z","end":"2026-05-21T13:12:02.278863Z","steps":["trace[12950274] 'process raft request' (duration: 188.606396ms)","trace[12950274] 'compare' (duration: 52.748357ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:12:02.342439Z","caller":"traceutil/trace.go:171","msg":"trace[1806836124] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"304.915008ms","start":"2026-05-21T13:12:02.037503Z","end":"2026-05-21T13:12:02.342418Z","steps":["trace[1806836124] 'process raft request' (duration: 304.799205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:02.342612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:02.037489Z","time spent":"305.034101ms","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:12:02.465416Z","caller":"traceutil/trace.go:171","msg":"trace[1893252092] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:724; }","duration":"167.077332ms","start":"2026-05-21T13:12:02.298317Z","end":"2026-05-21T13:12:02.465394Z","steps":["trace[1893252092] 'read index received' (duration: 44.003175ms)","trace[1893252092] 'applied index is now lower than readState.Index' (duration: 123.073167ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:12:02.465684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.344001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:02.465464Z","caller":"traceutil/trace.go:171","msg":"trace[882029222] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"182.769206ms","start":"2026-05-21T13:12:02.282662Z","end":"2026-05-21T13:12:02.465431Z","steps":["trace[882029222] 'process raft request' (duration: 152.033508ms)","trace[882029222] 'compare' (duration: 30.602245ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:12:02.465771Z","caller":"traceutil/trace.go:171","msg":"trace[7225482] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:701; }","duration":"167.467713ms","start":"2026-05-21T13:12:02.298291Z","end":"2026-05-21T13:12:02.465759Z","steps":["trace[7225482] 'agreement among raft nodes before linearized reading' (duration: 167.293129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:02.482309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.926037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:02.482364Z","caller":"traceutil/trace.go:171","msg":"trace[237846406] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:702; }","duration":"184.02289ms","start":"2026-05-21T13:12:02.298326Z","end":"2026-05-21T13:12:02.482349Z","steps":["trace[237846406] 'agreement among raft nodes before linearized reading' (duration: 183.863926ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:02.482994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.008398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:02.483044Z","caller":"traceutil/trace.go:171","msg":"trace[883624135] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:702; }","duration":"168.103751ms","start":"2026-05-21T13:12:02.314925Z","end":"2026-05-21T13:12:02.483029Z","steps":["trace[883624135] 'agreement among raft nodes before linearized reading' (duration: 168.033899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:02.483236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.634725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:02.483274Z","caller":"traceutil/trace.go:171","msg":"trace[2049912498] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:702; }","duration":"168.707507ms","start":"2026-05-21T13:12:02.314555Z","end":"2026-05-21T13:12:02.483262Z","steps":["trace[2049912498] 'agreement among raft nodes before linearized reading' (duration: 168.641775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:02.48343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.905872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:02.483467Z","caller":"traceutil/trace.go:171","msg":"trace[657340675] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:702; }","duration":"168.965294ms","start":"2026-05-21T13:12:02.31449Z","end":"2026-05-21T13:12:02.483455Z","steps":["trace[657340675] 'agreement among raft nodes before linearized reading' (duration: 168.908833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:02.483623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.417562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:02.483663Z","caller":"traceutil/trace.go:171","msg":"trace[455104516] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:702; }","duration":"184.470993ms","start":"2026-05-21T13:12:02.299181Z","end":"2026-05-21T13:12:02.483652Z","steps":["trace[455104516] 'agreement among raft nodes before linearized reading' (duration: 184.407731ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:10.428926Z","caller":"traceutil/trace.go:171","msg":"trace[592580945] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"116.214923ms","start":"2026-05-21T13:12:10.312691Z","end":"2026-05-21T13:12:10.428906Z","steps":["trace[592580945] 'process raft request' (duration: 115.926965ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:13.31048Z","caller":"traceutil/trace.go:171","msg":"trace[1241344671] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"710.625973ms","start":"2026-05-21T13:12:12.599831Z","end":"2026-05-21T13:12:13.310457Z","steps":["trace[1241344671] 'process raft request' (duration: 710.49494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:13.310636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:12.599811Z","time spent":"710.740515ms","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:12:14.189071Z","caller":"traceutil/trace.go:171","msg":"trace[1740492990] transaction","detail":"{read_only:false; response_revision:775; number_of_response:1; }","duration":"1.58848832s","start":"2026-05-21T13:12:12.600559Z","end":"2026-05-21T13:12:14.189048Z","steps":["trace[1740492990] 'process raft request' (duration: 1.499963387s)","trace[1740492990] 'compare' (duration: 87.950588ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:12:14.189397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023209135s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T13:12:14.189561Z","caller":"traceutil/trace.go:171","msg":"trace[332545716] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:775; }","duration":"1.02342619s","start":"2026-05-21T13:12:13.166112Z","end":"2026-05-21T13:12:14.189539Z","steps":["trace[332545716] 'agreement among raft nodes before linearized reading' (duration: 1.023199515s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:14.189821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:13.16607Z","time spent":"1.023728997s","remote":"127.0.0.1:53152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":14,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-21T13:12:14.189246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:12.600544Z","time spent":"1.588612283s","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:12:14.189277Z","caller":"traceutil/trace.go:171","msg":"trace[2067477278] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:802; }","duration":"1.023079521s","start":"2026-05-21T13:12:13.16617Z","end":"2026-05-21T13:12:14.189249Z","steps":["trace[2067477278] 'read index received' (duration: 144.226528ms)","trace[2067477278] 'applied index is now lower than readState.Index' (duration: 878.850573ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:12:14.20095Z","caller":"traceutil/trace.go:171","msg":"trace[411847727] transaction","detail":"{read_only:false; response_revision:776; number_of_response:1; }","duration":"865.172679ms","start":"2026-05-21T13:12:13.335735Z","end":"2026-05-21T13:12:14.200908Z","steps":["trace[411847727] 'process raft request' (duration: 865.007666ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:14.201122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:13.335705Z","time spent":"865.327834ms","remote":"127.0.0.1:53036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T13:12:14.201149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.143578ms","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-05-21T13:12:14.201211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.750477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:12:14.201236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.428135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:14.201293Z","caller":"traceutil/trace.go:171","msg":"trace[97205199] transaction","detail":"{read_only:false; response_revision:777; number_of_response:1; }","duration":"520.26348ms","start":"2026-05-21T13:12:13.68102Z","end":"2026-05-21T13:12:14.201284Z","steps":["trace[97205199] 'process raft request' (duration: 519.837799ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:14.20129Z","caller":"traceutil/trace.go:171","msg":"trace[1640386822] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:777; }","duration":"219.496286ms","start":"2026-05-21T13:12:13.981779Z","end":"2026-05-21T13:12:14.201275Z","steps":["trace[1640386822] 'agreement among raft nodes before linearized reading' (duration: 219.410874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:14.20129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.258837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:12:14.201323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.851417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:14.20134Z","caller":"traceutil/trace.go:171","msg":"trace[1917732237] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:777; }","duration":"185.322439ms","start":"2026-05-21T13:12:14.016008Z","end":"2026-05-21T13:12:14.201331Z","steps":["trace[1917732237] 'agreement among raft nodes before linearized reading' (duration: 185.222836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:14.201314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.741652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:14.201377Z","caller":"traceutil/trace.go:171","msg":"trace[1477846574] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:777; }","duration":"184.924269ms","start":"2026-05-21T13:12:14.016437Z","end":"2026-05-21T13:12:14.201361Z","steps":["trace[1477846574] 'agreement among raft nodes before linearized reading' (duration: 184.826217ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:14.201414Z","caller":"traceutil/trace.go:171","msg":"trace[98356028] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:777; }","duration":"134.875695ms","start":"2026-05-21T13:12:14.066522Z","end":"2026-05-21T13:12:14.201398Z","steps":["trace[98356028] 'agreement among raft nodes before linearized reading' (duration: 134.749742ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:14.201194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.427325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:12:14.20153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.196151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:14.201252Z","caller":"traceutil/trace.go:171","msg":"trace[889233185] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:777; }","duration":"133.809429ms","start":"2026-05-21T13:12:14.067433Z","end":"2026-05-21T13:12:14.201243Z","steps":["trace[889233185] 'agreement among raft nodes before linearized reading' (duration: 133.710756ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:14.201607Z","caller":"traceutil/trace.go:171","msg":"trace[346018370] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:777; }","duration":"199.289173ms","start":"2026-05-21T13:12:14.002304Z","end":"2026-05-21T13:12:14.201593Z","steps":["trace[346018370] 'agreement among raft nodes before linearized reading' (duration: 199.187251ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:14.201706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.723231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:12:14.201718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.619869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:14.201749Z","caller":"traceutil/trace.go:171","msg":"trace[1821739933] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:777; }","duration":"162.786543ms","start":"2026-05-21T13:12:14.038945Z","end":"2026-05-21T13:12:14.201732Z","steps":["trace[1821739933] 'agreement among raft nodes before linearized reading' (duration: 162.722502ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:14.201753Z","caller":"traceutil/trace.go:171","msg":"trace[2002180305] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:777; }","duration":"219.669641ms","start":"2026-05-21T13:12:13.982074Z","end":"2026-05-21T13:12:14.201744Z","steps":["trace[2002180305] 'agreement among raft nodes before linearized reading' (duration: 219.605389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:14.20177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.282475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:14.201802Z","caller":"traceutil/trace.go:171","msg":"trace[1016253360] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:777; }","duration":"135.329596ms","start":"2026-05-21T13:12:14.066464Z","end":"2026-05-21T13:12:14.201793Z","steps":["trace[1016253360] 'agreement among raft nodes before linearized reading' (duration: 135.270835ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:14.201843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.859272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:14.201869Z","caller":"traceutil/trace.go:171","msg":"trace[758192220] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:777; }","duration":"185.885023ms","start":"2026-05-21T13:12:14.015975Z","end":"2026-05-21T13:12:14.20186Z","steps":["trace[758192220] 'agreement among raft nodes before linearized reading' (duration: 185.843702ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:14.201563Z","caller":"traceutil/trace.go:171","msg":"trace[663849251] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:777; }","duration":"162.847464ms","start":"2026-05-21T13:12:14.038669Z","end":"2026-05-21T13:12:14.201517Z","steps":["trace[663849251] 'agreement among raft nodes before linearized reading' (duration: 162.427484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:14.201942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.167002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:14.202027Z","caller":"traceutil/trace.go:171","msg":"trace[1934247806] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:777; }","duration":"163.291215ms","start":"2026-05-21T13:12:14.038689Z","end":"2026-05-21T13:12:14.20198Z","steps":["trace[1934247806] 'agreement among raft nodes before linearized reading' (duration: 163.161892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:14.201371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:13.680982Z","time spent":"520.335111ms","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T13:12:14.202186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.296173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:12:14.202197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.454099ms","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-05-21T13:12:14.202222Z","caller":"traceutil/trace.go:171","msg":"trace[492410896] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:777; }","duration":"199.332824ms","start":"2026-05-21T13:12:14.002877Z","end":"2026-05-21T13:12:14.20221Z","steps":["trace[492410896] 'agreement among raft nodes before linearized reading' (duration: 199.278473ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:14.202242Z","caller":"traceutil/trace.go:171","msg":"trace[1378946226] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:777; }","duration":"220.533482ms","start":"2026-05-21T13:12:13.981697Z","end":"2026-05-21T13:12:14.20223Z","steps":["trace[1378946226] 'agreement among raft nodes before linearized reading' (duration: 220.46228ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:14.202326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.247498ms","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":"info","ts":"2026-05-21T13:12:14.202371Z","caller":"traceutil/trace.go:171","msg":"trace[1833494553] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:777; }","duration":"276.33668ms","start":"2026-05-21T13:12:13.926025Z","end":"2026-05-21T13:12:14.202362Z","steps":["trace[1833494553] 'agreement among raft nodes before linearized reading' (duration: 276.239947ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:14.202637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.237926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:14.202733Z","caller":"traceutil/trace.go:171","msg":"trace[1904510613] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:777; }","duration":"200.337029ms","start":"2026-05-21T13:12:14.002329Z","end":"2026-05-21T13:12:14.202666Z","steps":["trace[1904510613] 'agreement among raft nodes before linearized reading' (duration: 200.226596ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:14.201212Z","caller":"traceutil/trace.go:171","msg":"trace[951332133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:777; }","duration":"916.24372ms","start":"2026-05-21T13:12:13.284952Z","end":"2026-05-21T13:12:14.201196Z","steps":["trace[951332133] 'agreement among raft nodes before linearized reading' (duration: 916.061496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:14.204095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:13.284918Z","time spent":"919.156002ms","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T13:12:16.844217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.480937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T13:12:16.844258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.640179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-05-21T13:12:16.844338Z","caller":"traceutil/trace.go:171","msg":"trace[844309066] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:819; }","duration":"189.633031ms","start":"2026-05-21T13:12:16.654675Z","end":"2026-05-21T13:12:16.844308Z","steps":["trace[844309066] 'count revisions from in-memory index tree' (duration: 189.405305ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:16.844346Z","caller":"traceutil/trace.go:171","msg":"trace[1271700861] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:819; }","duration":"255.774293ms","start":"2026-05-21T13:12:16.588545Z","end":"2026-05-21T13:12:16.844319Z","steps":["trace[1271700861] 'range keys from in-memory index tree' (duration: 255.452225ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:17.077794Z","caller":"traceutil/trace.go:171","msg":"trace[924027150] linearizableReadLoop","detail":"{readStateIndex:850; appliedIndex:849; }","duration":"231.915139ms","start":"2026-05-21T13:12:16.845842Z","end":"2026-05-21T13:12:17.077758Z","steps":["trace[924027150] 'read index received' (duration: 229.350858ms)","trace[924027150] 'applied index is now lower than readState.Index' (duration: 2.562941ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:12:17.077852Z","caller":"traceutil/trace.go:171","msg":"trace[966302941] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"323.124369ms","start":"2026-05-21T13:12:16.754701Z","end":"2026-05-21T13:12:17.077826Z","steps":["trace[966302941] 'process raft request' (duration: 321.324865ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:17.07805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.172005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-bzrtb\" ","response":"range_response_count:1 size:5393"} {"level":"info","ts":"2026-05-21T13:12:17.078098Z","caller":"traceutil/trace.go:171","msg":"trace[1614807635] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-bzrtb; range_end:; response_count:1; response_revision:820; }","duration":"232.278048ms","start":"2026-05-21T13:12:16.845807Z","end":"2026-05-21T13:12:17.078085Z","steps":["trace[1614807635] 'agreement among raft nodes before linearized reading' (duration: 232.079073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:17.078192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:16.754666Z","time spent":"323.246301ms","remote":"127.0.0.1:52918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036286,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T13:12:18.108653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.510147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:18.108779Z","caller":"traceutil/trace.go:171","msg":"trace[1236462181] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:820; }","duration":"812.710802ms","start":"2026-05-21T13:12:17.296049Z","end":"2026-05-21T13:12:18.10876Z","steps":["trace[1236462181] 'count revisions from in-memory index tree' (duration: 812.400074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:18.10883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:17.296031Z","time spent":"812.786363ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-05-21T13:12:18.109058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.059973ms","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-05-21T13:12:18.109137Z","caller":"traceutil/trace.go:171","msg":"trace[1875071518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:820; }","duration":"741.171675ms","start":"2026-05-21T13:12:17.367946Z","end":"2026-05-21T13:12:18.109118Z","steps":["trace[1875071518] 'range keys from in-memory index tree' (duration: 740.882769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:18.109182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:17.367935Z","time spent":"741.237676ms","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T13:12:18.109167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"865.640581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:18.109239Z","caller":"traceutil/trace.go:171","msg":"trace[378905134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:820; }","duration":"865.725713ms","start":"2026-05-21T13:12:17.243495Z","end":"2026-05-21T13:12:18.109221Z","steps":["trace[378905134] 'range keys from in-memory index tree' (duration: 865.56075ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:18.109282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:17.243441Z","time spent":"865.833327ms","remote":"127.0.0.1:52828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T13:12:18.109559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.293425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-fkhmq\" ","response":"range_response_count:1 size:5044"} {"level":"info","ts":"2026-05-21T13:12:18.109637Z","caller":"traceutil/trace.go:171","msg":"trace[1961331032] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-fkhmq; range_end:; response_count:1; response_revision:820; }","duration":"185.402727ms","start":"2026-05-21T13:12:17.924213Z","end":"2026-05-21T13:12:18.109615Z","steps":["trace[1961331032] 'range keys from in-memory index tree' (duration: 184.608998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:18.109981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.933079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050248"} {"level":"info","ts":"2026-05-21T13:12:18.110014Z","caller":"traceutil/trace.go:171","msg":"trace[276641861] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:820; }","duration":"916.012531ms","start":"2026-05-21T13:12:17.193992Z","end":"2026-05-21T13:12:18.110004Z","steps":["trace[276641861] 'range keys from in-memory index tree' (duration: 914.791282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:18.110036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:17.193967Z","time spent":"916.063361ms","remote":"127.0.0.1:52918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":1050271,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-21T13:12:18.389499Z","caller":"traceutil/trace.go:171","msg":"trace[460712972] linearizableReadLoop","detail":"{readStateIndex:852; appliedIndex:851; }","duration":"183.292419ms","start":"2026-05-21T13:12:18.206181Z","end":"2026-05-21T13:12:18.389474Z","steps":["trace[460712972] 'read index received' (duration: 161.368718ms)","trace[460712972] 'applied index is now lower than readState.Index' (duration: 21.922351ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:12:18.389575Z","caller":"traceutil/trace.go:171","msg":"trace[712387241] transaction","detail":"{read_only:false; response_revision:822; number_of_response:1; }","duration":"269.275455ms","start":"2026-05-21T13:12:18.120274Z","end":"2026-05-21T13:12:18.389549Z","steps":["trace[712387241] 'process raft request' (duration: 247.277152ms)","trace[712387241] 'compare' (duration: 21.784258ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:12:18.38973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.525435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-21T13:12:18.389784Z","caller":"traceutil/trace.go:171","msg":"trace[673228810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:822; }","duration":"183.611607ms","start":"2026-05-21T13:12:18.206155Z","end":"2026-05-21T13:12:18.389766Z","steps":["trace[673228810] 'agreement among raft nodes before linearized reading' (duration: 183.445643ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:18.608843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.306496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T13:12:18.609001Z","caller":"traceutil/trace.go:171","msg":"trace[91390104] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:852; }","duration":"219.408698ms","start":"2026-05-21T13:12:18.389577Z","end":"2026-05-21T13:12:18.608986Z","steps":["trace[91390104] 'read index received' (duration: 107.895567ms)","trace[91390104] 'applied index is now lower than readState.Index' (duration: 111.512151ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:12:18.609075Z","caller":"traceutil/trace.go:171","msg":"trace[2013689481] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"302.824813ms","start":"2026-05-21T13:12:18.306238Z","end":"2026-05-21T13:12:18.609063Z","steps":["trace[2013689481] 'process raft request' (duration: 191.22558ms)","trace[2013689481] 'compare' (duration: 111.209394ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:12:18.609156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:18.306212Z","time spent":"302.891884ms","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T13:12:18.609465Z","caller":"traceutil/trace.go:171","msg":"trace[1166159458] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"268.279042ms","start":"2026-05-21T13:12:18.341158Z","end":"2026-05-21T13:12:18.609437Z","steps":["trace[1166159458] 'process raft request' (duration: 267.773499ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:18.609482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.279539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:18.60954Z","caller":"traceutil/trace.go:171","msg":"trace[1216820351] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:824; }","duration":"365.348101ms","start":"2026-05-21T13:12:18.244175Z","end":"2026-05-21T13:12:18.609523Z","steps":["trace[1216820351] 'agreement among raft nodes before linearized reading' (duration: 365.251579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:18.609574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:18.244095Z","time spent":"365.472144ms","remote":"127.0.0.1:52828","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T13:12:18.609615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.088272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-21T13:12:18.609622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.230587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050248"} {"level":"info","ts":"2026-05-21T13:12:18.609678Z","caller":"traceutil/trace.go:171","msg":"trace[642194458] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:824; }","duration":"338.179814ms","start":"2026-05-21T13:12:18.271483Z","end":"2026-05-21T13:12:18.609663Z","steps":["trace[642194458] 'agreement among raft nodes before linearized reading' (duration: 338.01777ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:18.609702Z","caller":"traceutil/trace.go:171","msg":"trace[974943756] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:824; }","duration":"304.36473ms","start":"2026-05-21T13:12:18.305318Z","end":"2026-05-21T13:12:18.609682Z","steps":["trace[974943756] 'agreement among raft nodes before linearized reading' (duration: 303.881167ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:18.609731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:18.271467Z","time spent":"338.251465ms","remote":"127.0.0.1:53060","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T13:12:18.609747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:18.305299Z","time spent":"304.437632ms","remote":"127.0.0.1:52918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":1050271,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-21T13:12:18.636999Z","caller":"traceutil/trace.go:171","msg":"trace[825993423] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"242.310934ms","start":"2026-05-21T13:12:18.39468Z","end":"2026-05-21T13:12:18.636991Z","steps":["trace[825993423] 'process raft request' (duration: 241.939075ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:18.637264Z","caller":"traceutil/trace.go:171","msg":"trace[1174831086] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"241.654878ms","start":"2026-05-21T13:12:18.395589Z","end":"2026-05-21T13:12:18.637244Z","steps":["trace[1174831086] 'process raft request' (duration: 241.32034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:18.637406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.370488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-21T13:12:18.637464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.178555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T13:12:18.63751Z","caller":"traceutil/trace.go:171","msg":"trace[1123703323] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:828; }","duration":"137.471591ms","start":"2026-05-21T13:12:18.500001Z","end":"2026-05-21T13:12:18.637472Z","steps":["trace[1123703323] 'agreement among raft nodes before linearized reading' (duration: 137.320216ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:18.637524Z","caller":"traceutil/trace.go:171","msg":"trace[1751780301] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:828; }","duration":"148.288497ms","start":"2026-05-21T13:12:18.489221Z","end":"2026-05-21T13:12:18.637509Z","steps":["trace[1751780301] 'agreement among raft nodes before linearized reading' (duration: 148.130402ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:18.637599Z","caller":"traceutil/trace.go:171","msg":"trace[1868324108] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"240.098402ms","start":"2026-05-21T13:12:18.397494Z","end":"2026-05-21T13:12:18.637592Z","steps":["trace[1868324108] 'process raft request' (duration: 239.667701ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:18.636761Z","caller":"traceutil/trace.go:171","msg":"trace[1429677661] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"242.327614ms","start":"2026-05-21T13:12:18.394414Z","end":"2026-05-21T13:12:18.636741Z","steps":["trace[1429677661] 'process raft request' (duration: 242.108019ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:18.816212Z","caller":"traceutil/trace.go:171","msg":"trace[685233865] transaction","detail":"{read_only:false; response_revision:831; number_of_response:1; }","duration":"174.439249ms","start":"2026-05-21T13:12:18.64175Z","end":"2026-05-21T13:12:18.816189Z","steps":["trace[685233865] 'process raft request' (duration: 174.355057ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:18.816236Z","caller":"traceutil/trace.go:171","msg":"trace[138800123] transaction","detail":"{read_only:false; response_revision:830; number_of_response:1; }","duration":"174.621533ms","start":"2026-05-21T13:12:18.641585Z","end":"2026-05-21T13:12:18.816206Z","steps":["trace[138800123] 'process raft request' (duration: 173.694012ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:18.816275Z","caller":"traceutil/trace.go:171","msg":"trace[1543239401] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"171.325975ms","start":"2026-05-21T13:12:18.644926Z","end":"2026-05-21T13:12:18.816252Z","steps":["trace[1543239401] 'process raft request' (duration: 171.228583ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:18.821706Z","caller":"traceutil/trace.go:171","msg":"trace[2116198100] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"111.394869ms","start":"2026-05-21T13:12:18.710283Z","end":"2026-05-21T13:12:18.821678Z","steps":["trace[2116198100] 'process raft request' (duration: 111.153913ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:21.006897Z","caller":"traceutil/trace.go:171","msg":"trace[28139391] linearizableReadLoop","detail":"{readStateIndex:880; appliedIndex:879; }","duration":"130.09874ms","start":"2026-05-21T13:12:20.876775Z","end":"2026-05-21T13:12:21.006873Z","steps":["trace[28139391] 'read index received' (duration: 129.922416ms)","trace[28139391] 'applied index is now lower than readState.Index' (duration: 175.204µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:12:21.006938Z","caller":"traceutil/trace.go:171","msg":"trace[1320229184] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"181.653516ms","start":"2026-05-21T13:12:20.825255Z","end":"2026-05-21T13:12:21.006909Z","steps":["trace[1320229184] 'process raft request' (duration: 181.454961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:21.00704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.238453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T13:12:21.007096Z","caller":"traceutil/trace.go:171","msg":"trace[503510937] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:849; }","duration":"130.344636ms","start":"2026-05-21T13:12:20.876736Z","end":"2026-05-21T13:12:21.00708Z","steps":["trace[503510937] 'agreement among raft nodes before linearized reading' (duration: 130.210563ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:21.298016Z","caller":"traceutil/trace.go:171","msg":"trace[1751483861] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"101.82585ms","start":"2026-05-21T13:12:21.196166Z","end":"2026-05-21T13:12:21.297992Z","steps":["trace[1751483861] 'process raft request' (duration: 101.606815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:21.478281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.078265ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T13:12:21.478439Z","caller":"traceutil/trace.go:171","msg":"trace[1023358089] transaction","detail":"{read_only:false; response_revision:851; number_of_response:1; }","duration":"214.723035ms","start":"2026-05-21T13:12:21.263682Z","end":"2026-05-21T13:12:21.478405Z","steps":["trace[1023358089] 'process raft request' (duration: 52.433715ms)","trace[1023358089] 'compare' (duration: 161.966203ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:12:21.478555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.678823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:21.478583Z","caller":"traceutil/trace.go:171","msg":"trace[969371915] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:851; }","duration":"179.751485ms","start":"2026-05-21T13:12:21.298824Z","end":"2026-05-21T13:12:21.478576Z","steps":["trace[969371915] 'agreement among raft nodes before linearized reading' (duration: 179.679174ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:21.478401Z","caller":"traceutil/trace.go:171","msg":"trace[1429104392] linearizableReadLoop","detail":"{readStateIndex:882; appliedIndex:881; }","duration":"179.52677ms","start":"2026-05-21T13:12:21.298858Z","end":"2026-05-21T13:12:21.478384Z","steps":["trace[1429104392] 'read index received' (duration: 17.318852ms)","trace[1429104392] 'applied index is now lower than readState.Index' (duration: 162.206688ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:12:21.493133Z","caller":"traceutil/trace.go:171","msg":"trace[1430043787] transaction","detail":"{read_only:false; response_revision:852; number_of_response:1; }","duration":"186.471911ms","start":"2026-05-21T13:12:21.306636Z","end":"2026-05-21T13:12:21.493108Z","steps":["trace[1430043787] 'process raft request' (duration: 186.263716ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:21.493341Z","caller":"traceutil/trace.go:171","msg":"trace[172209064] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"186.664736ms","start":"2026-05-21T13:12:21.306666Z","end":"2026-05-21T13:12:21.49333Z","steps":["trace[172209064] 'process raft request' (duration: 186.38512ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:21.493426Z","caller":"traceutil/trace.go:171","msg":"trace[308581641] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"186.676696ms","start":"2026-05-21T13:12:21.306744Z","end":"2026-05-21T13:12:21.493421Z","steps":["trace[308581641] 'process raft request' (duration: 186.522353ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:25.870766Z","caller":"traceutil/trace.go:171","msg":"trace[1948599476] transaction","detail":"{read_only:false; response_revision:907; number_of_response:1; }","duration":"325.647233ms","start":"2026-05-21T13:12:25.545092Z","end":"2026-05-21T13:12:25.870739Z","steps":["trace[1948599476] 'process raft request' (duration: 321.9353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:25.870874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.133172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T13:12:25.870906Z","caller":"traceutil/trace.go:171","msg":"trace[70313867] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:909; }","duration":"129.215945ms","start":"2026-05-21T13:12:25.741683Z","end":"2026-05-21T13:12:25.870899Z","steps":["trace[70313867] 'agreement among raft nodes before linearized reading' (duration: 129.140653ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:25.870904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:25.545079Z","time spent":"325.761927ms","remote":"127.0.0.1:52922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1745,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:12:25.870798Z","caller":"traceutil/trace.go:171","msg":"trace[724908425] linearizableReadLoop","detail":"{readStateIndex:941; appliedIndex:938; }","duration":"129.04809ms","start":"2026-05-21T13:12:25.741735Z","end":"2026-05-21T13:12:25.870783Z","steps":["trace[724908425] 'read index received' (duration: 125.222794ms)","trace[724908425] 'applied index is now lower than readState.Index' (duration: 3.824456ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:12:25.87085Z","caller":"traceutil/trace.go:171","msg":"trace[1342382415] transaction","detail":"{read_only:false; response_revision:908; number_of_response:1; }","duration":"325.440219ms","start":"2026-05-21T13:12:25.545375Z","end":"2026-05-21T13:12:25.870815Z","steps":["trace[1342382415] 'process raft request' (duration: 325.276975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:25.871153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:25.54536Z","time spent":"325.759286ms","remote":"127.0.0.1:53010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":178,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:12:25.871164Z","caller":"traceutil/trace.go:171","msg":"trace[213221041] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"322.072322ms","start":"2026-05-21T13:12:25.549077Z","end":"2026-05-21T13:12:25.871149Z","steps":["trace[213221041] 'process raft request' (duration: 321.665283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:12:25.871405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T13:12:25.549063Z","time spent":"322.190606ms","remote":"127.0.0.1:52918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12761,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T13:12:26.16963Z","caller":"traceutil/trace.go:171","msg":"trace[775258854] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"293.313817ms","start":"2026-05-21T13:12:25.876282Z","end":"2026-05-21T13:12:26.169596Z","steps":["trace[775258854] 'process raft request' (duration: 280.269038ms)","trace[775258854] 'compare' (duration: 12.802724ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:12:28.738063Z","caller":"traceutil/trace.go:171","msg":"trace[932993642] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"118.57537ms","start":"2026-05-21T13:12:28.619463Z","end":"2026-05-21T13:12:28.738039Z","steps":["trace[932993642] 'process raft request' (duration: 118.415826ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:31.496584Z","caller":"traceutil/trace.go:171","msg":"trace[1977960655] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"101.239968ms","start":"2026-05-21T13:12:31.395322Z","end":"2026-05-21T13:12:31.496562Z","steps":["trace[1977960655] 'process raft request' (duration: 101.100375ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:31.646374Z","caller":"traceutil/trace.go:171","msg":"trace[1738228451] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"143.316425ms","start":"2026-05-21T13:12:31.503038Z","end":"2026-05-21T13:12:31.646354Z","steps":["trace[1738228451] 'process raft request' (duration: 143.196582ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:12:34.983079Z","caller":"traceutil/trace.go:171","msg":"trace[730932685] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"101.427131ms","start":"2026-05-21T13:12:34.881621Z","end":"2026-05-21T13:12:34.983048Z","steps":["trace[730932685] 'process raft request' (duration: 100.462972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:13:21.720027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.034787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-21T13:13:21.72012Z","caller":"traceutil/trace.go:171","msg":"trace[1217628278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1135; }","duration":"129.161799ms","start":"2026-05-21T13:13:21.59094Z","end":"2026-05-21T13:13:21.720102Z","steps":["trace[1217628278] 'range keys from in-memory index tree' (duration: 128.915435ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:13:46.053224Z","caller":"traceutil/trace.go:171","msg":"trace[310443895] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"138.22636ms","start":"2026-05-21T13:13:45.914975Z","end":"2026-05-21T13:13:46.053201Z","steps":["trace[310443895] 'process raft request' (duration: 138.000667ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:13:46.564292Z","caller":"traceutil/trace.go:171","msg":"trace[948393083] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"119.475529ms","start":"2026-05-21T13:13:46.444788Z","end":"2026-05-21T13:13:46.564264Z","steps":["trace[948393083] 'process raft request' (duration: 119.312486ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:13:47.750415Z","caller":"traceutil/trace.go:171","msg":"trace[117524687] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"218.232378ms","start":"2026-05-21T13:13:47.532138Z","end":"2026-05-21T13:13:47.75037Z","steps":["trace[117524687] 'process raft request' (duration: 212.54362ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:14:31.671665Z","caller":"traceutil/trace.go:171","msg":"trace[1327555918] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"104.121368ms","start":"2026-05-21T13:14:31.567522Z","end":"2026-05-21T13:14:31.671643Z","steps":["trace[1327555918] 'process raft request' (duration: 103.955316ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:14:39.84089Z","caller":"traceutil/trace.go:171","msg":"trace[1831416609] linearizableReadLoop","detail":"{readStateIndex:1493; appliedIndex:1492; }","duration":"143.035112ms","start":"2026-05-21T13:14:39.69783Z","end":"2026-05-21T13:14:39.840865Z","steps":["trace[1831416609] 'read index received' (duration: 77.608284ms)","trace[1831416609] 'applied index is now lower than readState.Index' (duration: 65.425288ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:14:39.841122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.272305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-21T13:14:39.841158Z","caller":"traceutil/trace.go:171","msg":"trace[210841868] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1431; }","duration":"143.351866ms","start":"2026-05-21T13:14:39.697795Z","end":"2026-05-21T13:14:39.841147Z","steps":["trace[210841868] 'agreement among raft nodes before linearized reading' (duration: 143.205875ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:14:39.841213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.891209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hld2z\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-05-21T13:14:39.841303Z","caller":"traceutil/trace.go:171","msg":"trace[1178275899] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hld2z; range_end:; response_count:1; response_revision:1431; }","duration":"126.02267ms","start":"2026-05-21T13:14:39.715259Z","end":"2026-05-21T13:14:39.841281Z","steps":["trace[1178275899] 'agreement among raft nodes before linearized reading' (duration: 125.767857ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:14:39.993402Z","caller":"traceutil/trace.go:171","msg":"trace[787026349] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"148.461327ms","start":"2026-05-21T13:14:39.844926Z","end":"2026-05-21T13:14:39.993387Z","steps":["trace[787026349] 'process raft request' (duration: 148.381386ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T13:14:40.079212Z","caller":"traceutil/trace.go:171","msg":"trace[665324277] linearizableReadLoop","detail":"{readStateIndex:1495; appliedIndex:1493; }","duration":"230.676334ms","start":"2026-05-21T13:14:39.848509Z","end":"2026-05-21T13:14:40.079186Z","steps":["trace[665324277] 'read index received' (duration: 144.834224ms)","trace[665324277] 'applied index is now lower than readState.Index' (duration: 85.84119ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T13:14:40.079237Z","caller":"traceutil/trace.go:171","msg":"trace[653932584] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"234.205036ms","start":"2026-05-21T13:14:39.845001Z","end":"2026-05-21T13:14:40.079206Z","steps":["trace[653932584] 'process raft request' (duration: 198.617473ms)","trace[653932584] 'compare' (duration: 35.406221ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T13:14:40.079522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.965167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hld2z\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-05-21T13:14:40.079567Z","caller":"traceutil/trace.go:171","msg":"trace[431250105] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hld2z; range_end:; response_count:1; response_revision:1433; }","duration":"231.071938ms","start":"2026-05-21T13:14:39.848483Z","end":"2026-05-21T13:14:40.079555Z","steps":["trace[431250105] 'agreement among raft nodes before linearized reading' (duration: 230.833266ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T13:14:40.07974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.518613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-21T13:14:40.079826Z","caller":"traceutil/trace.go:171","msg":"trace[1721226758] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1433; }","duration":"110.707995ms","start":"2026-05-21T13:14:39.969097Z","end":"2026-05-21T13:14:40.079805Z","steps":["trace[1721226758] 'agreement among raft nodes before linearized reading' (duration: 110.365491ms)"],"step_count":1}