{"level":"warn","ts":"2026-05-06T05:03:30.479501Z","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-06T05:03:30.479618Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.163:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.163:2380","--initial-cluster=instance=https://199.19.213.163:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.163:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.163: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-06T05:03:30.479755Z","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-06T05:03:30.479769Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.163:2380"]} {"level":"info","ts":"2026-05-06T05:03:30.479808Z","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-06T05:03:30.480482Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"]} {"level":"info","ts":"2026-05-06T05:03:30.480607Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.163:2380"],"listen-peer-urls":["https://199.19.213.163:2380"],"advertise-client-urls":["https://199.19.213.163:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.163: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-06T05:03:30.486451Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.565711ms"} {"level":"info","ts":"2026-05-06T05:03:30.493417Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e7941a908d261618","cluster-id":"5b6cb15db6ea8c10"} {"level":"info","ts":"2026-05-06T05:03:30.493516Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T05:03:30.493569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became follower at term 0"} {"level":"info","ts":"2026-05-06T05:03:30.49359Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e7941a908d261618 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T05:03:30.493627Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became follower at term 1"} {"level":"info","ts":"2026-05-06T05:03:30.493705Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=(16686991727460226584)"} {"level":"warn","ts":"2026-05-06T05:03:30.501021Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T05:03:30.503543Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T05:03:30.504871Z","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-06T05:03:30.507153Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e7941a908d261618","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T05:03:30.507365Z","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-06T05:03:30.507466Z","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-06T05:03:30.507484Z","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-06T05:03:30.507448Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e7941a908d261618","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T05:03:30.509035Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 switched to configuration voters=(16686991727460226584)"} {"level":"info","ts":"2026-05-06T05:03:30.509489Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5b6cb15db6ea8c10","local-member-id":"e7941a908d261618","added-peer-id":"e7941a908d261618","added-peer-peer-urls":["https://199.19.213.163:2380"]} {"level":"info","ts":"2026-05-06T05:03:30.51678Z","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-06T05:03:30.516951Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.163:2380"} {"level":"info","ts":"2026-05-06T05:03:30.517014Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.163:2380"} {"level":"info","ts":"2026-05-06T05:03:30.517213Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e7941a908d261618","initial-advertise-peer-urls":["https://199.19.213.163:2380"],"listen-peer-urls":["https://199.19.213.163:2380"],"advertise-client-urls":["https://199.19.213.163:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.163:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T05:03:30.517268Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T05:03:30.693977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T05:03:30.694062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T05:03:30.694103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 received MsgPreVoteResp from e7941a908d261618 at term 1"} {"level":"info","ts":"2026-05-06T05:03:30.694126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became candidate at term 2"} {"level":"info","ts":"2026-05-06T05:03:30.694138Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 received MsgVoteResp from e7941a908d261618 at term 2"} {"level":"info","ts":"2026-05-06T05:03:30.694155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e7941a908d261618 became leader at term 2"} {"level":"info","ts":"2026-05-06T05:03:30.69417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e7941a908d261618 elected leader e7941a908d261618 at term 2"} {"level":"info","ts":"2026-05-06T05:03:30.69497Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:03:30.696543Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e7941a908d261618","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.163:2379]}","request-path":"/0/members/e7941a908d261618/attributes","cluster-id":"5b6cb15db6ea8c10","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T05:03:30.696737Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T05:03:30.696802Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T05:03:30.701101Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5b6cb15db6ea8c10","local-member-id":"e7941a908d261618","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:03:30.701414Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T05:03:30.702677Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T05:03:30.70156Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T05:03:30.702858Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:03:30.713531Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:03:30.70402Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.163:2379"} {"level":"warn","ts":"2026-05-06T05:03:43.832716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.514841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-05-06T05:03:43.832836Z","caller":"traceutil/trace.go:171","msg":"trace[1588391094] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:400; }","duration":"209.698695ms","start":"2026-05-06T05:03:43.623116Z","end":"2026-05-06T05:03:43.832814Z","steps":["trace[1588391094] 'range keys from in-memory index tree' (duration: 209.363587ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:03:54.850524Z","caller":"traceutil/trace.go:171","msg":"trace[291053953] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"263.262769ms","start":"2026-05-06T05:03:54.587243Z","end":"2026-05-06T05:03:54.850506Z","steps":["trace[291053953] 'process raft request' (duration: 167.018999ms)","trace[291053953] 'compare' (duration: 96.027055ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:03:54.850771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.564944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T05:03:54.850453Z","caller":"traceutil/trace.go:171","msg":"trace[1316716006] linearizableReadLoop","detail":"{readStateIndex:446; appliedIndex:445; }","duration":"120.234386ms","start":"2026-05-06T05:03:54.730186Z","end":"2026-05-06T05:03:54.85042Z","steps":["trace[1316716006] 'read index received' (duration: 24.086028ms)","trace[1316716006] 'applied index is now lower than readState.Index' (duration: 96.147138ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:03:54.8509Z","caller":"traceutil/trace.go:171","msg":"trace[1867183209] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:431; }","duration":"120.708307ms","start":"2026-05-06T05:03:54.730158Z","end":"2026-05-06T05:03:54.850866Z","steps":["trace[1867183209] 'agreement among raft nodes before linearized reading' (duration: 120.466292ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:36.176571Z","caller":"traceutil/trace.go:171","msg":"trace[528708340] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"264.574931ms","start":"2026-05-06T05:04:35.911965Z","end":"2026-05-06T05:04:36.17654Z","steps":["trace[528708340] 'process raft request' (duration: 264.490249ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:36.176959Z","caller":"traceutil/trace.go:171","msg":"trace[1740328691] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"268.832732ms","start":"2026-05-06T05:04:35.908079Z","end":"2026-05-06T05:04:36.176911Z","steps":["trace[1740328691] 'process raft request' (duration: 268.268419ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:36.177229Z","caller":"traceutil/trace.go:171","msg":"trace[352166015] transaction","detail":"{read_only:false; number_of_response:1; response_revision:608; }","duration":"270.46589ms","start":"2026-05-06T05:04:35.906542Z","end":"2026-05-06T05:04:36.177008Z","steps":["trace[352166015] 'process raft request' (duration: 269.6244ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:36.185356Z","caller":"traceutil/trace.go:171","msg":"trace[928614327] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"267.351556ms","start":"2026-05-06T05:04:35.91797Z","end":"2026-05-06T05:04:36.185322Z","steps":["trace[928614327] 'process raft request' (duration: 267.108101ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:36.185574Z","caller":"traceutil/trace.go:171","msg":"trace[1940675496] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"262.631965ms","start":"2026-05-06T05:04:35.922902Z","end":"2026-05-06T05:04:36.185534Z","steps":["trace[1940675496] 'process raft request' (duration: 262.335378ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:36.185914Z","caller":"traceutil/trace.go:171","msg":"trace[1037215913] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"262.664676ms","start":"2026-05-06T05:04:35.923231Z","end":"2026-05-06T05:04:36.185895Z","steps":["trace[1037215913] 'process raft request' (duration: 262.219335ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:36.186026Z","caller":"traceutil/trace.go:171","msg":"trace[49003170] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"252.461575ms","start":"2026-05-06T05:04:35.933551Z","end":"2026-05-06T05:04:36.186013Z","steps":["trace[49003170] 'process raft request' (duration: 252.239499ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:36.186109Z","caller":"traceutil/trace.go:171","msg":"trace[210067637] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"251.856221ms","start":"2026-05-06T05:04:35.934241Z","end":"2026-05-06T05:04:36.186097Z","steps":["trace[210067637] 'process raft request' (duration: 251.660636ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:36.186189Z","caller":"traceutil/trace.go:171","msg":"trace[2131744820] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"205.211599ms","start":"2026-05-06T05:04:35.980971Z","end":"2026-05-06T05:04:36.186182Z","steps":["trace[2131744820] 'process raft request' (duration: 205.058325ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:38.670017Z","caller":"traceutil/trace.go:171","msg":"trace[1941521605] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"199.32798ms","start":"2026-05-06T05:04:38.470661Z","end":"2026-05-06T05:04:38.669989Z","steps":["trace[1941521605] 'process raft request' (duration: 199.169126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:39.05355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.630142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-06T05:04:39.053646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.445643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:04:39.053668Z","caller":"traceutil/trace.go:171","msg":"trace[231288589] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:670; }","duration":"147.513335ms","start":"2026-05-06T05:04:38.906144Z","end":"2026-05-06T05:04:39.053658Z","steps":["trace[231288589] 'range keys from in-memory index tree' (duration: 147.218188ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:39.053666Z","caller":"traceutil/trace.go:171","msg":"trace[1246662445] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:670; }","duration":"228.796365ms","start":"2026-05-06T05:04:38.82485Z","end":"2026-05-06T05:04:39.053646Z","steps":["trace[1246662445] 'range keys from in-memory index tree' (duration: 228.474738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:39.054114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.938076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:04:39.054762Z","caller":"traceutil/trace.go:171","msg":"trace[1797633969] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:670; }","duration":"107.590602ms","start":"2026-05-06T05:04:38.94714Z","end":"2026-05-06T05:04:39.054731Z","steps":["trace[1797633969] 'count revisions from in-memory index tree' (duration: 106.872605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:39.053614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.253109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:04:39.054964Z","caller":"traceutil/trace.go:171","msg":"trace[262242681] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:670; }","duration":"148.619521ms","start":"2026-05-06T05:04:38.906333Z","end":"2026-05-06T05:04:39.054952Z","steps":["trace[262242681] 'count revisions from in-memory index tree' (duration: 147.207518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:39.054435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.104201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:04:39.055075Z","caller":"traceutil/trace.go:171","msg":"trace[940930977] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:670; }","duration":"107.751206ms","start":"2026-05-06T05:04:38.947308Z","end":"2026-05-06T05:04:39.055059Z","steps":["trace[940930977] 'range keys from in-memory index tree' (duration: 107.074659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:39.054493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.114131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:04:39.055158Z","caller":"traceutil/trace.go:171","msg":"trace[1862275389] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:670; }","duration":"107.872879ms","start":"2026-05-06T05:04:38.947278Z","end":"2026-05-06T05:04:39.055151Z","steps":["trace[1862275389] 'range keys from in-memory index tree' (duration: 106.952216ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:41.267003Z","caller":"traceutil/trace.go:171","msg":"trace[1784957954] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"150.644059ms","start":"2026-05-06T05:04:41.116335Z","end":"2026-05-06T05:04:41.26698Z","steps":["trace[1784957954] 'read index received' (duration: 150.542657ms)","trace[1784957954] 'applied index is now lower than readState.Index' (duration: 100.562µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:04:41.267062Z","caller":"traceutil/trace.go:171","msg":"trace[758507144] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"191.670148ms","start":"2026-05-06T05:04:41.075354Z","end":"2026-05-06T05:04:41.267024Z","steps":["trace[758507144] 'process raft request' (duration: 191.480354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:41.267198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.848174ms","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-06T05:04:41.267226Z","caller":"traceutil/trace.go:171","msg":"trace[1177384338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:683; }","duration":"150.946836ms","start":"2026-05-06T05:04:41.116271Z","end":"2026-05-06T05:04:41.267218Z","steps":["trace[1177384338] 'agreement among raft nodes before linearized reading' (duration: 150.810013ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:43.480979Z","caller":"traceutil/trace.go:171","msg":"trace[291277931] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:727; }","duration":"193.536772ms","start":"2026-05-06T05:04:43.287361Z","end":"2026-05-06T05:04:43.480898Z","steps":["trace[291277931] 'read index received' (duration: 43.066537ms)","trace[291277931] 'applied index is now lower than readState.Index' (duration: 150.469125ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:04:43.481418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.972573ms","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-06T05:04:43.481554Z","caller":"traceutil/trace.go:171","msg":"trace[2131335573] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:700; }","duration":"194.194538ms","start":"2026-05-06T05:04:43.287325Z","end":"2026-05-06T05:04:43.481519Z","steps":["trace[2131335573] 'agreement among raft nodes before linearized reading' (duration: 193.688656ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:43.481628Z","caller":"traceutil/trace.go:171","msg":"trace[1912010361] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"196.968213ms","start":"2026-05-06T05:04:43.284012Z","end":"2026-05-06T05:04:43.480981Z","steps":["trace[1912010361] 'process raft request' (duration: 196.063272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:43.48175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.103782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T05:04:43.481962Z","caller":"traceutil/trace.go:171","msg":"trace[1228511001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:700; }","duration":"190.392068ms","start":"2026-05-06T05:04:43.291526Z","end":"2026-05-06T05:04:43.481918Z","steps":["trace[1228511001] 'agreement among raft nodes before linearized reading' (duration: 189.824155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:44.890879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.15455ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:04:44.891037Z","caller":"traceutil/trace.go:171","msg":"trace[283774297] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"1.402477816s","start":"2026-05-06T05:04:43.488534Z","end":"2026-05-06T05:04:44.891012Z","steps":["trace[283774297] 'process raft request' (duration: 911.345123ms)","trace[283774297] 'compare' (duration: 489.658548ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:04:44.891138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:04:43.488507Z","time spent":"1.402575588s","remote":"127.0.0.1:57670","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-06T05:04:44.891543Z","caller":"traceutil/trace.go:171","msg":"trace[522529744] linearizableReadLoop","detail":"{readStateIndex:732; appliedIndex:730; }","duration":"906.894206ms","start":"2026-05-06T05:04:43.984609Z","end":"2026-05-06T05:04:44.891503Z","steps":["trace[522529744] 'read index received' (duration: 415.289292ms)","trace[522529744] 'applied index is now lower than readState.Index' (duration: 491.602934ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:04:44.891603Z","caller":"traceutil/trace.go:171","msg":"trace[981542941] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"822.552644ms","start":"2026-05-06T05:04:44.069026Z","end":"2026-05-06T05:04:44.891578Z","steps":["trace[981542941] 'process raft request' (duration: 822.410351ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:44.891547Z","caller":"traceutil/trace.go:171","msg":"trace[1524124781] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"1.348725236s","start":"2026-05-06T05:04:43.542779Z","end":"2026-05-06T05:04:44.891504Z","steps":["trace[1524124781] 'process raft request' (duration: 1.348511551s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:44.891897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:04:44.068994Z","time spent":"822.8467ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:04:44.891908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.991775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T05:04:44.891935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:04:43.542755Z","time spent":"1.349105855s","remote":"127.0.0.1:57646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T05:04:44.891945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.309386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.163\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-06T05:04:44.892009Z","caller":"traceutil/trace.go:171","msg":"trace[293392802] range","detail":"{range_begin:/registry/masterleases/199.19.213.163; range_end:; response_count:1; response_revision:704; }","duration":"907.45247ms","start":"2026-05-06T05:04:43.984541Z","end":"2026-05-06T05:04:44.891993Z","steps":["trace[293392802] 'agreement among raft nodes before linearized reading' (duration: 907.273295ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:44.89209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:04:43.984502Z","time spent":"907.574533ms","remote":"127.0.0.1:57450","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.163\" "} {"level":"warn","ts":"2026-05-06T05:04:44.892115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.062279ms","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-06T05:04:44.891956Z","caller":"traceutil/trace.go:171","msg":"trace[836601127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:704; }","duration":"432.088787ms","start":"2026-05-06T05:04:44.459852Z","end":"2026-05-06T05:04:44.891941Z","steps":["trace[836601127] 'agreement among raft nodes before linearized reading' (duration: 432.015316ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:44.892198Z","caller":"traceutil/trace.go:171","msg":"trace[1197418009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:704; }","duration":"173.191712ms","start":"2026-05-06T05:04:44.718993Z","end":"2026-05-06T05:04:44.892185Z","steps":["trace[1197418009] 'agreement among raft nodes before linearized reading' (duration: 172.992847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:44.892212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:04:44.459815Z","time spent":"432.382524ms","remote":"127.0.0.1:57424","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-06T05:04:50.279903Z","caller":"traceutil/trace.go:171","msg":"trace[1076700721] linearizableReadLoop","detail":"{readStateIndex:813; appliedIndex:812; }","duration":"110.596554ms","start":"2026-05-06T05:04:50.169279Z","end":"2026-05-06T05:04:50.279876Z","steps":["trace[1076700721] 'read index received' (duration: 110.282806ms)","trace[1076700721] 'applied index is now lower than readState.Index' (duration: 312.568µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:04:50.28002Z","caller":"traceutil/trace.go:171","msg":"trace[165703820] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"278.988712ms","start":"2026-05-06T05:04:50.000989Z","end":"2026-05-06T05:04:50.279978Z","steps":["trace[165703820] 'process raft request' (duration: 278.687475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:50.280151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.860319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-05-06T05:04:50.280196Z","caller":"traceutil/trace.go:171","msg":"trace[604318445] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:782; }","duration":"110.976082ms","start":"2026-05-06T05:04:50.169208Z","end":"2026-05-06T05:04:50.280184Z","steps":["trace[604318445] 'agreement among raft nodes before linearized reading' (duration: 110.791208ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:51.032529Z","caller":"traceutil/trace.go:171","msg":"trace[209481144] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"112.446967ms","start":"2026-05-06T05:04:50.920046Z","end":"2026-05-06T05:04:51.032493Z","steps":["trace[209481144] 'read index received' (duration: 71.730695ms)","trace[209481144] 'applied index is now lower than readState.Index' (duration: 40.714852ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:04:51.032631Z","caller":"traceutil/trace.go:171","msg":"trace[2060115882] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"172.389953ms","start":"2026-05-06T05:04:50.860191Z","end":"2026-05-06T05:04:51.032581Z","steps":["trace[2060115882] 'process raft request' (duration: 131.714702ms)","trace[2060115882] 'compare' (duration: 40.413045ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:04:51.032789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.724263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-06T05:04:51.033005Z","caller":"traceutil/trace.go:171","msg":"trace[1791376848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:790; }","duration":"112.889617ms","start":"2026-05-06T05:04:50.919983Z","end":"2026-05-06T05:04:51.032873Z","steps":["trace[1791376848] 'agreement among raft nodes before linearized reading' (duration: 112.62314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:51.033115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.052863ms","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-06T05:04:51.033457Z","caller":"traceutil/trace.go:171","msg":"trace[977277847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:790; }","duration":"111.32464ms","start":"2026-05-06T05:04:50.921989Z","end":"2026-05-06T05:04:51.033313Z","steps":["trace[977277847] 'agreement among raft nodes before linearized reading' (duration: 110.741686ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:51.16391Z","caller":"traceutil/trace.go:171","msg":"trace[1020428502] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"124.969413ms","start":"2026-05-06T05:04:51.038903Z","end":"2026-05-06T05:04:51.163872Z","steps":["trace[1020428502] 'process raft request' (duration: 124.381249ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:51.168498Z","caller":"traceutil/trace.go:171","msg":"trace[259322271] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"129.51015ms","start":"2026-05-06T05:04:51.038956Z","end":"2026-05-06T05:04:51.168466Z","steps":["trace[259322271] 'process raft request' (duration: 129.335526ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:04:59.467247Z","caller":"traceutil/trace.go:171","msg":"trace[443657933] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:886; }","duration":"253.012588ms","start":"2026-05-06T05:04:59.21421Z","end":"2026-05-06T05:04:59.467222Z","steps":["trace[443657933] 'read index received' (duration: 252.745432ms)","trace[443657933] 'applied index is now lower than readState.Index' (duration: 266.046µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:04:59.467284Z","caller":"traceutil/trace.go:171","msg":"trace[1298279510] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"258.419356ms","start":"2026-05-06T05:04:59.208833Z","end":"2026-05-06T05:04:59.467252Z","steps":["trace[1298279510] 'process raft request' (duration: 258.234242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:04:59.467483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.250464ms","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-06T05:04:59.467533Z","caller":"traceutil/trace.go:171","msg":"trace[664843507] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:854; }","duration":"253.363796ms","start":"2026-05-06T05:04:59.214157Z","end":"2026-05-06T05:04:59.46752Z","steps":["trace[664843507] 'agreement among raft nodes before linearized reading' (duration: 253.194682ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:01.83251Z","caller":"traceutil/trace.go:171","msg":"trace[281433687] linearizableReadLoop","detail":"{readStateIndex:901; appliedIndex:900; }","duration":"272.512229ms","start":"2026-05-06T05:05:01.559963Z","end":"2026-05-06T05:05:01.832475Z","steps":["trace[281433687] 'read index received' (duration: 272.16705ms)","trace[281433687] 'applied index is now lower than readState.Index' (duration: 344.139µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:05:01.832632Z","caller":"traceutil/trace.go:171","msg":"trace[139385971] transaction","detail":"{read_only:false; response_revision:867; number_of_response:1; }","duration":"357.683281ms","start":"2026-05-06T05:05:01.474899Z","end":"2026-05-06T05:05:01.832582Z","steps":["trace[139385971] 'process raft request' (duration: 357.338973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:01.833009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:01.474869Z","time spent":"358.03295ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:05:01.833051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.976287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T05:05:01.833155Z","caller":"traceutil/trace.go:171","msg":"trace[1305653450] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:867; }","duration":"220.10874ms","start":"2026-05-06T05:05:01.613022Z","end":"2026-05-06T05:05:01.833131Z","steps":["trace[1305653450] 'agreement among raft nodes before linearized reading' (duration: 219.870404ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:01.833262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.793931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-06T05:05:01.833397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.877567ms","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-06T05:05:01.833449Z","caller":"traceutil/trace.go:171","msg":"trace[1400360584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:867; }","duration":"273.542483ms","start":"2026-05-06T05:05:01.559888Z","end":"2026-05-06T05:05:01.83343Z","steps":["trace[1400360584] 'agreement among raft nodes before linearized reading' (duration: 272.806916ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:01.833461Z","caller":"traceutil/trace.go:171","msg":"trace[342089959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:867; }","duration":"269.011296ms","start":"2026-05-06T05:05:01.564404Z","end":"2026-05-06T05:05:01.833415Z","steps":["trace[342089959] 'agreement among raft nodes before linearized reading' (duration: 268.477743ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:02.297169Z","caller":"traceutil/trace.go:171","msg":"trace[1476114279] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"458.835791ms","start":"2026-05-06T05:05:01.838297Z","end":"2026-05-06T05:05:02.297133Z","steps":["trace[1476114279] 'process raft request' (duration: 458.627916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:02.297585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:01.838275Z","time spent":"458.976615ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:05:02.298708Z","caller":"traceutil/trace.go:171","msg":"trace[1808471915] linearizableReadLoop","detail":"{readStateIndex:904; appliedIndex:901; }","duration":"400.577455ms","start":"2026-05-06T05:05:01.898078Z","end":"2026-05-06T05:05:02.298656Z","steps":["trace[1808471915] 'read index received' (duration: 398.907496ms)","trace[1808471915] 'applied index is now lower than readState.Index' (duration: 1.668659ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:05:02.298762Z","caller":"traceutil/trace.go:171","msg":"trace[68554114] transaction","detail":"{read_only:false; response_revision:869; number_of_response:1; }","duration":"459.914297ms","start":"2026-05-06T05:05:01.838809Z","end":"2026-05-06T05:05:02.298723Z","steps":["trace[68554114] 'process raft request' (duration: 459.61975ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:02.298929Z","caller":"traceutil/trace.go:171","msg":"trace[970170091] transaction","detail":"{read_only:false; response_revision:870; number_of_response:1; }","duration":"459.237331ms","start":"2026-05-06T05:05:01.839648Z","end":"2026-05-06T05:05:02.298885Z","steps":["trace[970170091] 'process raft request' (duration: 458.951674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:02.299001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.907722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6mwb9\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-06T05:05:02.299048Z","caller":"traceutil/trace.go:171","msg":"trace[1035017253] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6mwb9; range_end:; response_count:1; response_revision:870; }","duration":"401.025355ms","start":"2026-05-06T05:05:01.898008Z","end":"2026-05-06T05:05:02.299033Z","steps":["trace[1035017253] 'agreement among raft nodes before linearized reading' (duration: 400.781319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:02.299098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:01.897975Z","time spent":"401.113267ms","remote":"127.0.0.1:57594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-6mwb9\" "} {"level":"warn","ts":"2026-05-06T05:05:02.299129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:01.839638Z","time spent":"459.382564ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:05:02.29896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:01.838796Z","time spent":"460.051ms","remote":"127.0.0.1:57670","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-06T05:05:13.191595Z","caller":"traceutil/trace.go:171","msg":"trace[1259703011] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"137.18313ms","start":"2026-05-06T05:05:13.054351Z","end":"2026-05-06T05:05:13.191534Z","steps":["trace[1259703011] 'process raft request' (duration: 93.624938ms)","trace[1259703011] 'compare' (duration: 43.174753ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:05:37.898198Z","caller":"traceutil/trace.go:171","msg":"trace[661503987] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"604.939806ms","start":"2026-05-06T05:05:37.293229Z","end":"2026-05-06T05:05:37.898169Z","steps":["trace[661503987] 'process raft request' (duration: 604.800693ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:37.898383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:05:37.293202Z","time spent":"605.085589ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:05:38.149099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.309817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-06T05:05:38.149197Z","caller":"traceutil/trace.go:171","msg":"trace[1772850491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1084; }","duration":"102.46674ms","start":"2026-05-06T05:05:38.04671Z","end":"2026-05-06T05:05:38.149177Z","steps":["trace[1772850491] 'range keys from in-memory index tree' (duration: 102.071331ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:03.086149Z","caller":"traceutil/trace.go:171","msg":"trace[1761684869] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"436.541108ms","start":"2026-05-06T05:06:02.649566Z","end":"2026-05-06T05:06:03.086108Z","steps":["trace[1761684869] 'read index received' (duration: 436.425146ms)","trace[1761684869] 'applied index is now lower than readState.Index' (duration: 114.632µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:03.086246Z","caller":"traceutil/trace.go:171","msg":"trace[1055198541] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"436.728954ms","start":"2026-05-06T05:06:02.649478Z","end":"2026-05-06T05:06:03.086207Z","steps":["trace[1055198541] 'process raft request' (duration: 436.453667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:03.086401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:02.649466Z","time spent":"436.854436ms","remote":"127.0.0.1:57670","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-06T05:06:03.086451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.854006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T05:06:03.086498Z","caller":"traceutil/trace.go:171","msg":"trace[643311501] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1165; }","duration":"436.973459ms","start":"2026-05-06T05:06:02.649511Z","end":"2026-05-06T05:06:03.086484Z","steps":["trace[643311501] 'agreement among raft nodes before linearized reading' (duration: 436.743614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:03.086552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:02.649491Z","time spent":"437.050041ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T05:06:03.097154Z","caller":"traceutil/trace.go:171","msg":"trace[600749878] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"444.641502ms","start":"2026-05-06T05:06:02.652487Z","end":"2026-05-06T05:06:03.097128Z","steps":["trace[600749878] 'process raft request' (duration: 444.508169ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:03.097323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:02.652473Z","time spent":"444.759625ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:06:06.301505Z","caller":"traceutil/trace.go:171","msg":"trace[1872114780] transaction","detail":"{read_only:false; response_revision:1176; number_of_response:1; }","duration":"524.623996ms","start":"2026-05-06T05:06:05.776856Z","end":"2026-05-06T05:06:06.30148Z","steps":["trace[1872114780] 'process raft request' (duration: 524.456042ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:06.301646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:05.776829Z","time spent":"524.741439ms","remote":"127.0.0.1:57670","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-06T05:06:06.647863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.316837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:06:06.648018Z","caller":"traceutil/trace.go:171","msg":"trace[191600131] linearizableReadLoop","detail":"{readStateIndex:1225; appliedIndex:1224; }","duration":"264.253879ms","start":"2026-05-06T05:06:06.383739Z","end":"2026-05-06T05:06:06.647993Z","steps":["trace[191600131] 'read index received' (duration: 138.640135ms)","trace[191600131] 'applied index is now lower than readState.Index' (duration: 125.611694ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:06.648165Z","caller":"traceutil/trace.go:171","msg":"trace[433627998] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"568.117845ms","start":"2026-05-06T05:06:06.080015Z","end":"2026-05-06T05:06:06.648133Z","steps":["trace[433627998] 'process raft request' (duration: 442.398849ms)","trace[433627998] 'compare' (duration: 125.03893ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:06:06.648252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:06.07998Z","time spent":"568.234898ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:06:06.648373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.896278ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:06:06.648428Z","caller":"traceutil/trace.go:171","msg":"trace[742613691] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1177; }","duration":"188.99663ms","start":"2026-05-06T05:06:06.459416Z","end":"2026-05-06T05:06:06.648413Z","steps":["trace[742613691] 'agreement among raft nodes before linearized reading' (duration: 188.881217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:06.648759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.993747ms","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-06T05:06:06.648865Z","caller":"traceutil/trace.go:171","msg":"trace[132136132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1177; }","duration":"265.174861ms","start":"2026-05-06T05:06:06.383668Z","end":"2026-05-06T05:06:06.648843Z","steps":["trace[132136132] 'agreement among raft nodes before linearized reading' (duration: 264.879544ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:19.351313Z","caller":"traceutil/trace.go:171","msg":"trace[1380554198] transaction","detail":"{read_only:false; response_revision:1269; number_of_response:1; }","duration":"313.122932ms","start":"2026-05-06T05:06:19.03813Z","end":"2026-05-06T05:06:19.351253Z","steps":["trace[1380554198] 'process raft request' (duration: 312.753854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:19.351675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.52991ms","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-06T05:06:19.351935Z","caller":"traceutil/trace.go:171","msg":"trace[1408912395] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1269; }","duration":"180.830727ms","start":"2026-05-06T05:06:19.171084Z","end":"2026-05-06T05:06:19.351915Z","steps":["trace[1408912395] 'agreement among raft nodes before linearized reading' (duration: 180.454848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:19.351981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:19.038093Z","time spent":"313.744518ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:06:19.351164Z","caller":"traceutil/trace.go:171","msg":"trace[2054134462] linearizableReadLoop","detail":"{readStateIndex:1321; appliedIndex:1320; }","duration":"180.015228ms","start":"2026-05-06T05:06:19.171122Z","end":"2026-05-06T05:06:19.351137Z","steps":["trace[2054134462] 'read index received' (duration: 179.831403ms)","trace[2054134462] 'applied index is now lower than readState.Index' (duration: 182.595µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:19.765965Z","caller":"traceutil/trace.go:171","msg":"trace[432486200] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"409.844407ms","start":"2026-05-06T05:06:19.356087Z","end":"2026-05-06T05:06:19.765931Z","steps":["trace[432486200] 'process raft request' (duration: 409.660403ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:19.766002Z","caller":"traceutil/trace.go:171","msg":"trace[1922692719] linearizableReadLoop","detail":"{readStateIndex:1322; appliedIndex:1321; }","duration":"413.615867ms","start":"2026-05-06T05:06:19.352213Z","end":"2026-05-06T05:06:19.765829Z","steps":["trace[1922692719] 'read index received' (duration: 409.784405ms)","trace[1922692719] 'applied index is now lower than readState.Index' (duration: 3.825531ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:06:19.766171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:19.356065Z","time spent":"410.00278ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:06:19.766464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.646185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-06T05:06:19.766513Z","caller":"traceutil/trace.go:171","msg":"trace[827102866] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1270; }","duration":"514.770077ms","start":"2026-05-06T05:06:19.251722Z","end":"2026-05-06T05:06:19.766492Z","steps":["trace[827102866] 'agreement among raft nodes before linearized reading' (duration: 514.496141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:19.766554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:19.251711Z","time spent":"514.83436ms","remote":"127.0.0.1:57670","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-06T05:06:19.766716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.113704ms","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-06T05:06:19.766742Z","caller":"traceutil/trace.go:171","msg":"trace[869102693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1270; }","duration":"578.158234ms","start":"2026-05-06T05:06:19.188573Z","end":"2026-05-06T05:06:19.766732Z","steps":["trace[869102693] 'agreement among raft nodes before linearized reading' (duration: 578.082122ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:19.766767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:19.188562Z","time spent":"578.198706ms","remote":"127.0.0.1:57670","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-06T05:06:19.766904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.323634ms","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-06T05:06:19.766942Z","caller":"traceutil/trace.go:171","msg":"trace[2055186240] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1270; }","duration":"582.373375ms","start":"2026-05-06T05:06:19.184561Z","end":"2026-05-06T05:06:19.766934Z","steps":["trace[2055186240] 'agreement among raft nodes before linearized reading' (duration: 582.264732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:19.766966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:19.184555Z","time spent":"582.405576ms","remote":"127.0.0.1:57670","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-06T05:06:19.767096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.672383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:06:19.767153Z","caller":"traceutil/trace.go:171","msg":"trace[1639682440] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1270; }","duration":"307.764095ms","start":"2026-05-06T05:06:19.459371Z","end":"2026-05-06T05:06:19.767135Z","steps":["trace[1639682440] 'agreement among raft nodes before linearized reading' (duration: 307.682813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:19.767193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:19.459347Z","time spent":"307.837586ms","remote":"127.0.0.1:57428","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-06T05:06:32.921514Z","caller":"traceutil/trace.go:171","msg":"trace[1949474076] linearizableReadLoop","detail":"{readStateIndex:1368; appliedIndex:1367; }","duration":"118.613258ms","start":"2026-05-06T05:06:32.802879Z","end":"2026-05-06T05:06:32.921492Z","steps":["trace[1949474076] 'read index received' (duration: 118.515236ms)","trace[1949474076] 'applied index is now lower than readState.Index' (duration: 96.662µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:32.921619Z","caller":"traceutil/trace.go:171","msg":"trace[1323159145] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"180.834647ms","start":"2026-05-06T05:06:32.740777Z","end":"2026-05-06T05:06:32.921612Z","steps":["trace[1323159145] 'process raft request' (duration: 180.590871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:32.922183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.261674ms","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-06T05:06:32.922363Z","caller":"traceutil/trace.go:171","msg":"trace[950248529] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1313; }","duration":"119.457919ms","start":"2026-05-06T05:06:32.802811Z","end":"2026-05-06T05:06:32.922269Z","steps":["trace[950248529] 'agreement among raft nodes before linearized reading' (duration: 118.773062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:37.44063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592196172514248917,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T05:06:37.940812Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1592196172514248917,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T05:06:38.028677Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.093792323s","expected-duration":"1s"} {"level":"info","ts":"2026-05-06T05:06:38.029399Z","caller":"traceutil/trace.go:171","msg":"trace[349296149] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"1.094641814s","start":"2026-05-06T05:06:36.93473Z","end":"2026-05-06T05:06:38.029372Z","steps":["trace[349296149] 'process raft request' (duration: 1.09446662s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:38.029611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:36.9347Z","time spent":"1.094802717s","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:06:38.334024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.179357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:06:38.334113Z","caller":"traceutil/trace.go:171","msg":"trace[979154865] linearizableReadLoop","detail":"{readStateIndex:1406; appliedIndex:1404; }","duration":"1.394211902s","start":"2026-05-06T05:06:36.939889Z","end":"2026-05-06T05:06:38.334101Z","steps":["trace[979154865] 'read index received' (duration: 1.089281725s)","trace[979154865] 'applied index is now lower than readState.Index' (duration: 304.929487ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:06:38.334265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.046738097s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-48nmp\" ","response":"range_response_count:1 size:11061"} {"level":"info","ts":"2026-05-06T05:06:38.334285Z","caller":"traceutil/trace.go:171","msg":"trace[1287731582] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-48nmp; range_end:; response_count:1; response_revision:1350; }","duration":"1.046802188s","start":"2026-05-06T05:06:37.287478Z","end":"2026-05-06T05:06:38.334281Z","steps":["trace[1287731582] 'agreement among raft nodes before linearized reading' (duration: 1.046705226s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:38.334306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:37.287455Z","time spent":"1.04684584s","remote":"127.0.0.1:57594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11084,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-48nmp\" "} {"level":"warn","ts":"2026-05-06T05:06:38.334456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.560193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:06:38.33448Z","caller":"traceutil/trace.go:171","msg":"trace[816508026] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1350; }","duration":"112.617355ms","start":"2026-05-06T05:06:38.221856Z","end":"2026-05-06T05:06:38.334474Z","steps":["trace[816508026] 'agreement among raft nodes before linearized reading' (duration: 112.583574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:38.334586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.139821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:06:38.334608Z","caller":"traceutil/trace.go:171","msg":"trace[564874237] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1350; }","duration":"305.199403ms","start":"2026-05-06T05:06:38.029403Z","end":"2026-05-06T05:06:38.334602Z","steps":["trace[564874237] 'agreement among raft nodes before linearized reading' (duration: 305.166882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:38.334621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:38.02938Z","time spent":"305.237334ms","remote":"127.0.0.1:57632","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:06:38.334722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.849391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-06T05:06:38.334743Z","caller":"traceutil/trace.go:171","msg":"trace[1779002300] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1350; }","duration":"420.889331ms","start":"2026-05-06T05:06:37.913848Z","end":"2026-05-06T05:06:38.334738Z","steps":["trace[1779002300] 'agreement among raft nodes before linearized reading' (duration: 420.84182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:38.334756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:37.913838Z","time spent":"420.915112ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T05:06:38.33474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.394622521s","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-06T05:06:38.334852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.803382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T05:06:38.334867Z","caller":"traceutil/trace.go:171","msg":"trace[563160205] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1350; }","duration":"441.845233ms","start":"2026-05-06T05:06:37.893018Z","end":"2026-05-06T05:06:38.334863Z","steps":["trace[563160205] 'agreement among raft nodes before linearized reading' (duration: 441.806082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:38.334881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:37.893004Z","time spent":"441.873663ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T05:06:38.335043Z","caller":"traceutil/trace.go:171","msg":"trace[963465118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1350; }","duration":"1.395034411s","start":"2026-05-06T05:06:36.939825Z","end":"2026-05-06T05:06:38.334859Z","steps":["trace[963465118] 'agreement among raft nodes before linearized reading' (duration: 1.394336624s)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:38.335095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.037714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-06T05:06:38.335115Z","caller":"traceutil/trace.go:171","msg":"trace[1246363182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1350; }","duration":"459.098216ms","start":"2026-05-06T05:06:37.876011Z","end":"2026-05-06T05:06:38.33511Z","steps":["trace[1246363182] 'agreement among raft nodes before linearized reading' (duration: 459.042475ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:38.335118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:36.939799Z","time spent":"1.395284637s","remote":"127.0.0.1:57670","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-06T05:06:38.33513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:37.875987Z","time spent":"459.139536ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T05:06:38.335214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"875.053599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:06:38.335228Z","caller":"traceutil/trace.go:171","msg":"trace[73446523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1350; }","duration":"875.104751ms","start":"2026-05-06T05:06:37.460118Z","end":"2026-05-06T05:06:38.335222Z","steps":["trace[73446523] 'agreement among raft nodes before linearized reading' (duration: 875.08434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:38.335239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:37.460094Z","time spent":"875.143001ms","remote":"127.0.0.1:57424","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-06T05:06:38.33524Z","caller":"traceutil/trace.go:171","msg":"trace[1647120278] transaction","detail":"{read_only:false; response_revision:1350; number_of_response:1; }","duration":"1.176907052s","start":"2026-05-06T05:06:37.15831Z","end":"2026-05-06T05:06:38.335217Z","steps":["trace[1647120278] 'process raft request' (duration: 968.466495ms)","trace[1647120278] 'compare' (duration: 207.095735ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:06:38.335321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:37.158275Z","time spent":"1.177002664s","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:06:38.334965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.935217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T05:06:38.335455Z","caller":"traceutil/trace.go:171","msg":"trace[1238707746] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1350; }","duration":"458.542152ms","start":"2026-05-06T05:06:37.876908Z","end":"2026-05-06T05:06:38.33545Z","steps":["trace[1238707746] 'agreement among raft nodes before linearized reading' (duration: 458.02963ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:38.335469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:37.876881Z","time spent":"458.583523ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-06T05:06:38.521454Z","caller":"traceutil/trace.go:171","msg":"trace[57462426] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"113.426775ms","start":"2026-05-06T05:06:38.408008Z","end":"2026-05-06T05:06:38.521435Z","steps":["trace[57462426] 'process raft request' (duration: 113.168828ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:38.522013Z","caller":"traceutil/trace.go:171","msg":"trace[1473732079] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"108.554417ms","start":"2026-05-06T05:06:38.413448Z","end":"2026-05-06T05:06:38.522002Z","steps":["trace[1473732079] 'process raft request' (duration: 107.800149ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:39.641931Z","caller":"traceutil/trace.go:171","msg":"trace[1717402965] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"454.6808ms","start":"2026-05-06T05:06:39.187177Z","end":"2026-05-06T05:06:39.641858Z","steps":["trace[1717402965] 'process raft request' (duration: 453.360369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:39.642173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:39.187154Z","time spent":"454.887855ms","remote":"127.0.0.1:57580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5862,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:06:40.04471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.174136ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-06T05:06:40.045165Z","caller":"traceutil/trace.go:171","msg":"trace[1755809680] linearizableReadLoop","detail":"{readStateIndex:1417; appliedIndex:1415; }","duration":"586.75657ms","start":"2026-05-06T05:06:39.458341Z","end":"2026-05-06T05:06:40.045098Z","steps":["trace[1755809680] 'read index received' (duration: 182.19686ms)","trace[1755809680] 'applied index is now lower than readState.Index' (duration: 404.55674ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:40.045337Z","caller":"traceutil/trace.go:171","msg":"trace[506180996] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"546.790434ms","start":"2026-05-06T05:06:39.498468Z","end":"2026-05-06T05:06:40.045259Z","steps":["trace[506180996] 'process raft request' (duration: 546.388394ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:40.046487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:39.498435Z","time spent":"547.184454ms","remote":"127.0.0.1:57670","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-06T05:06:40.203076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.716431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:06:40.203177Z","caller":"traceutil/trace.go:171","msg":"trace[1779903727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1361; }","duration":"744.860423ms","start":"2026-05-06T05:06:39.458292Z","end":"2026-05-06T05:06:40.203152Z","steps":["trace[1779903727] 'agreement among raft nodes before linearized reading' (duration: 586.987605ms)","trace[1779903727] 'range keys from in-memory index tree' (duration: 157.733414ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:06:40.203219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:39.458271Z","time spent":"744.938996ms","remote":"127.0.0.1:57428","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-06T05:06:40.203489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.309695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:06:40.203568Z","caller":"traceutil/trace.go:171","msg":"trace[1611192205] linearizableReadLoop","detail":"{readStateIndex:1419; appliedIndex:1418; }","duration":"158.309788ms","start":"2026-05-06T05:06:40.045245Z","end":"2026-05-06T05:06:40.203555Z","steps":["trace[1611192205] 'read index received' (duration: 10.790008ms)","trace[1611192205] 'applied index is now lower than readState.Index' (duration: 147.51876ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:06:40.20366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.821762ms","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-06T05:06:40.203674Z","caller":"traceutil/trace.go:171","msg":"trace[1081650692] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"167.191611ms","start":"2026-05-06T05:06:40.03646Z","end":"2026-05-06T05:06:40.203651Z","steps":["trace[1081650692] 'process raft request' (duration: 19.62862ms)","trace[1081650692] 'compare' (duration: 147.161542ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:40.203691Z","caller":"traceutil/trace.go:171","msg":"trace[1423274697] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1362; }","duration":"479.883993ms","start":"2026-05-06T05:06:39.723794Z","end":"2026-05-06T05:06:40.203678Z","steps":["trace[1423274697] 'agreement among raft nodes before linearized reading' (duration: 479.799011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:40.203724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:39.723775Z","time spent":"479.940365ms","remote":"127.0.0.1:36854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:06:40.484695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.213692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-06T05:06:40.484801Z","caller":"traceutil/trace.go:171","msg":"trace[1900409618] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1362; }","duration":"119.384246ms","start":"2026-05-06T05:06:40.365395Z","end":"2026-05-06T05:06:40.484779Z","steps":["trace[1900409618] 'range keys from in-memory index tree' (duration: 118.985507ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:40.774197Z","caller":"traceutil/trace.go:171","msg":"trace[503048798] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"161.680039ms","start":"2026-05-06T05:06:40.612489Z","end":"2026-05-06T05:06:40.774169Z","steps":["trace[503048798] 'process raft request' (duration: 161.477384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:41.22101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.905077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:06:41.221129Z","caller":"traceutil/trace.go:171","msg":"trace[1587728176] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"591.237238ms","start":"2026-05-06T05:06:40.629871Z","end":"2026-05-06T05:06:41.221109Z","steps":["trace[1587728176] 'process raft request' (duration: 240.138756ms)","trace[1587728176] 'compare' (duration: 350.689302ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:06:41.221187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:40.629852Z","time spent":"591.308259ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:06:42.406494Z","caller":"traceutil/trace.go:171","msg":"trace[520271080] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"197.083496ms","start":"2026-05-06T05:06:42.20938Z","end":"2026-05-06T05:06:42.406464Z","steps":["trace[520271080] 'process raft request' (duration: 196.876601ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:50.874543Z","caller":"traceutil/trace.go:171","msg":"trace[1815131004] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"110.856762ms","start":"2026-05-06T05:06:50.763657Z","end":"2026-05-06T05:06:50.874514Z","steps":["trace[1815131004] 'process raft request' (duration: 110.808511ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:50.874908Z","caller":"traceutil/trace.go:171","msg":"trace[450091376] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"111.316884ms","start":"2026-05-06T05:06:50.763558Z","end":"2026-05-06T05:06:50.874875Z","steps":["trace[450091376] 'process raft request' (duration: 110.857013ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:50.875113Z","caller":"traceutil/trace.go:171","msg":"trace[861705239] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"111.741224ms","start":"2026-05-06T05:06:50.763334Z","end":"2026-05-06T05:06:50.875075Z","steps":["trace[861705239] 'process raft request' (duration: 57.489196ms)","trace[861705239] 'compare' (duration: 53.371577ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:52.674769Z","caller":"traceutil/trace.go:171","msg":"trace[2120572118] transaction","detail":"{read_only:false; response_revision:1443; number_of_response:1; }","duration":"173.304377ms","start":"2026-05-06T05:06:52.501427Z","end":"2026-05-06T05:06:52.674731Z","steps":["trace[2120572118] 'process raft request' (duration: 173.02609ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:52.805667Z","caller":"traceutil/trace.go:171","msg":"trace[260323008] transaction","detail":"{read_only:false; response_revision:1444; number_of_response:1; }","duration":"126.053186ms","start":"2026-05-06T05:06:52.679578Z","end":"2026-05-06T05:06:52.805631Z","steps":["trace[260323008] 'process raft request' (duration: 123.600207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:53.412859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.357684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:06:53.41302Z","caller":"traceutil/trace.go:171","msg":"trace[65147525] transaction","detail":"{read_only:false; response_revision:1445; number_of_response:1; }","duration":"601.611366ms","start":"2026-05-06T05:06:52.811383Z","end":"2026-05-06T05:06:53.412994Z","steps":["trace[65147525] 'process raft request' (duration: 133.969225ms)","trace[65147525] 'compare' (duration: 466.598006ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:06:53.413115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:52.811362Z","time spent":"601.703378ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:06:53.413324Z","caller":"traceutil/trace.go:171","msg":"trace[1196209260] linearizableReadLoop","detail":"{readStateIndex:1505; appliedIndex:1503; }","duration":"534.317646ms","start":"2026-05-06T05:06:52.878979Z","end":"2026-05-06T05:06:53.413296Z","steps":["trace[1196209260] 'read index received' (duration: 66.388619ms)","trace[1196209260] 'applied index is now lower than readState.Index' (duration: 467.927977ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:53.41341Z","caller":"traceutil/trace.go:171","msg":"trace[543272285] transaction","detail":"{read_only:false; response_revision:1446; number_of_response:1; }","duration":"601.653417ms","start":"2026-05-06T05:06:52.81174Z","end":"2026-05-06T05:06:53.413393Z","steps":["trace[543272285] 'process raft request' (duration: 601.415352ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:53.413637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:52.811717Z","time spent":"601.76925ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:06:53.413694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.174023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T05:06:53.413764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.07464ms","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-06T05:06:53.413826Z","caller":"traceutil/trace.go:171","msg":"trace[1588569568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1446; }","duration":"534.211333ms","start":"2026-05-06T05:06:52.879599Z","end":"2026-05-06T05:06:53.41381Z","steps":["trace[1588569568] 'agreement among raft nodes before linearized reading' (duration: 534.002908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:53.413877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:52.879566Z","time spent":"534.298886ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-06T05:06:53.413879Z","caller":"traceutil/trace.go:171","msg":"trace[1390929102] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1446; }","duration":"328.309106ms","start":"2026-05-06T05:06:53.085457Z","end":"2026-05-06T05:06:53.413766Z","steps":["trace[1390929102] 'agreement among raft nodes before linearized reading' (duration: 328.06758ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:53.413927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:53.085334Z","time spent":"328.582243ms","remote":"127.0.0.1:36886","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:06:53.413867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.88106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-06T05:06:53.41404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.588593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-06T05:06:53.414046Z","caller":"traceutil/trace.go:171","msg":"trace[2077596365] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1446; }","duration":"535.112616ms","start":"2026-05-06T05:06:52.878919Z","end":"2026-05-06T05:06:53.414032Z","steps":["trace[2077596365] 'agreement among raft nodes before linearized reading' (duration: 534.525812ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:53.41409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:52.878895Z","time spent":"535.183147ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T05:06:53.414097Z","caller":"traceutil/trace.go:171","msg":"trace[586440137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1446; }","duration":"534.637463ms","start":"2026-05-06T05:06:52.879429Z","end":"2026-05-06T05:06:53.414066Z","steps":["trace[586440137] 'agreement among raft nodes before linearized reading' (duration: 534.053719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:53.414124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:06:52.879422Z","time spent":"534.695586ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-06T05:06:54.81788Z","caller":"traceutil/trace.go:171","msg":"trace[53216713] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"143.830962ms","start":"2026-05-06T05:06:54.674018Z","end":"2026-05-06T05:06:54.817849Z","steps":["trace[53216713] 'process raft request' (duration: 143.540135ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:54.932268Z","caller":"traceutil/trace.go:171","msg":"trace[1256015018] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"252.647925ms","start":"2026-05-06T05:06:54.679582Z","end":"2026-05-06T05:06:54.93223Z","steps":["trace[1256015018] 'process raft request' (duration: 172.432896ms)","trace[1256015018] 'compare' (duration: 80.046735ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:55.749165Z","caller":"traceutil/trace.go:171","msg":"trace[1828878667] transaction","detail":"{read_only:false; response_revision:1458; number_of_response:1; }","duration":"217.30013ms","start":"2026-05-06T05:06:55.531834Z","end":"2026-05-06T05:06:55.749134Z","steps":["trace[1828878667] 'process raft request' (duration: 217.232188ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:55.749388Z","caller":"traceutil/trace.go:171","msg":"trace[2014596846] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"217.631088ms","start":"2026-05-06T05:06:55.531747Z","end":"2026-05-06T05:06:55.749378Z","steps":["trace[2014596846] 'process raft request' (duration: 217.140906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:56.212629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.377117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:06:56.212899Z","caller":"traceutil/trace.go:171","msg":"trace[860769373] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"132.501971ms","start":"2026-05-06T05:06:56.08037Z","end":"2026-05-06T05:06:56.212872Z","steps":["trace[860769373] 'process raft request' (duration: 27.744014ms)","trace[860769373] 'compare' (duration: 104.223604ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:58.071959Z","caller":"traceutil/trace.go:171","msg":"trace[1630180457] linearizableReadLoop","detail":"{readStateIndex:1528; appliedIndex:1527; }","duration":"164.190529ms","start":"2026-05-06T05:06:57.90774Z","end":"2026-05-06T05:06:58.071931Z","steps":["trace[1630180457] 'read index received' (duration: 163.879381ms)","trace[1630180457] 'applied index is now lower than readState.Index' (duration: 310.218µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:58.07209Z","caller":"traceutil/trace.go:171","msg":"trace[846985398] transaction","detail":"{read_only:false; response_revision:1468; number_of_response:1; }","duration":"214.201276ms","start":"2026-05-06T05:06:57.857864Z","end":"2026-05-06T05:06:58.072065Z","steps":["trace[846985398] 'process raft request' (duration: 213.827717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:58.07214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.367963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T05:06:58.072232Z","caller":"traceutil/trace.go:171","msg":"trace[2109706687] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1468; }","duration":"164.532417ms","start":"2026-05-06T05:06:57.90768Z","end":"2026-05-06T05:06:58.072212Z","steps":["trace[2109706687] 'agreement among raft nodes before linearized reading' (duration: 164.373063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:58.477929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.273038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-06T05:06:58.47883Z","caller":"traceutil/trace.go:171","msg":"trace[902159875] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1468; }","duration":"260.629576ms","start":"2026-05-06T05:06:58.2176Z","end":"2026-05-06T05:06:58.478229Z","steps":["trace[902159875] 'range keys from in-memory index tree' (duration: 259.649524ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:58.658217Z","caller":"traceutil/trace.go:171","msg":"trace[60066906] transaction","detail":"{read_only:false; response_revision:1469; number_of_response:1; }","duration":"174.995797ms","start":"2026-05-06T05:06:58.483191Z","end":"2026-05-06T05:06:58.658187Z","steps":["trace[60066906] 'process raft request' (duration: 174.666219ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:06:59.392725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.764049ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-06T05:06:59.740912Z","caller":"traceutil/trace.go:171","msg":"trace[1635893033] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"140.418689ms","start":"2026-05-06T05:06:59.600446Z","end":"2026-05-06T05:06:59.740865Z","steps":["trace[1635893033] 'process raft request' (duration: 44.775651ms)","trace[1635893033] 'compare' (duration: 95.484614ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:06:59.90177Z","caller":"traceutil/trace.go:171","msg":"trace[712106159] transaction","detail":"{read_only:false; response_revision:1475; number_of_response:1; }","duration":"108.428325ms","start":"2026-05-06T05:06:59.793304Z","end":"2026-05-06T05:06:59.901732Z","steps":["trace[712106159] 'process raft request' (duration: 104.358827ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:06:59.91939Z","caller":"traceutil/trace.go:171","msg":"trace[1531648091] transaction","detail":"{read_only:false; response_revision:1476; number_of_response:1; }","duration":"123.808602ms","start":"2026-05-06T05:06:59.795552Z","end":"2026-05-06T05:06:59.919361Z","steps":["trace[1531648091] 'process raft request' (duration: 123.418403ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:01.138449Z","caller":"traceutil/trace.go:171","msg":"trace[231190176] transaction","detail":"{read_only:false; response_revision:1480; number_of_response:1; }","duration":"119.226463ms","start":"2026-05-06T05:07:01.019187Z","end":"2026-05-06T05:07:01.138413Z","steps":["trace[231190176] 'process raft request' (duration: 118.968057ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:02.133312Z","caller":"traceutil/trace.go:171","msg":"trace[666461599] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"165.099301ms","start":"2026-05-06T05:07:01.968172Z","end":"2026-05-06T05:07:02.133271Z","steps":["trace[666461599] 'process raft request' (duration: 164.934027ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:02.214232Z","caller":"traceutil/trace.go:171","msg":"trace[45098665] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"245.783261ms","start":"2026-05-06T05:07:01.968264Z","end":"2026-05-06T05:07:02.214047Z","steps":["trace[45098665] 'process raft request' (duration: 196.82708ms)","trace[45098665] 'compare' (duration: 48.780277ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:03.151743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.109378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T05:07:03.151819Z","caller":"traceutil/trace.go:171","msg":"trace[1801156766] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1488; }","duration":"167.248872ms","start":"2026-05-06T05:07:02.984553Z","end":"2026-05-06T05:07:03.151802Z","steps":["trace[1801156766] 'count revisions from in-memory index tree' (duration: 167.013886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:03.437524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.942117ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:03.437656Z","caller":"traceutil/trace.go:171","msg":"trace[9873354] transaction","detail":"{read_only:false; response_revision:1490; number_of_response:1; }","duration":"280.882551ms","start":"2026-05-06T05:07:03.15675Z","end":"2026-05-06T05:07:03.437633Z","steps":["trace[9873354] 'process raft request' (duration: 97.719769ms)","trace[9873354] 'compare' (duration: 182.665681ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:03.918341Z","caller":"traceutil/trace.go:171","msg":"trace[327389365] linearizableReadLoop","detail":"{readStateIndex:1553; appliedIndex:1552; }","duration":"114.397478ms","start":"2026-05-06T05:07:03.803907Z","end":"2026-05-06T05:07:03.918304Z","steps":["trace[327389365] 'read index received' (duration: 114.07409ms)","trace[327389365] 'applied index is now lower than readState.Index' (duration: 322.138µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:03.918718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.791717ms","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-06T05:07:03.918881Z","caller":"traceutil/trace.go:171","msg":"trace[577937806] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"123.358962ms","start":"2026-05-06T05:07:03.795147Z","end":"2026-05-06T05:07:03.918506Z","steps":["trace[577937806] 'process raft request' (duration: 122.941042ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:03.918937Z","caller":"traceutil/trace.go:171","msg":"trace[538061272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1492; }","duration":"115.044003ms","start":"2026-05-06T05:07:03.80387Z","end":"2026-05-06T05:07:03.918914Z","steps":["trace[538061272] 'agreement among raft nodes before linearized reading' (duration: 114.685495ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:04.098862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.615033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.163\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-06T05:07:04.099133Z","caller":"traceutil/trace.go:171","msg":"trace[589607445] range","detail":"{range_begin:/registry/masterleases/199.19.213.163; range_end:; response_count:1; response_revision:1493; }","duration":"104.988642ms","start":"2026-05-06T05:07:03.994124Z","end":"2026-05-06T05:07:04.099113Z","steps":["trace[589607445] 'range keys from in-memory index tree' (duration: 103.905556ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:04.341555Z","caller":"traceutil/trace.go:171","msg":"trace[290275108] linearizableReadLoop","detail":"{readStateIndex:1557; appliedIndex:1555; }","duration":"121.598578ms","start":"2026-05-06T05:07:04.219922Z","end":"2026-05-06T05:07:04.341521Z","steps":["trace[290275108] 'read index received' (duration: 38.586232ms)","trace[290275108] 'applied index is now lower than readState.Index' (duration: 83.010966ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:04.341837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.892615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T05:07:04.341868Z","caller":"traceutil/trace.go:171","msg":"trace[84719656] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1495; }","duration":"121.975908ms","start":"2026-05-06T05:07:04.219886Z","end":"2026-05-06T05:07:04.341862Z","steps":["trace[84719656] 'agreement among raft nodes before linearized reading' (duration: 121.761082ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:04.342056Z","caller":"traceutil/trace.go:171","msg":"trace[856840771] transaction","detail":"{read_only:false; response_revision:1494; number_of_response:1; }","duration":"152.788765ms","start":"2026-05-06T05:07:04.189243Z","end":"2026-05-06T05:07:04.342032Z","steps":["trace[856840771] 'process raft request' (duration: 69.176304ms)","trace[856840771] 'compare' (duration: 82.616077ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:04.342065Z","caller":"traceutil/trace.go:171","msg":"trace[1365903057] transaction","detail":"{read_only:false; response_revision:1495; number_of_response:1; }","duration":"149.412394ms","start":"2026-05-06T05:07:04.192604Z","end":"2026-05-06T05:07:04.342016Z","steps":["trace[1365903057] 'process raft request' (duration: 148.864791ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:05.182809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.095148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-06T05:07:05.182928Z","caller":"traceutil/trace.go:171","msg":"trace[638523260] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1497; }","duration":"238.288472ms","start":"2026-05-06T05:07:04.944611Z","end":"2026-05-06T05:07:05.182899Z","steps":["trace[638523260] 'range keys from in-memory index tree' (duration: 237.76958ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:05.360357Z","caller":"traceutil/trace.go:171","msg":"trace[434106807] transaction","detail":"{read_only:false; response_revision:1498; number_of_response:1; }","duration":"171.047654ms","start":"2026-05-06T05:07:05.189274Z","end":"2026-05-06T05:07:05.360322Z","steps":["trace[434106807] 'process raft request' (duration: 170.830788ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:05.541437Z","caller":"traceutil/trace.go:171","msg":"trace[1245363858] transaction","detail":"{read_only:false; response_revision:1499; number_of_response:1; }","duration":"333.791267ms","start":"2026-05-06T05:07:05.207603Z","end":"2026-05-06T05:07:05.541395Z","steps":["trace[1245363858] 'process raft request' (duration: 242.546504ms)","trace[1245363858] 'compare' (duration: 90.932756ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:05.541676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:07:05.207585Z","time spent":"333.962091ms","remote":"127.0.0.1:57670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:07:06.333979Z","caller":"traceutil/trace.go:171","msg":"trace[1979116616] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"113.576768ms","start":"2026-05-06T05:07:06.220358Z","end":"2026-05-06T05:07:06.333935Z","steps":["trace[1979116616] 'process raft request' (duration: 113.278041ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:08.967569Z","caller":"traceutil/trace.go:171","msg":"trace[1864055778] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"101.723764ms","start":"2026-05-06T05:07:08.865815Z","end":"2026-05-06T05:07:08.967539Z","steps":["trace[1864055778] 'process raft request' (duration: 101.524749ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:10.198385Z","caller":"traceutil/trace.go:171","msg":"trace[1931887802] transaction","detail":"{read_only:false; response_revision:1520; number_of_response:1; }","duration":"121.719503ms","start":"2026-05-06T05:07:10.07664Z","end":"2026-05-06T05:07:10.19836Z","steps":["trace[1931887802] 'process raft request' (duration: 121.451326ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:10.558351Z","caller":"traceutil/trace.go:171","msg":"trace[761819013] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"101.397796ms","start":"2026-05-06T05:07:10.456912Z","end":"2026-05-06T05:07:10.55831Z","steps":["trace[761819013] 'process raft request' (duration: 91.908479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:10.787817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.484955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:10.787953Z","caller":"traceutil/trace.go:171","msg":"trace[901845091] linearizableReadLoop","detail":"{readStateIndex:1586; appliedIndex:1585; }","duration":"181.105013ms","start":"2026-05-06T05:07:10.606818Z","end":"2026-05-06T05:07:10.787923Z","steps":["trace[901845091] 'read index received' (duration: 57.27957ms)","trace[901845091] 'applied index is now lower than readState.Index' (duration: 123.823883ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:10.788119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.309489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-06T05:07:10.788078Z","caller":"traceutil/trace.go:171","msg":"trace[791619202] transaction","detail":"{read_only:false; response_revision:1523; number_of_response:1; }","duration":"222.478664ms","start":"2026-05-06T05:07:10.565538Z","end":"2026-05-06T05:07:10.788017Z","steps":["trace[791619202] 'process raft request' (duration: 98.671871ms)","trace[791619202] 'compare' (duration: 123.332291ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:10.788156Z","caller":"traceutil/trace.go:171","msg":"trace[1685636737] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1523; }","duration":"181.398701ms","start":"2026-05-06T05:07:10.606746Z","end":"2026-05-06T05:07:10.788145Z","steps":["trace[1685636737] 'agreement among raft nodes before linearized reading' (duration: 181.249907ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:10.932507Z","caller":"traceutil/trace.go:171","msg":"trace[1530146936] linearizableReadLoop","detail":"{readStateIndex:1588; appliedIndex:1587; }","duration":"109.51119ms","start":"2026-05-06T05:07:10.822962Z","end":"2026-05-06T05:07:10.932473Z","steps":["trace[1530146936] 'read index received' (duration: 68.233953ms)","trace[1530146936] 'applied index is now lower than readState.Index' (duration: 41.275297ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:10.93289Z","caller":"traceutil/trace.go:171","msg":"trace[1266549362] transaction","detail":"{read_only:false; response_revision:1525; number_of_response:1; }","duration":"139.321023ms","start":"2026-05-06T05:07:10.793534Z","end":"2026-05-06T05:07:10.932855Z","steps":["trace[1266549362] 'process raft request' (duration: 97.81082ms)","trace[1266549362] 'compare' (duration: 40.971431ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:10.932936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.93164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-06T05:07:10.933126Z","caller":"traceutil/trace.go:171","msg":"trace[1821152031] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1525; }","duration":"110.207346ms","start":"2026-05-06T05:07:10.822899Z","end":"2026-05-06T05:07:10.933106Z","steps":["trace[1821152031] 'agreement among raft nodes before linearized reading' (duration: 109.692874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:11.14843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.946793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:11.148645Z","caller":"traceutil/trace.go:171","msg":"trace[667896559] transaction","detail":"{read_only:false; response_revision:1526; number_of_response:1; }","duration":"170.862148ms","start":"2026-05-06T05:07:10.977718Z","end":"2026-05-06T05:07:11.14858Z","steps":["trace[667896559] 'process raft request' (duration: 62.494855ms)","trace[667896559] 'compare' (duration: 107.79798ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:14.251738Z","caller":"traceutil/trace.go:171","msg":"trace[158186721] linearizableReadLoop","detail":"{readStateIndex:1604; appliedIndex:1603; }","duration":"155.320906ms","start":"2026-05-06T05:07:14.096394Z","end":"2026-05-06T05:07:14.251715Z","steps":["trace[158186721] 'read index received' (duration: 155.108741ms)","trace[158186721] 'applied index is now lower than readState.Index' (duration: 210.855µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:14.251869Z","caller":"traceutil/trace.go:171","msg":"trace[1152524834] transaction","detail":"{read_only:false; response_revision:1540; number_of_response:1; }","duration":"178.87958ms","start":"2026-05-06T05:07:14.072956Z","end":"2026-05-06T05:07:14.251836Z","steps":["trace[1152524834] 'process raft request' (duration: 178.600463ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:14.251973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.560943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-06T05:07:14.252332Z","caller":"traceutil/trace.go:171","msg":"trace[1708665371] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1540; }","duration":"155.972902ms","start":"2026-05-06T05:07:14.096322Z","end":"2026-05-06T05:07:14.252295Z","steps":["trace[1708665371] 'agreement among raft nodes before linearized reading' (duration: 155.48661ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:15.893286Z","caller":"traceutil/trace.go:171","msg":"trace[1840817394] transaction","detail":"{read_only:false; response_revision:1546; number_of_response:1; }","duration":"169.178159ms","start":"2026-05-06T05:07:15.724069Z","end":"2026-05-06T05:07:15.893247Z","steps":["trace[1840817394] 'process raft request' (duration: 168.974764ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:16.048925Z","caller":"traceutil/trace.go:171","msg":"trace[758801949] transaction","detail":"{read_only:false; response_revision:1547; number_of_response:1; }","duration":"127.197464ms","start":"2026-05-06T05:07:15.9217Z","end":"2026-05-06T05:07:16.048898Z","steps":["trace[758801949] 'process raft request' (duration: 89.033091ms)","trace[758801949] 'compare' (duration: 38.0465ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:17.338961Z","caller":"traceutil/trace.go:171","msg":"trace[2105703928] linearizableReadLoop","detail":"{readStateIndex:1617; appliedIndex:1616; }","duration":"106.660112ms","start":"2026-05-06T05:07:17.232276Z","end":"2026-05-06T05:07:17.338936Z","steps":["trace[2105703928] 'read index received' (duration: 106.423927ms)","trace[2105703928] 'applied index is now lower than readState.Index' (duration: 235.125µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:07:17.339216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.914728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-05-06T05:07:17.339255Z","caller":"traceutil/trace.go:171","msg":"trace[1545166609] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1553; }","duration":"107.021481ms","start":"2026-05-06T05:07:17.232219Z","end":"2026-05-06T05:07:17.33924Z","steps":["trace[1545166609] 'agreement among raft nodes before linearized reading' (duration: 106.827206ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:17.339193Z","caller":"traceutil/trace.go:171","msg":"trace[317057017] transaction","detail":"{read_only:false; response_revision:1553; number_of_response:1; }","duration":"154.604959ms","start":"2026-05-06T05:07:17.184533Z","end":"2026-05-06T05:07:17.339138Z","steps":["trace[317057017] 'process raft request' (duration: 154.246991ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:18.157949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.457025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-06T05:07:18.158043Z","caller":"traceutil/trace.go:171","msg":"trace[1292478119] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1555; }","duration":"103.624689ms","start":"2026-05-06T05:07:18.054398Z","end":"2026-05-06T05:07:18.158022Z","steps":["trace[1292478119] 'range keys from in-memory index tree' (duration: 103.215119ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:18.442989Z","caller":"traceutil/trace.go:171","msg":"trace[1242033629] linearizableReadLoop","detail":"{readStateIndex:1621; appliedIndex:1620; }","duration":"117.424178ms","start":"2026-05-06T05:07:18.32554Z","end":"2026-05-06T05:07:18.442964Z","steps":["trace[1242033629] 'read index received' (duration: 117.185792ms)","trace[1242033629] 'applied index is now lower than readState.Index' (duration: 237.356µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:18.44319Z","caller":"traceutil/trace.go:171","msg":"trace[791666670] transaction","detail":"{read_only:false; response_revision:1557; number_of_response:1; }","duration":"137.777143ms","start":"2026-05-06T05:07:18.305318Z","end":"2026-05-06T05:07:18.443095Z","steps":["trace[791666670] 'process raft request' (duration: 137.474836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:18.443354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.795767ms","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-06T05:07:18.44342Z","caller":"traceutil/trace.go:171","msg":"trace[2065740687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1557; }","duration":"118.535214ms","start":"2026-05-06T05:07:18.324867Z","end":"2026-05-06T05:07:18.443402Z","steps":["trace[2065740687] 'agreement among raft nodes before linearized reading' (duration: 118.210637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:18.661328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.631692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-06T05:07:18.661424Z","caller":"traceutil/trace.go:171","msg":"trace[14424126] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1558; }","duration":"151.788426ms","start":"2026-05-06T05:07:18.509614Z","end":"2026-05-06T05:07:18.661402Z","steps":["trace[14424126] 'range keys from in-memory index tree' (duration: 151.429077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:20.66994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.815611ms","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-06T05:07:20.670038Z","caller":"traceutil/trace.go:171","msg":"trace[711636819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1566; }","duration":"170.955884ms","start":"2026-05-06T05:07:20.49906Z","end":"2026-05-06T05:07:20.670016Z","steps":["trace[711636819] 'range keys from in-memory index tree' (duration: 170.652297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:20.670038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.303967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-06T05:07:20.670158Z","caller":"traceutil/trace.go:171","msg":"trace[749655813] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1566; }","duration":"146.46004ms","start":"2026-05-06T05:07:20.523642Z","end":"2026-05-06T05:07:20.670102Z","steps":["trace[749655813] 'range keys from in-memory index tree' (duration: 146.008189ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:20.786198Z","caller":"traceutil/trace.go:171","msg":"trace[784933990] transaction","detail":"{read_only:false; response_revision:1568; number_of_response:1; }","duration":"110.043332ms","start":"2026-05-06T05:07:20.67613Z","end":"2026-05-06T05:07:20.786173Z","steps":["trace[784933990] 'process raft request' (duration: 109.331145ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:07:21.158848Z","caller":"traceutil/trace.go:171","msg":"trace[124944324] transaction","detail":"{read_only:false; response_revision:1570; number_of_response:1; }","duration":"116.522587ms","start":"2026-05-06T05:07:21.042277Z","end":"2026-05-06T05:07:21.1588Z","steps":["trace[124944324] 'process raft request' (duration: 74.129167ms)","trace[124944324] 'compare' (duration: 42.220856ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:31.719048Z","caller":"traceutil/trace.go:171","msg":"trace[1298384715] transaction","detail":"{read_only:false; response_revision:1619; number_of_response:1; }","duration":"121.158667ms","start":"2026-05-06T05:07:31.597862Z","end":"2026-05-06T05:07:31.71902Z","steps":["trace[1298384715] 'process raft request' (duration: 121.005963ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:08:41.429946Z","caller":"traceutil/trace.go:171","msg":"trace[1932383272] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"104.141116ms","start":"2026-05-06T05:08:41.325775Z","end":"2026-05-06T05:08:41.429916Z","steps":["trace[1932383272] 'process raft request' (duration: 103.979872ms)"],"step_count":1}