{"level":"warn","ts":"2026-05-05T16:55:16.206066Z","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-05T16:55:16.206181Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.223:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.223:2380","--initial-cluster=instance=https://199.204.45.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.223: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-05T16:55:16.206268Z","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-05T16:55:16.206284Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-05-05T16:55:16.206307Z","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-05T16:55:16.207056Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"]} {"level":"info","ts":"2026-05-05T16:55:16.207139Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.223: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-05T16:55:16.21536Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.983318ms"} {"level":"info","ts":"2026-05-05T16:55:16.224706Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"11f26225a5bb9fe3","cluster-id":"75c25101f01fd4c6"} {"level":"info","ts":"2026-05-05T16:55:16.224837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T16:55:16.224896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 0"} {"level":"info","ts":"2026-05-05T16:55:16.224919Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 11f26225a5bb9fe3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T16:55:16.224934Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became follower at term 1"} {"level":"info","ts":"2026-05-05T16:55:16.225007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"warn","ts":"2026-05-05T16:55:16.233434Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T16:55:16.236929Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T16:55:16.238Z","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-05T16:55:16.240543Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"11f26225a5bb9fe3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T16:55:16.2407Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"11f26225a5bb9fe3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T16:55:16.240802Z","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-05T16:55:16.240896Z","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-05T16:55:16.240908Z","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-05T16:55:16.241742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 switched to configuration voters=(1293203956842602467)"} {"level":"info","ts":"2026-05-05T16:55:16.241896Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","added-peer-id":"11f26225a5bb9fe3","added-peer-peer-urls":["https://199.204.45.223:2380"]} {"level":"info","ts":"2026-05-05T16:55:16.24549Z","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-05T16:55:16.24619Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-05-05T16:55:16.246227Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.223:2380"} {"level":"info","ts":"2026-05-05T16:55:16.247083Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"11f26225a5bb9fe3","initial-advertise-peer-urls":["https://199.204.45.223:2380"],"listen-peer-urls":["https://199.204.45.223:2380"],"advertise-client-urls":["https://199.204.45.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T16:55:16.247126Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T16:55:17.125643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T16:55:17.125712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T16:55:17.125736Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgPreVoteResp from 11f26225a5bb9fe3 at term 1"} {"level":"info","ts":"2026-05-05T16:55:17.125756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became candidate at term 2"} {"level":"info","ts":"2026-05-05T16:55:17.125768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 received MsgVoteResp from 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-05-05T16:55:17.125787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"11f26225a5bb9fe3 became leader at term 2"} {"level":"info","ts":"2026-05-05T16:55:17.125814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 11f26225a5bb9fe3 elected leader 11f26225a5bb9fe3 at term 2"} {"level":"info","ts":"2026-05-05T16:55:17.128827Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:55:17.130047Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"11f26225a5bb9fe3","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.223:2379]}","request-path":"/0/members/11f26225a5bb9fe3/attributes","cluster-id":"75c25101f01fd4c6","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T16:55:17.130118Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T16:55:17.130109Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T16:55:17.130523Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T16:55:17.130548Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"75c25101f01fd4c6","local-member-id":"11f26225a5bb9fe3","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:55:17.130583Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T16:55:17.130746Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:55:17.130797Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:55:17.133594Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.223:2379"} {"level":"info","ts":"2026-05-05T16:55:17.134145Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T16:55:21.65982Z","caller":"traceutil/trace.go:171","msg":"trace[1310786850] linearizableReadLoop","detail":"{readStateIndex:252; appliedIndex:251; }","duration":"491.958841ms","start":"2026-05-05T16:55:21.167829Z","end":"2026-05-05T16:55:21.659788Z","steps":["trace[1310786850] 'read index received' (duration: 491.764264ms)","trace[1310786850] 'applied index is now lower than readState.Index' (duration: 192.997µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:55:21.659949Z","caller":"traceutil/trace.go:171","msg":"trace[1114026203] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"747.518781ms","start":"2026-05-05T16:55:20.912384Z","end":"2026-05-05T16:55:21.659903Z","steps":["trace[1114026203] 'process raft request' (duration: 747.199421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:21.660116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.483204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T16:55:21.660144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.219119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-05-05T16:55:21.660195Z","caller":"traceutil/trace.go:171","msg":"trace[1622796268] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:246; }","duration":"353.680849ms","start":"2026-05-05T16:55:21.3065Z","end":"2026-05-05T16:55:21.660181Z","steps":["trace[1622796268] 'agreement among raft nodes before linearized reading' (duration: 353.488523ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:21.660206Z","caller":"traceutil/trace.go:171","msg":"trace[1261991726] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:246; }","duration":"492.407085ms","start":"2026-05-05T16:55:21.167784Z","end":"2026-05-05T16:55:21.660191Z","steps":["trace[1261991726] 'agreement among raft nodes before linearized reading' (duration: 492.131486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:21.660242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:21.167762Z","time spent":"492.471587ms","remote":"127.0.0.1:33288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3731,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-05-05T16:55:21.660238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:21.306479Z","time spent":"353.748962ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T16:55:21.66067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:20.912356Z","time spent":"747.685017ms","remote":"127.0.0.1:33300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T16:55:22.261088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.658843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:55:22.261337Z","caller":"traceutil/trace.go:171","msg":"trace[1448205900] transaction","detail":"{read_only:false; response_revision:248; number_of_response:1; }","duration":"594.929056ms","start":"2026-05-05T16:55:21.666384Z","end":"2026-05-05T16:55:22.261313Z","steps":["trace[1448205900] 'process raft request' (duration: 594.838663ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:22.261354Z","caller":"traceutil/trace.go:171","msg":"trace[90122081] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"595.793433ms","start":"2026-05-05T16:55:21.665496Z","end":"2026-05-05T16:55:22.261289Z","steps":["trace[90122081] 'process raft request' (duration: 243.750308ms)","trace[90122081] 'compare' (duration: 351.420095ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:55:22.261582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:21.665458Z","time spent":"596.017891ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T16:55:22.261656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:21.666366Z","time spent":"595.022298ms","remote":"127.0.0.1:33288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4003,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:55:59.192065Z","caller":"traceutil/trace.go:171","msg":"trace[740875131] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"144.882263ms","start":"2026-05-05T16:55:59.047152Z","end":"2026-05-05T16:55:59.192034Z","steps":["trace[740875131] 'process raft request' (duration: 144.675946ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:59.940062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.787838ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:55:59.940342Z","caller":"traceutil/trace.go:171","msg":"trace[1631005533] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:499; }","duration":"472.070588ms","start":"2026-05-05T16:55:59.468241Z","end":"2026-05-05T16:55:59.940312Z","steps":["trace[1631005533] 'range keys from in-memory index tree' (duration: 471.771267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:59.940806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.693667ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:55:59.940915Z","caller":"traceutil/trace.go:171","msg":"trace[221022482] linearizableReadLoop","detail":"{readStateIndex:521; appliedIndex:520; }","duration":"413.167312ms","start":"2026-05-05T16:55:59.527735Z","end":"2026-05-05T16:55:59.940902Z","steps":["trace[221022482] 'read index received' (duration: 194.293679ms)","trace[221022482] 'applied index is now lower than readState.Index' (duration: 218.871923ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:55:59.941223Z","caller":"traceutil/trace.go:171","msg":"trace[1985842493] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"712.109435ms","start":"2026-05-05T16:55:59.2291Z","end":"2026-05-05T16:55:59.941209Z","steps":["trace[1985842493] 'process raft request' (duration: 492.933522ms)","trace[1985842493] 'compare' (duration: 218.316393ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:55:59.94127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.531794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T16:55:59.941299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:59.229069Z","time spent":"712.195119ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:55:59.941339Z","caller":"traceutil/trace.go:171","msg":"trace[1847954556] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:0; response_revision:500; }","duration":"413.630668ms","start":"2026-05-05T16:55:59.527693Z","end":"2026-05-05T16:55:59.941323Z","steps":["trace[1847954556] 'agreement among raft nodes before linearized reading' (duration: 413.527484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:59.941379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:55:59.527674Z","time spent":"413.697061ms","remote":"127.0.0.1:33312","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"info","ts":"2026-05-05T16:56:00.157076Z","caller":"traceutil/trace.go:171","msg":"trace[1323787984] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"123.579432ms","start":"2026-05-05T16:56:00.033475Z","end":"2026-05-05T16:56:00.157054Z","steps":["trace[1323787984] 'process raft request' (duration: 123.144127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:00.748094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.888205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T16:56:00.74827Z","caller":"traceutil/trace.go:171","msg":"trace[285230295] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"154.106433ms","start":"2026-05-05T16:56:00.594139Z","end":"2026-05-05T16:56:00.748245Z","steps":["trace[285230295] 'range keys from in-memory index tree' (duration: 153.72885ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:00.748347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.676054ms","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-05T16:56:00.74845Z","caller":"traceutil/trace.go:171","msg":"trace[1358718415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:535; }","duration":"148.813869ms","start":"2026-05-05T16:56:00.599611Z","end":"2026-05-05T16:56:00.748425Z","steps":["trace[1358718415] 'range keys from in-memory index tree' (duration: 148.479447ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:01.906924Z","caller":"traceutil/trace.go:171","msg":"trace[240390568] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"133.705604ms","start":"2026-05-05T16:56:01.773193Z","end":"2026-05-05T16:56:01.906899Z","steps":["trace[240390568] 'process raft request' (duration: 133.533768ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:01.912761Z","caller":"traceutil/trace.go:171","msg":"trace[637417028] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"107.568976ms","start":"2026-05-05T16:56:01.805157Z","end":"2026-05-05T16:56:01.912726Z","steps":["trace[637417028] 'process raft request' (duration: 107.343848ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:01.912889Z","caller":"traceutil/trace.go:171","msg":"trace[114363146] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"138.19342ms","start":"2026-05-05T16:56:01.774689Z","end":"2026-05-05T16:56:01.912883Z","steps":["trace[114363146] 'process raft request' (duration: 137.692682ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:02.777689Z","caller":"traceutil/trace.go:171","msg":"trace[1626998811] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"812.385219ms","start":"2026-05-05T16:56:01.965273Z","end":"2026-05-05T16:56:02.777658Z","steps":["trace[1626998811] 'process raft request' (duration: 812.151801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:02.77787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:01.965251Z","time spent":"812.525804ms","remote":"127.0.0.1:33164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:56:02.789281Z","caller":"traceutil/trace.go:171","msg":"trace[2121533895] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"815.675503ms","start":"2026-05-05T16:56:01.973576Z","end":"2026-05-05T16:56:02.789251Z","steps":["trace[2121533895] 'process raft request' (duration: 815.399253ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:02.789476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:01.973556Z","time spent":"815.786937ms","remote":"127.0.0.1:33164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:56:02.78965Z","caller":"traceutil/trace.go:171","msg":"trace[738341570] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:586; }","duration":"226.334052ms","start":"2026-05-05T16:56:02.563295Z","end":"2026-05-05T16:56:02.789629Z","steps":["trace[738341570] 'read index received' (duration: 214.153569ms)","trace[738341570] 'applied index is now lower than readState.Index' (duration: 12.179543ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:02.789643Z","caller":"traceutil/trace.go:171","msg":"trace[2053553035] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"809.530909ms","start":"2026-05-05T16:56:01.980104Z","end":"2026-05-05T16:56:02.789635Z","steps":["trace[2053553035] 'process raft request' (duration: 809.054113ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:02.789742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:01.980092Z","time spent":"809.621613ms","remote":"127.0.0.1:33164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:56:02.789935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.621192ms","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-05T16:56:02.78998Z","caller":"traceutil/trace.go:171","msg":"trace[1179344691] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:566; }","duration":"226.716335ms","start":"2026-05-05T16:56:02.563254Z","end":"2026-05-05T16:56:02.78997Z","steps":["trace[1179344691] 'agreement among raft nodes before linearized reading' (duration: 226.448386ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:02.790175Z","caller":"traceutil/trace.go:171","msg":"trace[1018354029] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"772.097619ms","start":"2026-05-05T16:56:02.018057Z","end":"2026-05-05T16:56:02.790155Z","steps":["trace[1018354029] 'process raft request' (duration: 771.440237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:02.790267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:02.018022Z","time spent":"772.199343ms","remote":"127.0.0.1:33164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T16:56:02.99716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.812993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:56:02.997243Z","caller":"traceutil/trace.go:171","msg":"trace[1831432865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"115.909886ms","start":"2026-05-05T16:56:02.881317Z","end":"2026-05-05T16:56:02.997227Z","steps":["trace[1831432865] 'range keys from in-memory index tree' (duration: 115.758721ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:02.997492Z","caller":"traceutil/trace.go:171","msg":"trace[1290885767] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"109.232204ms","start":"2026-05-05T16:56:02.888222Z","end":"2026-05-05T16:56:02.997454Z","steps":["trace[1290885767] 'process raft request' (duration: 103.499645ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:03.456704Z","caller":"traceutil/trace.go:171","msg":"trace[1576829747] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"157.717628ms","start":"2026-05-05T16:56:03.298947Z","end":"2026-05-05T16:56:03.456664Z","steps":["trace[1576829747] 'process raft request' (duration: 89.964135ms)","trace[1576829747] 'compare' (duration: 67.550956ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:56:04.449402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.031142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-05-05T16:56:04.449482Z","caller":"traceutil/trace.go:171","msg":"trace[7689530] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoypatchpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:619; }","duration":"203.170457ms","start":"2026-05-05T16:56:04.246295Z","end":"2026-05-05T16:56:04.449466Z","steps":["trace[7689530] 'range keys from in-memory index tree' (duration: 202.863506ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:04.450281Z","caller":"traceutil/trace.go:171","msg":"trace[531051768] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"184.467177ms","start":"2026-05-05T16:56:04.265803Z","end":"2026-05-05T16:56:04.45027Z","steps":["trace[531051768] 'process raft request' (duration: 184.309022ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:04.933244Z","caller":"traceutil/trace.go:171","msg":"trace[929269362] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"132.72477ms","start":"2026-05-05T16:56:04.800503Z","end":"2026-05-05T16:56:04.933228Z","steps":["trace[929269362] 'read index received' (duration: 132.574265ms)","trace[929269362] 'applied index is now lower than readState.Index' (duration: 149.855µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:04.93347Z","caller":"traceutil/trace.go:171","msg":"trace[1398608818] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"133.069173ms","start":"2026-05-05T16:56:04.800395Z","end":"2026-05-05T16:56:04.933464Z","steps":["trace[1398608818] 'process raft request' (duration: 132.733491ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:04.933651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.138475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T16:56:04.933673Z","caller":"traceutil/trace.go:171","msg":"trace[1217842789] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"133.212638ms","start":"2026-05-05T16:56:04.800455Z","end":"2026-05-05T16:56:04.933667Z","steps":["trace[1217842789] 'agreement among raft nodes before linearized reading' (duration: 133.162716ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:05.093616Z","caller":"traceutil/trace.go:171","msg":"trace[593208396] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"154.583618ms","start":"2026-05-05T16:56:04.939008Z","end":"2026-05-05T16:56:05.093591Z","steps":["trace[593208396] 'process raft request' (duration: 78.266728ms)","trace[593208396] 'compare' (duration: 76.151645ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:05.093746Z","caller":"traceutil/trace.go:171","msg":"trace[270292999] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"154.141203ms","start":"2026-05-05T16:56:04.939581Z","end":"2026-05-05T16:56:05.093722Z","steps":["trace[270292999] 'process raft request' (duration: 153.973487ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:06.132586Z","caller":"traceutil/trace.go:171","msg":"trace[461309080] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"129.491188ms","start":"2026-05-05T16:56:06.003071Z","end":"2026-05-05T16:56:06.132563Z","steps":["trace[461309080] 'process raft request' (duration: 58.16273ms)","trace[461309080] 'compare' (duration: 71.228864ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:06.191433Z","caller":"traceutil/trace.go:171","msg":"trace[1694699245] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"187.841635ms","start":"2026-05-05T16:56:06.003564Z","end":"2026-05-05T16:56:06.191405Z","steps":["trace[1694699245] 'process raft request' (duration: 187.709ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:06.602415Z","caller":"traceutil/trace.go:171","msg":"trace[1653773768] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"218.212789ms","start":"2026-05-05T16:56:06.38417Z","end":"2026-05-05T16:56:06.602383Z","steps":["trace[1653773768] 'process raft request' (duration: 217.830966ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:07.215848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.03026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T16:56:07.215881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.059371ms","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-05T16:56:07.215933Z","caller":"traceutil/trace.go:171","msg":"trace[1934730788] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:629; }","duration":"118.163875ms","start":"2026-05-05T16:56:07.09775Z","end":"2026-05-05T16:56:07.215914Z","steps":["trace[1934730788] 'range keys from in-memory index tree' (duration: 117.822593ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:07.215964Z","caller":"traceutil/trace.go:171","msg":"trace[340240665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"118.193796ms","start":"2026-05-05T16:56:07.097748Z","end":"2026-05-05T16:56:07.215942Z","steps":["trace[340240665] 'range keys from in-memory index tree' (duration: 117.831163ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:07.298265Z","caller":"traceutil/trace.go:171","msg":"trace[1113436984] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"103.158373ms","start":"2026-05-05T16:56:07.195071Z","end":"2026-05-05T16:56:07.29823Z","steps":["trace[1113436984] 'process raft request' (duration: 95.965673ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:09.503119Z","caller":"traceutil/trace.go:171","msg":"trace[2063660268] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"114.923502ms","start":"2026-05-05T16:56:09.38816Z","end":"2026-05-05T16:56:09.503083Z","steps":["trace[2063660268] 'process raft request' (duration: 114.673043ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:11.324696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.069188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:56:11.32482Z","caller":"traceutil/trace.go:171","msg":"trace[74359732] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"449.254505ms","start":"2026-05-05T16:56:10.875539Z","end":"2026-05-05T16:56:11.324793Z","steps":["trace[74359732] 'range keys from in-memory index tree' (duration: 448.988896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:11.324898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:10.875531Z","time spent":"449.356729ms","remote":"127.0.0.1:33110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T16:56:11.325097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.894875ms","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-05T16:56:11.32518Z","caller":"traceutil/trace.go:171","msg":"trace[1804718987] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:660; }","duration":"329.060691ms","start":"2026-05-05T16:56:10.996102Z","end":"2026-05-05T16:56:11.325163Z","steps":["trace[1804718987] 'range keys from in-memory index tree' (duration: 328.652706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:11.32522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:10.996071Z","time spent":"329.141974ms","remote":"127.0.0.1:33360","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-05T16:56:11.325349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.898276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-fnsqr\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-05T16:56:11.325396Z","caller":"traceutil/trace.go:171","msg":"trace[1542077254] range","detail":"{range_begin:/registry/pods/kube-system/cilium-fnsqr; range_end:; response_count:1; response_revision:660; }","duration":"455.99558ms","start":"2026-05-05T16:56:10.869387Z","end":"2026-05-05T16:56:11.325383Z","steps":["trace[1542077254] 'range keys from in-memory index tree' (duration: 455.678009ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:11.325426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:10.869359Z","time spent":"456.060822ms","remote":"127.0.0.1:33300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-fnsqr\" "} {"level":"info","ts":"2026-05-05T16:56:11.446948Z","caller":"traceutil/trace.go:171","msg":"trace[2030138738] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"115.041576ms","start":"2026-05-05T16:56:11.331874Z","end":"2026-05-05T16:56:11.446916Z","steps":["trace[2030138738] 'process raft request' (duration: 114.842839ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:12.290389Z","caller":"traceutil/trace.go:171","msg":"trace[107969141] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"956.251015ms","start":"2026-05-05T16:56:11.334095Z","end":"2026-05-05T16:56:12.290346Z","steps":["trace[107969141] 'process raft request' (duration: 955.981775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:12.29066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:11.334078Z","time spent":"956.43167ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:56:12.290914Z","caller":"traceutil/trace.go:171","msg":"trace[48139752] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"956.660599ms","start":"2026-05-05T16:56:11.334177Z","end":"2026-05-05T16:56:12.290838Z","steps":["trace[48139752] 'process raft request' (duration: 956.11964ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:12.290966Z","caller":"traceutil/trace.go:171","msg":"trace[490118936] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"955.83124ms","start":"2026-05-05T16:56:11.335097Z","end":"2026-05-05T16:56:12.290928Z","steps":["trace[490118936] 'process raft request' (duration: 955.584491ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:12.290978Z","caller":"traceutil/trace.go:171","msg":"trace[1567312842] linearizableReadLoop","detail":"{readStateIndex:691; appliedIndex:687; }","duration":"412.919732ms","start":"2026-05-05T16:56:11.878Z","end":"2026-05-05T16:56:12.29092Z","steps":["trace[1567312842] 'read index received' (duration: 412.040661ms)","trace[1567312842] 'applied index is now lower than readState.Index' (duration: 877.891µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:56:12.291181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.15544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T16:56:12.291184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:11.33417Z","time spent":"956.872746ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:56:12.291222Z","caller":"traceutil/trace.go:171","msg":"trace[46022134] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:665; }","duration":"413.280135ms","start":"2026-05-05T16:56:11.877929Z","end":"2026-05-05T16:56:12.291209Z","steps":["trace[46022134] 'agreement among raft nodes before linearized reading' (duration: 413.14852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:12.291164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:11.335088Z","time spent":"955.960294ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:56:12.291261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:11.8779Z","time spent":"413.352957ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T16:56:12.291392Z","caller":"traceutil/trace.go:171","msg":"trace[47678033] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"951.020833ms","start":"2026-05-05T16:56:11.340359Z","end":"2026-05-05T16:56:12.29138Z","steps":["trace[47678033] 'process raft request' (duration: 950.476224ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:12.291453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:11.340348Z","time spent":"951.073115ms","remote":"127.0.0.1:33300","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:56:14.379387Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225964796905113,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T16:56:14.474551Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.018080854s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T16:56:14.475233Z","caller":"traceutil/trace.go:171","msg":"trace[1843658993] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"1.018863881s","start":"2026-05-05T16:56:13.456314Z","end":"2026-05-05T16:56:14.475178Z","steps":["trace[1843658993] 'process raft request' (duration: 1.018509978s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:14.475439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:13.456278Z","time spent":"1.019025816s","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:56:15.108964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.823998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:56:15.109119Z","caller":"traceutil/trace.go:171","msg":"trace[1745109379] linearizableReadLoop","detail":"{readStateIndex:693; appliedIndex:691; }","duration":"1.229790397s","start":"2026-05-05T16:56:13.879305Z","end":"2026-05-05T16:56:15.109096Z","steps":["trace[1745109379] 'read index received' (duration: 595.501215ms)","trace[1745109379] 'applied index is now lower than readState.Index' (duration: 634.286682ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:15.109335Z","caller":"traceutil/trace.go:171","msg":"trace[2017634214] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"1.153622971s","start":"2026-05-05T16:56:13.955675Z","end":"2026-05-05T16:56:15.109298Z","steps":["trace[2017634214] 'process raft request' (duration: 637.282406ms)","trace[2017634214] 'compare' (duration: 515.554128ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:56:15.109451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230139659s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T16:56:15.109513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.2966ms","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-05T16:56:15.109516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.565685ms","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-05T16:56:15.109599Z","caller":"traceutil/trace.go:171","msg":"trace[924942581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:667; }","duration":"813.440186ms","start":"2026-05-05T16:56:14.296135Z","end":"2026-05-05T16:56:15.109575Z","steps":["trace[924942581] 'agreement among raft nodes before linearized reading' (duration: 813.183897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:15.109592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:13.95563Z","time spent":"1.153825889s","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:56:15.109638Z","caller":"traceutil/trace.go:171","msg":"trace[636112205] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:667; }","duration":"812.72073ms","start":"2026-05-05T16:56:14.296895Z","end":"2026-05-05T16:56:15.109615Z","steps":["trace[636112205] 'agreement among raft nodes before linearized reading' (duration: 812.43365ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:15.109659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:14.296114Z","time spent":"813.536038ms","remote":"127.0.0.1:33360","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-05T16:56:15.109708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:14.296888Z","time spent":"812.806653ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T16:56:15.109816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"813.245619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T16:56:15.109959Z","caller":"traceutil/trace.go:171","msg":"trace[1277113891] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:667; }","duration":"813.367702ms","start":"2026-05-05T16:56:14.296518Z","end":"2026-05-05T16:56:15.109885Z","steps":["trace[1277113891] 'agreement among raft nodes before linearized reading' (duration: 812.875585ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:15.109592Z","caller":"traceutil/trace.go:171","msg":"trace[921528059] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:667; }","duration":"1.230294835s","start":"2026-05-05T16:56:13.879255Z","end":"2026-05-05T16:56:15.10955Z","steps":["trace[921528059] 'agreement among raft nodes before linearized reading' (duration: 1.230052946s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:15.110056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:13.87923Z","time spent":"1.230802613s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T16:56:15.110154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:14.296497Z","time spent":"813.647552ms","remote":"127.0.0.1:33360","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-05T16:56:15.231237Z","caller":"traceutil/trace.go:171","msg":"trace[490780593] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"116.553599ms","start":"2026-05-05T16:56:15.11466Z","end":"2026-05-05T16:56:15.231214Z","steps":["trace[490780593] 'process raft request' (duration: 111.950619ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:15.233701Z","caller":"traceutil/trace.go:171","msg":"trace[420826971] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"118.351181ms","start":"2026-05-05T16:56:15.115338Z","end":"2026-05-05T16:56:15.233689Z","steps":["trace[420826971] 'process raft request' (duration: 118.166554ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:15.233698Z","caller":"traceutil/trace.go:171","msg":"trace[702899551] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:693; }","duration":"113.13902ms","start":"2026-05-05T16:56:15.120527Z","end":"2026-05-05T16:56:15.233666Z","steps":["trace[702899551] 'read index received' (duration: 106.034493ms)","trace[702899551] 'applied index is now lower than readState.Index' (duration: 7.103607ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:56:15.23396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.42112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:56:15.234Z","caller":"traceutil/trace.go:171","msg":"trace[521057949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:670; }","duration":"113.509953ms","start":"2026-05-05T16:56:15.120479Z","end":"2026-05-05T16:56:15.233989Z","steps":["trace[521057949] 'agreement among raft nodes before linearized reading' (duration: 113.276155ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:15.234017Z","caller":"traceutil/trace.go:171","msg":"trace[690440792] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"117.698048ms","start":"2026-05-05T16:56:15.116296Z","end":"2026-05-05T16:56:15.233994Z","steps":["trace[690440792] 'process raft request' (duration: 117.333036ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:17.56671Z","caller":"traceutil/trace.go:171","msg":"trace[279274109] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"564.321402ms","start":"2026-05-05T16:56:17.002357Z","end":"2026-05-05T16:56:17.566678Z","steps":["trace[279274109] 'process raft request' (duration: 556.300494ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:17.566834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:17.002123Z","time spent":"564.650354ms","remote":"127.0.0.1:33164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:56:17.575271Z","caller":"traceutil/trace.go:171","msg":"trace[635123248] linearizableReadLoop","detail":"{readStateIndex:721; appliedIndex:719; }","duration":"339.365028ms","start":"2026-05-05T16:56:17.235882Z","end":"2026-05-05T16:56:17.575247Z","steps":["trace[635123248] 'read index received' (duration: 322.870375ms)","trace[635123248] 'applied index is now lower than readState.Index' (duration: 16.493203ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:17.57529Z","caller":"traceutil/trace.go:171","msg":"trace[1414497420] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"407.538306ms","start":"2026-05-05T16:56:17.167726Z","end":"2026-05-05T16:56:17.575264Z","steps":["trace[1414497420] 'process raft request' (duration: 407.37143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:17.575483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.582795ms","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-05T16:56:17.575527Z","caller":"traceutil/trace.go:171","msg":"trace[1682754027] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:694; }","duration":"339.667918ms","start":"2026-05-05T16:56:17.235849Z","end":"2026-05-05T16:56:17.575517Z","steps":["trace[1682754027] 'agreement among raft nodes before linearized reading' (duration: 339.494382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:17.575552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:17.235832Z","time spent":"339.71304ms","remote":"127.0.0.1:33360","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-05T16:56:17.575592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:17.167684Z","time spent":"407.808395ms","remote":"127.0.0.1:33164","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T16:56:17.5775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.363983ms","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-05T16:56:17.577579Z","caller":"traceutil/trace.go:171","msg":"trace[1395697549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:696; }","duration":"338.465346ms","start":"2026-05-05T16:56:17.239095Z","end":"2026-05-05T16:56:17.57756Z","steps":["trace[1395697549] 'agreement among raft nodes before linearized reading' (duration: 338.258319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:17.577629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:17.239083Z","time spent":"338.534689ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T16:56:17.577625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.60627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:56:17.577749Z","caller":"traceutil/trace.go:171","msg":"trace[1061659453] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:696; }","duration":"211.797957ms","start":"2026-05-05T16:56:17.365935Z","end":"2026-05-05T16:56:17.577733Z","steps":["trace[1061659453] 'agreement among raft nodes before linearized reading' (duration: 211.541188ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:17.57788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.57607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T16:56:17.577918Z","caller":"traceutil/trace.go:171","msg":"trace[1578808679] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:696; }","duration":"338.624222ms","start":"2026-05-05T16:56:17.239285Z","end":"2026-05-05T16:56:17.577909Z","steps":["trace[1578808679] 'agreement among raft nodes before linearized reading' (duration: 338.516829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:17.577953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:17.239258Z","time spent":"338.688075ms","remote":"127.0.0.1:33360","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-05T16:56:20.255408Z","caller":"traceutil/trace.go:171","msg":"trace[562804200] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"117.397158ms","start":"2026-05-05T16:56:20.137982Z","end":"2026-05-05T16:56:20.255379Z","steps":["trace[562804200] 'process raft request' (duration: 117.219172ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:31.117236Z","caller":"traceutil/trace.go:171","msg":"trace[127925081] linearizableReadLoop","detail":"{readStateIndex:857; appliedIndex:856; }","duration":"237.51633ms","start":"2026-05-05T16:56:30.879693Z","end":"2026-05-05T16:56:31.11721Z","steps":["trace[127925081] 'read index received' (duration: 237.338674ms)","trace[127925081] 'applied index is now lower than readState.Index' (duration: 175.906µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:31.117352Z","caller":"traceutil/trace.go:171","msg":"trace[217779977] transaction","detail":"{read_only:false; response_revision:825; number_of_response:1; }","duration":"564.529009ms","start":"2026-05-05T16:56:30.552785Z","end":"2026-05-05T16:56:31.117314Z","steps":["trace[217779977] 'process raft request' (duration: 564.231168ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:31.11743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.714827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:56:31.117474Z","caller":"traceutil/trace.go:171","msg":"trace[750137173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:825; }","duration":"237.849152ms","start":"2026-05-05T16:56:30.879615Z","end":"2026-05-05T16:56:31.117464Z","steps":["trace[750137173] 'agreement among raft nodes before linearized reading' (duration: 237.757069ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:31.117577Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:30.55275Z","time spent":"564.695894ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:56:31.117633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.015872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-njfsb\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-05-05T16:56:31.117734Z","caller":"traceutil/trace.go:171","msg":"trace[396310274] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-njfsb; range_end:; response_count:1; response_revision:825; }","duration":"125.179148ms","start":"2026-05-05T16:56:30.992533Z","end":"2026-05-05T16:56:31.117712Z","steps":["trace[396310274] 'agreement among raft nodes before linearized reading' (duration: 124.912588ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:31.268669Z","caller":"traceutil/trace.go:171","msg":"trace[1573034908] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"142.767599ms","start":"2026-05-05T16:56:31.125875Z","end":"2026-05-05T16:56:31.268643Z","steps":["trace[1573034908] 'process raft request' (duration: 142.49225ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:31.268513Z","caller":"traceutil/trace.go:171","msg":"trace[1136619368] transaction","detail":"{read_only:false; response_revision:826; number_of_response:1; }","duration":"142.654285ms","start":"2026-05-05T16:56:31.125822Z","end":"2026-05-05T16:56:31.268477Z","steps":["trace[1136619368] 'process raft request' (duration: 141.666161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:31.842164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.043001ms","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-05T16:56:31.842252Z","caller":"traceutil/trace.go:171","msg":"trace[1174959414] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:827; }","duration":"133.200297ms","start":"2026-05-05T16:56:31.709031Z","end":"2026-05-05T16:56:31.842232Z","steps":["trace[1174959414] 'range keys from in-memory index tree' (duration: 132.775142ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:31.842312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.178592ms","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-05T16:56:31.842399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.472086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T16:56:31.84245Z","caller":"traceutil/trace.go:171","msg":"trace[1255927587] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:827; }","duration":"150.593281ms","start":"2026-05-05T16:56:31.691843Z","end":"2026-05-05T16:56:31.842436Z","steps":["trace[1255927587] 'range keys from in-memory index tree' (duration: 150.314831ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:31.842449Z","caller":"traceutil/trace.go:171","msg":"trace[1395530154] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:827; }","duration":"128.343448ms","start":"2026-05-05T16:56:31.714055Z","end":"2026-05-05T16:56:31.842398Z","steps":["trace[1395530154] 'range keys from in-memory index tree' (duration: 127.918032ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:35.298183Z","caller":"traceutil/trace.go:171","msg":"trace[1747972113] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"154.080432ms","start":"2026-05-05T16:56:35.144082Z","end":"2026-05-05T16:56:35.298162Z","steps":["trace[1747972113] 'process raft request' (duration: 153.986019ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:35.298439Z","caller":"traceutil/trace.go:171","msg":"trace[172495825] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"156.265667ms","start":"2026-05-05T16:56:35.14214Z","end":"2026-05-05T16:56:35.298406Z","steps":["trace[172495825] 'process raft request' (duration: 114.197996ms)","trace[172495825] 'compare' (duration: 41.595915ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:35.298491Z","caller":"traceutil/trace.go:171","msg":"trace[1818892388] linearizableReadLoop","detail":"{readStateIndex:874; appliedIndex:873; }","duration":"154.506797ms","start":"2026-05-05T16:56:35.143879Z","end":"2026-05-05T16:56:35.298386Z","steps":["trace[1818892388] 'read index received' (duration: 112.403194ms)","trace[1818892388] 'applied index is now lower than readState.Index' (duration: 42.099582ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:56:35.298891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.992404ms","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-05T16:56:35.298943Z","caller":"traceutil/trace.go:171","msg":"trace[1075051693] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:843; }","duration":"155.096177ms","start":"2026-05-05T16:56:35.143833Z","end":"2026-05-05T16:56:35.298929Z","steps":["trace[1075051693] 'agreement among raft nodes before linearized reading' (duration: 154.799197ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:35.552073Z","caller":"traceutil/trace.go:171","msg":"trace[1647237084] transaction","detail":"{read_only:false; response_revision:857; number_of_response:1; }","duration":"138.165059ms","start":"2026-05-05T16:56:35.413871Z","end":"2026-05-05T16:56:35.552036Z","steps":["trace[1647237084] 'process raft request' (duration: 130.71214ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:35.750306Z","caller":"traceutil/trace.go:171","msg":"trace[1964465038] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"125.085615ms","start":"2026-05-05T16:56:35.625179Z","end":"2026-05-05T16:56:35.750264Z","steps":["trace[1964465038] 'process raft request' (duration: 124.94232ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:35.750456Z","caller":"traceutil/trace.go:171","msg":"trace[395672171] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"124.174433ms","start":"2026-05-05T16:56:35.626239Z","end":"2026-05-05T16:56:35.750413Z","steps":["trace[395672171] 'process raft request' (duration: 123.945045ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:35.750677Z","caller":"traceutil/trace.go:171","msg":"trace[517417267] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"127.777348ms","start":"2026-05-05T16:56:35.622872Z","end":"2026-05-05T16:56:35.75065Z","steps":["trace[517417267] 'process raft request' (duration: 75.828484ms)","trace[517417267] 'compare' (duration: 51.232279ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:35.889658Z","caller":"traceutil/trace.go:171","msg":"trace[628241831] linearizableReadLoop","detail":"{readStateIndex:896; appliedIndex:895; }","duration":"130.9789ms","start":"2026-05-05T16:56:35.758653Z","end":"2026-05-05T16:56:35.889632Z","steps":["trace[628241831] 'read index received' (duration: 130.11146ms)","trace[628241831] 'applied index is now lower than readState.Index' (duration: 866.45µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:56:35.890053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.379354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/envoy-gateway-system/envoy-gateway\" ","response":"range_response_count:1 size:5266"} {"level":"info","ts":"2026-05-05T16:56:35.890088Z","caller":"traceutil/trace.go:171","msg":"trace[1690365153] range","detail":"{range_begin:/registry/deployments/envoy-gateway-system/envoy-gateway; range_end:; response_count:1; response_revision:863; }","duration":"131.461216ms","start":"2026-05-05T16:56:35.758617Z","end":"2026-05-05T16:56:35.890078Z","steps":["trace[1690365153] 'agreement among raft nodes before linearized reading' (duration: 131.314511ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:35.890064Z","caller":"traceutil/trace.go:171","msg":"trace[938136738] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"133.163346ms","start":"2026-05-05T16:56:35.756818Z","end":"2026-05-05T16:56:35.889982Z","steps":["trace[938136738] 'process raft request' (duration: 131.870081ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:35.89313Z","caller":"traceutil/trace.go:171","msg":"trace[1030172377] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"133.810568ms","start":"2026-05-05T16:56:35.759299Z","end":"2026-05-05T16:56:35.893109Z","steps":["trace[1030172377] 'process raft request' (duration: 133.740505ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:35.893497Z","caller":"traceutil/trace.go:171","msg":"trace[1314383432] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"134.541354ms","start":"2026-05-05T16:56:35.758945Z","end":"2026-05-05T16:56:35.893487Z","steps":["trace[1314383432] 'process raft request' (duration: 133.969724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:46.140164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.732131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:56:46.140436Z","caller":"traceutil/trace.go:171","msg":"trace[1527245742] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"173.715304ms","start":"2026-05-05T16:56:45.966687Z","end":"2026-05-05T16:56:46.140403Z","steps":["trace[1527245742] 'process raft request' (duration: 173.5943ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:46.140543Z","caller":"traceutil/trace.go:171","msg":"trace[332786902] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"175.958662ms","start":"2026-05-05T16:56:45.96455Z","end":"2026-05-05T16:56:46.140509Z","steps":["trace[332786902] 'process raft request' (duration: 63.776735ms)","trace[332786902] 'compare' (duration: 111.594016ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:46.299185Z","caller":"traceutil/trace.go:171","msg":"trace[625083902] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"149.754332ms","start":"2026-05-05T16:56:46.149404Z","end":"2026-05-05T16:56:46.299158Z","steps":["trace[625083902] 'process raft request' (duration: 149.586536ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:46.940607Z","caller":"traceutil/trace.go:171","msg":"trace[986028479] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1001; }","duration":"374.860511ms","start":"2026-05-05T16:56:46.565728Z","end":"2026-05-05T16:56:46.940588Z","steps":["trace[986028479] 'read index received' (duration: 374.720116ms)","trace[986028479] 'applied index is now lower than readState.Index' (duration: 139.065µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:46.941057Z","caller":"traceutil/trace.go:171","msg":"trace[1254324447] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"400.062225ms","start":"2026-05-05T16:56:46.540907Z","end":"2026-05-05T16:56:46.94097Z","steps":["trace[1254324447] 'process raft request' (duration: 399.62519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:46.941289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.532474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T16:56:46.941281Z","caller":"traceutil/trace.go:171","msg":"trace[285940921] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"400.865964ms","start":"2026-05-05T16:56:46.540374Z","end":"2026-05-05T16:56:46.94124Z","steps":["trace[285940921] 'process raft request' (duration: 400.009694ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:46.941353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:46.540822Z","time spent":"400.382776ms","remote":"127.0.0.1:33594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5192,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:56:46.941363Z","caller":"traceutil/trace.go:171","msg":"trace[1027012586] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:968; }","duration":"375.684769ms","start":"2026-05-05T16:56:46.56566Z","end":"2026-05-05T16:56:46.941345Z","steps":["trace[1027012586] 'agreement among raft nodes before linearized reading' (duration: 375.399769ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:46.941424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:46.565626Z","time spent":"375.784382ms","remote":"127.0.0.1:33186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":113,"response size":29,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-05T16:56:46.941465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:46.540369Z","time spent":"400.996158ms","remote":"127.0.0.1:33372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1628,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:56:47.360805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.866863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:56:47.360914Z","caller":"traceutil/trace.go:171","msg":"trace[1296600643] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"416.653043ms","start":"2026-05-05T16:56:46.944245Z","end":"2026-05-05T16:56:47.360898Z","steps":["trace[1296600643] 'process raft request' (duration: 239.598903ms)","trace[1296600643] 'compare' (duration: 176.779021ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:56:47.360961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:46.944214Z","time spent":"416.724866ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:56:47.39552Z","caller":"traceutil/trace.go:171","msg":"trace[900918399] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"445.713293ms","start":"2026-05-05T16:56:46.949786Z","end":"2026-05-05T16:56:47.3955Z","steps":["trace[900918399] 'process raft request' (duration: 445.308349ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:47.395658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:56:46.949773Z","time spent":"445.807336ms","remote":"127.0.0.1:33572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5626,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:56:47.55398Z","caller":"traceutil/trace.go:171","msg":"trace[1433673395] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"144.222369ms","start":"2026-05-05T16:56:47.409724Z","end":"2026-05-05T16:56:47.553946Z","steps":["trace[1433673395] 'read index received' (duration: 70.333443ms)","trace[1433673395] 'applied index is now lower than readState.Index' (duration: 73.887366ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:47.554057Z","caller":"traceutil/trace.go:171","msg":"trace[1550734040] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"153.095298ms","start":"2026-05-05T16:56:47.400921Z","end":"2026-05-05T16:56:47.554017Z","steps":["trace[1550734040] 'process raft request' (duration: 79.126789ms)","trace[1550734040] 'compare' (duration: 73.770802ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:56:47.554449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.688646ms","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-05T16:56:47.554524Z","caller":"traceutil/trace.go:171","msg":"trace[1449075500] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:971; }","duration":"144.784379ms","start":"2026-05-05T16:56:47.409721Z","end":"2026-05-05T16:56:47.554506Z","steps":["trace[1449075500] 'agreement among raft nodes before linearized reading' (duration: 144.371865ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:56:47.801251Z","caller":"traceutil/trace.go:171","msg":"trace[692498215] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"242.524804ms","start":"2026-05-05T16:56:47.558702Z","end":"2026-05-05T16:56:47.801227Z","steps":["trace[692498215] 'process raft request' (duration: 157.106527ms)","trace[692498215] 'compare' (duration: 85.20227ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:54.455737Z","caller":"traceutil/trace.go:171","msg":"trace[955987412] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"191.889595ms","start":"2026-05-05T16:56:54.263827Z","end":"2026-05-05T16:56:54.455717Z","steps":["trace[955987412] 'process raft request' (duration: 191.72598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:54.455944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.733692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T16:56:54.455741Z","caller":"traceutil/trace.go:171","msg":"trace[2068141159] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"143.521085ms","start":"2026-05-05T16:56:54.312185Z","end":"2026-05-05T16:56:54.455706Z","steps":["trace[2068141159] 'read index received' (duration: 143.505605ms)","trace[2068141159] 'applied index is now lower than readState.Index' (duration: 12.55µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:56:54.456131Z","caller":"traceutil/trace.go:171","msg":"trace[107781616] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:994; }","duration":"143.983741ms","start":"2026-05-05T16:56:54.31212Z","end":"2026-05-05T16:56:54.456104Z","steps":["trace[107781616] 'agreement among raft nodes before linearized reading' (duration: 143.68237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:56:54.460651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.064052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T16:56:54.460711Z","caller":"traceutil/trace.go:171","msg":"trace[165355727] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:995; }","duration":"148.145495ms","start":"2026-05-05T16:56:54.312541Z","end":"2026-05-05T16:56:54.460686Z","steps":["trace[165355727] 'agreement among raft nodes before linearized reading' (duration: 147.14265ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:01.384157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.950089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:57:01.384253Z","caller":"traceutil/trace.go:171","msg":"trace[1562471445] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1017; }","duration":"505.105183ms","start":"2026-05-05T16:57:00.879123Z","end":"2026-05-05T16:57:01.384228Z","steps":["trace[1562471445] 'range keys from in-memory index tree' (duration: 504.830074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:01.38429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:00.879097Z","time spent":"505.184356ms","remote":"127.0.0.1:33110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T16:57:06.875264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.429945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T16:57:06.875353Z","caller":"traceutil/trace.go:171","msg":"trace[2102144918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1039; }","duration":"276.58097ms","start":"2026-05-05T16:57:06.598752Z","end":"2026-05-05T16:57:06.875333Z","steps":["trace[2102144918] 'range keys from in-memory index tree' (duration: 276.134215ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:08.204576Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225964796905958,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T16:57:08.228433Z","caller":"traceutil/trace.go:171","msg":"trace[1163328835] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"524.781646ms","start":"2026-05-05T16:57:07.703627Z","end":"2026-05-05T16:57:08.228409Z","steps":["trace[1163328835] 'read index received' (duration: 524.541609ms)","trace[1163328835] 'applied index is now lower than readState.Index' (duration: 222.677µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:57:08.228532Z","caller":"traceutil/trace.go:171","msg":"trace[508461192] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"698.01538ms","start":"2026-05-05T16:57:07.530484Z","end":"2026-05-05T16:57:08.228499Z","steps":["trace[508461192] 'process raft request' (duration: 697.765962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:08.228665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.021635ms","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-05T16:57:08.228696Z","caller":"traceutil/trace.go:171","msg":"trace[1493957943] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1041; }","duration":"525.107287ms","start":"2026-05-05T16:57:07.703578Z","end":"2026-05-05T16:57:08.228685Z","steps":["trace[1493957943] 'agreement among raft nodes before linearized reading' (duration: 524.950982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:08.22869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:07.53046Z","time spent":"698.140215ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:57:08.228722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:07.703557Z","time spent":"525.159808ms","remote":"127.0.0.1:33360","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-05T16:57:08.22879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.240917ms","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-05T16:57:08.228923Z","caller":"traceutil/trace.go:171","msg":"trace[195424988] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1041; }","duration":"303.436584ms","start":"2026-05-05T16:57:07.925458Z","end":"2026-05-05T16:57:08.228894Z","steps":["trace[195424988] 'agreement among raft nodes before linearized reading' (duration: 303.131594ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:08.228967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:07.925428Z","time spent":"303.529097ms","remote":"127.0.0.1:33360","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-05T16:57:09.061988Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225964796905961,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T16:57:09.303181Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.069960049s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T16:57:09.303825Z","caller":"traceutil/trace.go:171","msg":"trace[52370866] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"1.07061767s","start":"2026-05-05T16:57:08.233113Z","end":"2026-05-05T16:57:09.30373Z","steps":["trace[52370866] 'process raft request' (duration: 1.070250188s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:09.304033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:08.233084Z","time spent":"1.070811796s","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:57:09.304921Z","caller":"traceutil/trace.go:171","msg":"trace[1813000759] linearizableReadLoop","detail":"{readStateIndex:1084; appliedIndex:1081; }","duration":"743.857436ms","start":"2026-05-05T16:57:08.561047Z","end":"2026-05-05T16:57:09.304904Z","steps":["trace[1813000759] 'read index received' (duration: 742.341327ms)","trace[1813000759] 'applied index is now lower than readState.Index' (duration: 1.515229ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:57:09.30522Z","caller":"traceutil/trace.go:171","msg":"trace[474333233] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"1.07185287s","start":"2026-05-05T16:57:08.233348Z","end":"2026-05-05T16:57:09.305201Z","steps":["trace[474333233] 'process raft request' (duration: 1.071411096s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:09.30533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:08.23334Z","time spent":"1.071929063s","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:57:09.305533Z","caller":"traceutil/trace.go:171","msg":"trace[965634264] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"1.071363604s","start":"2026-05-05T16:57:08.234152Z","end":"2026-05-05T16:57:09.305515Z","steps":["trace[965634264] 'process raft request' (duration: 1.070715894s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:09.305614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:08.234145Z","time spent":"1.071425486s","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:57:09.305913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.870508ms","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-05T16:57:09.30596Z","caller":"traceutil/trace.go:171","msg":"trace[1116643333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1044; }","duration":"744.959001ms","start":"2026-05-05T16:57:08.560986Z","end":"2026-05-05T16:57:09.305945Z","steps":["trace[1116643333] 'agreement among raft nodes before linearized reading' (duration: 744.812557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:09.306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:08.560949Z","time spent":"745.041544ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T16:57:09.306171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.921819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T16:57:09.306198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.021672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T16:57:09.306221Z","caller":"traceutil/trace.go:171","msg":"trace[1404096638] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"427.965961ms","start":"2026-05-05T16:57:08.878238Z","end":"2026-05-05T16:57:09.306204Z","steps":["trace[1404096638] 'agreement among raft nodes before linearized reading' (duration: 427.870528ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:09.306234Z","caller":"traceutil/trace.go:171","msg":"trace[1536302814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1044; }","duration":"400.092444ms","start":"2026-05-05T16:57:08.906131Z","end":"2026-05-05T16:57:09.306224Z","steps":["trace[1536302814] 'agreement among raft nodes before linearized reading' (duration: 399.996021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:09.306251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:08.878175Z","time spent":"428.069914ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T16:57:09.30626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:08.906112Z","time spent":"400.141766ms","remote":"127.0.0.1:33360","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-05T16:57:30.541418Z","caller":"traceutil/trace.go:171","msg":"trace[777212827] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"111.007611ms","start":"2026-05-05T16:57:30.430108Z","end":"2026-05-05T16:57:30.541116Z","steps":["trace[777212827] 'process raft request' (duration: 110.765484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:31.722688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.77733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-05T16:57:31.722732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.109395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T16:57:31.7228Z","caller":"traceutil/trace.go:171","msg":"trace[1286733633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1115; }","duration":"305.962876ms","start":"2026-05-05T16:57:31.41681Z","end":"2026-05-05T16:57:31.722773Z","steps":["trace[1286733633] 'range keys from in-memory index tree' (duration: 305.556852ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:31.722859Z","caller":"traceutil/trace.go:171","msg":"trace[1075848874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1115; }","duration":"290.284541ms","start":"2026-05-05T16:57:31.43254Z","end":"2026-05-05T16:57:31.722825Z","steps":["trace[1075848874] 'range keys from in-memory index tree' (duration: 289.905339ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:31.722869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:31.416798Z","time spent":"306.055759ms","remote":"127.0.0.1:33360","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-05T16:57:31.722842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.826492ms","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-05T16:57:31.722945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.358212ms","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-05T16:57:31.72296Z","caller":"traceutil/trace.go:171","msg":"trace[1167121102] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1115; }","duration":"319.978016ms","start":"2026-05-05T16:57:31.402953Z","end":"2026-05-05T16:57:31.722931Z","steps":["trace[1167121102] 'range keys from in-memory index tree' (duration: 319.555242ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:31.723004Z","caller":"traceutil/trace.go:171","msg":"trace[1726581982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1115; }","duration":"317.472186ms","start":"2026-05-05T16:57:31.405515Z","end":"2026-05-05T16:57:31.722987Z","steps":["trace[1726581982] 'range keys from in-memory index tree' (duration: 317.187997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:31.723024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:31.402946Z","time spent":"320.061488ms","remote":"127.0.0.1:33360","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-05T16:57:31.723045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:31.405482Z","time spent":"317.550978ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T16:57:31.723355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.532384ms","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-05T16:57:31.723552Z","caller":"traceutil/trace.go:171","msg":"trace[1502605340] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1115; }","duration":"320.71678ms","start":"2026-05-05T16:57:31.402721Z","end":"2026-05-05T16:57:31.723438Z","steps":["trace[1502605340] 'range keys from in-memory index tree' (duration: 320.297616ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:31.723712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:31.402697Z","time spent":"321.003029ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T16:57:32.981626Z","caller":"traceutil/trace.go:171","msg":"trace[1297157824] linearizableReadLoop","detail":"{readStateIndex:1166; appliedIndex:1165; }","duration":"329.231184ms","start":"2026-05-05T16:57:32.652371Z","end":"2026-05-05T16:57:32.981602Z","steps":["trace[1297157824] 'read index received' (duration: 328.956956ms)","trace[1297157824] 'applied index is now lower than readState.Index' (duration: 272.648µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:57:32.981709Z","caller":"traceutil/trace.go:171","msg":"trace[223580797] transaction","detail":"{read_only:false; response_revision:1121; number_of_response:1; }","duration":"435.176423ms","start":"2026-05-05T16:57:32.546493Z","end":"2026-05-05T16:57:32.981669Z","steps":["trace[223580797] 'process raft request' (duration: 434.935535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:32.981801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.40482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:57:32.981839Z","caller":"traceutil/trace.go:171","msg":"trace[1353977359] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1121; }","duration":"329.505103ms","start":"2026-05-05T16:57:32.652323Z","end":"2026-05-05T16:57:32.981828Z","steps":["trace[1353977359] 'agreement among raft nodes before linearized reading' (duration: 329.41977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:32.981878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:32.652295Z","time spent":"329.570195ms","remote":"127.0.0.1:48544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-05-05T16:57:32.981899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:32.546456Z","time spent":"435.326118ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:57:35.341797Z","caller":"traceutil/trace.go:171","msg":"trace[636142855] transaction","detail":"{read_only:false; response_revision:1127; number_of_response:1; }","duration":"351.854902ms","start":"2026-05-05T16:57:34.989922Z","end":"2026-05-05T16:57:35.341777Z","steps":["trace[636142855] 'process raft request' (duration: 351.626824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:35.341952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:34.989895Z","time spent":"351.970246ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:57:35.659728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.680068ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"warn","ts":"2026-05-05T16:57:50.046056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.204454244s","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-05T16:57:50.046124Z","caller":"traceutil/trace.go:171","msg":"trace[1222555836] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1219; }","duration":"2.204533357s","start":"2026-05-05T16:57:47.841578Z","end":"2026-05-05T16:57:50.046111Z","steps":["trace[1222555836] 'range keys from in-memory index tree' (duration: 2.20433184s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:50.046151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:47.84152Z","time spent":"2.20462348s","remote":"127.0.0.1:33360","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-05T16:57:50.046277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.168694824s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:57:50.046295Z","caller":"traceutil/trace.go:171","msg":"trace[1029736887] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1219; }","duration":"2.168734604s","start":"2026-05-05T16:57:47.877555Z","end":"2026-05-05T16:57:50.04629Z","steps":["trace[1029736887] 'range keys from in-memory index tree' (duration: 2.168639482s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:50.04683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.158186455s","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-05T16:57:50.046859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.445424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:57:50.046897Z","caller":"traceutil/trace.go:171","msg":"trace[872948436] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1219; }","duration":"647.519346ms","start":"2026-05-05T16:57:49.399367Z","end":"2026-05-05T16:57:50.046886Z","steps":["trace[872948436] 'range keys from in-memory index tree' (duration: 647.34069ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:50.046895Z","caller":"traceutil/trace.go:171","msg":"trace[1335530095] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1219; }","duration":"2.15832514s","start":"2026-05-05T16:57:47.888556Z","end":"2026-05-05T16:57:50.046882Z","steps":["trace[1335530095] 'range keys from in-memory index tree' (duration: 2.157961998s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:50.046917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:49.399341Z","time spent":"647.571338ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T16:57:50.046922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:47.888517Z","time spent":"2.158399262s","remote":"127.0.0.1:33360","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-05T16:57:50.046785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.844176ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:57:50.047093Z","caller":"traceutil/trace.go:171","msg":"trace[586971092] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1219; }","duration":"579.178957ms","start":"2026-05-05T16:57:49.467906Z","end":"2026-05-05T16:57:50.047085Z","steps":["trace[586971092] 'range keys from in-memory index tree' (duration: 578.826276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:50.047123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.090660322s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T16:57:50.04703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.097277135s","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-05T16:57:50.047193Z","caller":"traceutil/trace.go:171","msg":"trace[1386310307] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1219; }","duration":"2.090747495s","start":"2026-05-05T16:57:47.956426Z","end":"2026-05-05T16:57:50.047174Z","steps":["trace[1386310307] 'range keys from in-memory index tree' (duration: 2.090565509s)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:50.047213Z","caller":"traceutil/trace.go:171","msg":"trace[232250504] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1219; }","duration":"2.097536724s","start":"2026-05-05T16:57:47.94967Z","end":"2026-05-05T16:57:50.047207Z","steps":["trace[232250504] 'range keys from in-memory index tree' (duration: 2.097082039s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:50.047233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:47.949643Z","time spent":"2.097582846s","remote":"127.0.0.1:33360","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-05T16:57:50.047231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.090157477s","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-05T16:57:50.047255Z","caller":"traceutil/trace.go:171","msg":"trace[37185038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1219; }","duration":"2.090210148s","start":"2026-05-05T16:57:47.957039Z","end":"2026-05-05T16:57:50.047249Z","steps":["trace[37185038] 'range keys from in-memory index tree' (duration: 2.090065333s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:50.047273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:47.957024Z","time spent":"2.090244789s","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T16:57:50.04723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:47.956405Z","time spent":"2.090817748s","remote":"127.0.0.1:33360","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-05T16:57:50.04631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:47.877544Z","time spent":"2.168762646s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T16:57:50.062221Z","caller":"traceutil/trace.go:171","msg":"trace[130078173] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1269; }","duration":"183.995451ms","start":"2026-05-05T16:57:49.878203Z","end":"2026-05-05T16:57:50.062198Z","steps":["trace[130078173] 'read index received' (duration: 183.773843ms)","trace[130078173] 'applied index is now lower than readState.Index' (duration: 220.477µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:57:50.062324Z","caller":"traceutil/trace.go:171","msg":"trace[1262332717] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"650.033217ms","start":"2026-05-05T16:57:49.412276Z","end":"2026-05-05T16:57:50.062309Z","steps":["trace[1262332717] 'process raft request' (duration: 649.722277ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:50.06236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.147716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:57:50.0624Z","caller":"traceutil/trace.go:171","msg":"trace[595827891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1220; }","duration":"184.236749ms","start":"2026-05-05T16:57:49.878152Z","end":"2026-05-05T16:57:50.062389Z","steps":["trace[595827891] 'agreement among raft nodes before linearized reading' (duration: 184.160536ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:50.062415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:57:49.412258Z","time spent":"650.104039ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:57:56.288811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.464146ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:57:56.288941Z","caller":"traceutil/trace.go:171","msg":"trace[2122214449] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"196.640487ms","start":"2026-05-05T16:57:56.092281Z","end":"2026-05-05T16:57:56.288922Z","steps":["trace[2122214449] 'process raft request' (duration: 20.982065ms)","trace[2122214449] 'compare' (duration: 175.222018ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:57:56.32323Z","caller":"traceutil/trace.go:171","msg":"trace[1198375972] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1293; }","duration":"230.190126ms","start":"2026-05-05T16:57:56.09302Z","end":"2026-05-05T16:57:56.32321Z","steps":["trace[1198375972] 'read index received' (duration: 20.253411ms)","trace[1198375972] 'applied index is now lower than readState.Index' (duration: 209.935835ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:57:56.323293Z","caller":"traceutil/trace.go:171","msg":"trace[896665485] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"227.884143ms","start":"2026-05-05T16:57:56.095387Z","end":"2026-05-05T16:57:56.323271Z","steps":["trace[896665485] 'process raft request' (duration: 227.79072ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:56.323275Z","caller":"traceutil/trace.go:171","msg":"trace[320049799] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"228.710459ms","start":"2026-05-05T16:57:56.094526Z","end":"2026-05-05T16:57:56.323237Z","steps":["trace[320049799] 'process raft request' (duration: 228.620536ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:56.323461Z","caller":"traceutil/trace.go:171","msg":"trace[255599407] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"230.91224ms","start":"2026-05-05T16:57:56.092529Z","end":"2026-05-05T16:57:56.323442Z","steps":["trace[255599407] 'process raft request' (duration: 230.534728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:56.323474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.419935ms","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-05T16:57:56.323546Z","caller":"traceutil/trace.go:171","msg":"trace[1680062242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1245; }","duration":"230.540989ms","start":"2026-05-05T16:57:56.092988Z","end":"2026-05-05T16:57:56.323529Z","steps":["trace[1680062242] 'agreement among raft nodes before linearized reading' (duration: 230.310121ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:07.099668Z","caller":"traceutil/trace.go:171","msg":"trace[409473702] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"210.54284ms","start":"2026-05-05T16:58:06.8891Z","end":"2026-05-05T16:58:07.099642Z","steps":["trace[409473702] 'process raft request' (duration: 210.366444ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:07.212738Z","caller":"traceutil/trace.go:171","msg":"trace[152210312] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"105.522059ms","start":"2026-05-05T16:58:07.107189Z","end":"2026-05-05T16:58:07.212711Z","steps":["trace[152210312] 'process raft request' (duration: 105.376344ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:08.312202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.187112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:58:08.312274Z","caller":"traceutil/trace.go:171","msg":"trace[797256779] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"695.292675ms","start":"2026-05-05T16:58:07.616969Z","end":"2026-05-05T16:58:08.312262Z","steps":["trace[797256779] 'process raft request' (duration: 198.98654ms)","trace[797256779] 'compare' (duration: 495.982125ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:08.313304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:07.616936Z","time spent":"696.292747ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:08.315616Z","caller":"traceutil/trace.go:171","msg":"trace[941414305] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"175.710992ms","start":"2026-05-05T16:58:08.139878Z","end":"2026-05-05T16:58:08.315589Z","steps":["trace[941414305] 'process raft request' (duration: 175.491345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:08.876249Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225964796906572,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T16:58:08.984466Z","caller":"traceutil/trace.go:171","msg":"trace[1127262576] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1355; }","duration":"608.732596ms","start":"2026-05-05T16:58:08.375701Z","end":"2026-05-05T16:58:08.984433Z","steps":["trace[1127262576] 'read index received' (duration: 538.122299ms)","trace[1127262576] 'applied index is now lower than readState.Index' (duration: 70.608967ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:08.984589Z","caller":"traceutil/trace.go:171","msg":"trace[421134820] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"642.382876ms","start":"2026-05-05T16:58:08.342196Z","end":"2026-05-05T16:58:08.984579Z","steps":["trace[421134820] 'process raft request' (duration: 571.766239ms)","trace[421134820] 'compare' (duration: 70.231955ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:08.984679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:08.34218Z","time spent":"642.429157ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:08.984963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.258593ms","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-05T16:58:08.984988Z","caller":"traceutil/trace.go:171","msg":"trace[1026323192] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1302; }","duration":"609.301723ms","start":"2026-05-05T16:58:08.375677Z","end":"2026-05-05T16:58:08.984979Z","steps":["trace[1026323192] 'agreement among raft nodes before linearized reading' (duration: 609.19553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:08.985007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:08.375668Z","time spent":"609.334435ms","remote":"127.0.0.1:33360","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-05T16:58:08.985188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.789475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T16:58:08.985189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.918464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T16:58:08.985226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.308819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T16:58:08.985258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.435903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T16:58:08.985271Z","caller":"traceutil/trace.go:171","msg":"trace[461871632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1302; }","duration":"109.891969ms","start":"2026-05-05T16:58:08.875356Z","end":"2026-05-05T16:58:08.985248Z","steps":["trace[461871632] 'agreement among raft nodes before linearized reading' (duration: 109.767335ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:08.985297Z","caller":"traceutil/trace.go:171","msg":"trace[280223184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1302; }","duration":"597.508705ms","start":"2026-05-05T16:58:08.387781Z","end":"2026-05-05T16:58:08.98529Z","steps":["trace[280223184] 'agreement among raft nodes before linearized reading' (duration: 597.406322ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:08.985292Z","caller":"traceutil/trace.go:171","msg":"trace[2088944053] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1302; }","duration":"354.040878ms","start":"2026-05-05T16:58:08.631211Z","end":"2026-05-05T16:58:08.985252Z","steps":["trace[2088944053] 'agreement among raft nodes before linearized reading' (duration: 353.882513ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:08.985299Z","caller":"traceutil/trace.go:171","msg":"trace[453887450] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1302; }","duration":"327.411753ms","start":"2026-05-05T16:58:08.657867Z","end":"2026-05-05T16:58:08.985278Z","steps":["trace[453887450] 'agreement among raft nodes before linearized reading' (duration: 327.282589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:08.985317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:08.387763Z","time spent":"597.550166ms","remote":"127.0.0.1:33360","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-05T16:58:08.985282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.841009ms","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-05T16:58:08.985348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:08.657857Z","time spent":"327.481085ms","remote":"127.0.0.1:33558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-05T16:58:08.985384Z","caller":"traceutil/trace.go:171","msg":"trace[826383173] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1302; }","duration":"608.965443ms","start":"2026-05-05T16:58:08.376392Z","end":"2026-05-05T16:58:08.985357Z","steps":["trace[826383173] 'agreement among raft nodes before linearized reading' (duration: 608.688615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:08.985343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:08.631181Z","time spent":"354.147232ms","remote":"127.0.0.1:48230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":4,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-05T16:58:08.985434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:08.376387Z","time spent":"609.036066ms","remote":"127.0.0.1:33360","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-05T16:58:08.985688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.604308ms","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-05T16:58:08.985773Z","caller":"traceutil/trace.go:171","msg":"trace[846504984] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1302; }","duration":"606.68469ms","start":"2026-05-05T16:58:08.379064Z","end":"2026-05-05T16:58:08.985748Z","steps":["trace[846504984] 'agreement among raft nodes before linearized reading' (duration: 606.131522ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:08.985808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:08.37906Z","time spent":"606.740092ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T16:58:09.203721Z","caller":"traceutil/trace.go:171","msg":"trace[155813293] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"213.780104ms","start":"2026-05-05T16:58:08.989914Z","end":"2026-05-05T16:58:09.203694Z","steps":["trace[155813293] 'process raft request' (duration: 213.702662ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:09.203802Z","caller":"traceutil/trace.go:171","msg":"trace[365431884] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"213.923999ms","start":"2026-05-05T16:58:08.989825Z","end":"2026-05-05T16:58:09.203749Z","steps":["trace[365431884] 'process raft request' (duration: 193.339398ms)","trace[365431884] 'compare' (duration: 20.284871ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:09.203882Z","caller":"traceutil/trace.go:171","msg":"trace[390060229] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"213.754254ms","start":"2026-05-05T16:58:08.990116Z","end":"2026-05-05T16:58:09.203871Z","steps":["trace[390060229] 'process raft request' (duration: 213.539817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:10.666367Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225964796906582,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T16:58:11.122008Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.077238777s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-05T16:58:11.122255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:10.04465Z","time spent":"1.077600759s","remote":"127.0.0.1:33158","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-05T16:58:11.507961Z","caller":"traceutil/trace.go:171","msg":"trace[1605179921] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1361; }","duration":"1.342402891s","start":"2026-05-05T16:58:10.165537Z","end":"2026-05-05T16:58:11.50794Z","steps":["trace[1605179921] 'read index received' (duration: 956.692027ms)","trace[1605179921] 'applied index is now lower than readState.Index' (duration: 385.709564ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:11.508097Z","caller":"traceutil/trace.go:171","msg":"trace[2106201376] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"384.856827ms","start":"2026-05-05T16:58:11.123217Z","end":"2026-05-05T16:58:11.508074Z","steps":["trace[2106201376] 'process raft request' (duration: 384.651341ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:11.508167Z","caller":"traceutil/trace.go:171","msg":"trace[1573599268] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"1.184941317s","start":"2026-05-05T16:58:10.323209Z","end":"2026-05-05T16:58:11.50815Z","steps":["trace[1573599268] 'process raft request' (duration: 1.164264823s)","trace[1573599268] 'compare' (duration: 20.044164ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:11.508212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:11.123149Z","time spent":"384.998282ms","remote":"127.0.0.1:33158","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:11.508296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:10.323187Z","time spent":"1.1850223s","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:11.508558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.052812405s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T16:58:11.508953Z","caller":"traceutil/trace.go:171","msg":"trace[1193090576] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1309; }","duration":"1.053251868s","start":"2026-05-05T16:58:10.45569Z","end":"2026-05-05T16:58:11.508942Z","steps":["trace[1193090576] 'agreement among raft nodes before linearized reading' (duration: 1.052773333s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:11.50906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:10.455669Z","time spent":"1.053379653s","remote":"127.0.0.1:33288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":29,"request content":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true "} {"level":"warn","ts":"2026-05-05T16:58:11.508637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.661094ms","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-05T16:58:11.509183Z","caller":"traceutil/trace.go:171","msg":"trace[469894668] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1309; }","duration":"301.186091ms","start":"2026-05-05T16:58:11.207959Z","end":"2026-05-05T16:58:11.509145Z","steps":["trace[469894668] 'agreement among raft nodes before linearized reading' (duration: 300.53006ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:11.50926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:11.207905Z","time spent":"301.305174ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T16:58:11.508681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.235128ms","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-05T16:58:11.509531Z","caller":"traceutil/trace.go:171","msg":"trace[717422034] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1309; }","duration":"300.085405ms","start":"2026-05-05T16:58:11.209436Z","end":"2026-05-05T16:58:11.509521Z","steps":["trace[717422034] 'agreement among raft nodes before linearized reading' (duration: 299.164565ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:11.5096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:11.209407Z","time spent":"300.160667ms","remote":"127.0.0.1:33360","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-05T16:58:11.508683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.089244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-05T16:58:11.508733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.629769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-05T16:58:11.509724Z","caller":"traceutil/trace.go:171","msg":"trace[129376086] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1309; }","duration":"300.183418ms","start":"2026-05-05T16:58:11.209505Z","end":"2026-05-05T16:58:11.509689Z","steps":["trace[129376086] 'agreement among raft nodes before linearized reading' (duration: 298.99212ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:11.508772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.844911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T16:58:11.509839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:11.209478Z","time spent":"300.320383ms","remote":"127.0.0.1:33360","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":"info","ts":"2026-05-05T16:58:11.509762Z","caller":"traceutil/trace.go:171","msg":"trace[171790648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1309; }","duration":"494.685844ms","start":"2026-05-05T16:58:11.01506Z","end":"2026-05-05T16:58:11.509746Z","steps":["trace[171790648] 'agreement among raft nodes before linearized reading' (duration: 493.61809ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:11.509864Z","caller":"traceutil/trace.go:171","msg":"trace[1201059495] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1309; }","duration":"491.965146ms","start":"2026-05-05T16:58:11.01788Z","end":"2026-05-05T16:58:11.509845Z","steps":["trace[1201059495] 'agreement among raft nodes before linearized reading' (duration: 490.872141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:11.509901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:11.015045Z","time spent":"494.840349ms","remote":"127.0.0.1:33360","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-05T16:58:11.509933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:11.017861Z","time spent":"492.05456ms","remote":"127.0.0.1:33434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-05-05T16:58:11.508775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.343233788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:11.510009Z","caller":"traceutil/trace.go:171","msg":"trace[1367883902] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1309; }","duration":"1.344503479s","start":"2026-05-05T16:58:10.165493Z","end":"2026-05-05T16:58:11.509997Z","steps":["trace[1367883902] 'agreement among raft nodes before linearized reading' (duration: 1.343227638s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:11.50885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.841133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:11.510093Z","caller":"traceutil/trace.go:171","msg":"trace[1602552630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1309; }","duration":"631.130275ms","start":"2026-05-05T16:58:10.878949Z","end":"2026-05-05T16:58:11.51008Z","steps":["trace[1602552630] 'agreement among raft nodes before linearized reading' (duration: 629.851434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:11.510104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:10.165477Z","time spent":"1.344571311s","remote":"127.0.0.1:48548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-05-05T16:58:11.510144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:10.878932Z","time spent":"631.202558ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T16:58:11.508887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.350505ms","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-05T16:58:11.51025Z","caller":"traceutil/trace.go:171","msg":"trace[409555527] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1309; }","duration":"520.73464ms","start":"2026-05-05T16:58:10.989501Z","end":"2026-05-05T16:58:11.510236Z","steps":["trace[409555527] 'agreement among raft nodes before linearized reading' (duration: 519.297044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:11.510302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:10.989483Z","time spent":"520.806013ms","remote":"127.0.0.1:33360","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":"info","ts":"2026-05-05T16:58:11.83808Z","caller":"traceutil/trace.go:171","msg":"trace[1169764376] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"325.179921ms","start":"2026-05-05T16:58:11.51288Z","end":"2026-05-05T16:58:11.83806Z","steps":["trace[1169764376] 'process raft request' (duration: 325.031106ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:11.838046Z","caller":"traceutil/trace.go:171","msg":"trace[122709788] linearizableReadLoop","detail":"{readStateIndex:1365; appliedIndex:1364; }","duration":"324.591322ms","start":"2026-05-05T16:58:11.513437Z","end":"2026-05-05T16:58:11.838028Z","steps":["trace[122709788] 'read index received' (duration: 324.461238ms)","trace[122709788] 'applied index is now lower than readState.Index' (duration: 129.324µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:11.838188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:11.512865Z","time spent":"325.261083ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:11.838229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.785338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T16:58:11.838263Z","caller":"traceutil/trace.go:171","msg":"trace[30039472] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1310; }","duration":"324.85207ms","start":"2026-05-05T16:58:11.513403Z","end":"2026-05-05T16:58:11.838255Z","steps":["trace[30039472] 'agreement among raft nodes before linearized reading' (duration: 324.733886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:11.838287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:11.513389Z","time spent":"324.893102ms","remote":"127.0.0.1:33158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-05-05T16:58:11.910132Z","caller":"traceutil/trace.go:171","msg":"trace[1556973368] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"395.23512ms","start":"2026-05-05T16:58:11.51487Z","end":"2026-05-05T16:58:11.910105Z","steps":["trace[1556973368] 'process raft request' (duration: 395.040794ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:11.910182Z","caller":"traceutil/trace.go:171","msg":"trace[899519563] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"395.2194ms","start":"2026-05-05T16:58:11.51494Z","end":"2026-05-05T16:58:11.910159Z","steps":["trace[899519563] 'process raft request' (duration: 395.092246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:11.910289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:11.51485Z","time spent":"395.360455ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:11.910326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:11.514936Z","time spent":"395.309653ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:11.910537Z","caller":"traceutil/trace.go:171","msg":"trace[2048093714] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"395.54417ms","start":"2026-05-05T16:58:11.514956Z","end":"2026-05-05T16:58:11.9105Z","steps":["trace[2048093714] 'process raft request' (duration: 395.107576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:11.9106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:11.514946Z","time spent":"395.622593ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:11.910683Z","caller":"traceutil/trace.go:171","msg":"trace[258385362] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"395.662174ms","start":"2026-05-05T16:58:11.515014Z","end":"2026-05-05T16:58:11.910676Z","steps":["trace[258385362] 'process raft request' (duration: 395.075726ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:11.91073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:11.51499Z","time spent":"395.716905ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:11.910846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.601444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:11.910882Z","caller":"traceutil/trace.go:171","msg":"trace[173209988] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1314; }","duration":"163.637685ms","start":"2026-05-05T16:58:11.747236Z","end":"2026-05-05T16:58:11.910873Z","steps":["trace[173209988] 'agreement among raft nodes before linearized reading' (duration: 163.553152ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:12.283463Z","caller":"traceutil/trace.go:171","msg":"trace[1839724932] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"152.157585ms","start":"2026-05-05T16:58:12.131284Z","end":"2026-05-05T16:58:12.283441Z","steps":["trace[1839724932] 'process raft request' (duration: 133.882768ms)","trace[1839724932] 'compare' (duration: 17.962527ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:12.510978Z","caller":"traceutil/trace.go:171","msg":"trace[977848990] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"334.132189ms","start":"2026-05-05T16:58:12.176813Z","end":"2026-05-05T16:58:12.510945Z","steps":["trace[977848990] 'process raft request' (duration: 243.689805ms)","trace[977848990] 'compare' (duration: 90.225307ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:12.510976Z","caller":"traceutil/trace.go:171","msg":"trace[1762852409] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"280.640611ms","start":"2026-05-05T16:58:12.230315Z","end":"2026-05-05T16:58:12.510955Z","steps":["trace[1762852409] 'process raft request' (duration: 280.59572ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:12.51105Z","caller":"traceutil/trace.go:171","msg":"trace[2139960184] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"327.810146ms","start":"2026-05-05T16:58:12.183216Z","end":"2026-05-05T16:58:12.511026Z","steps":["trace[2139960184] 'process raft request' (duration: 327.64424ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:12.511095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:12.176797Z","time spent":"334.241592ms","remote":"127.0.0.1:33186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T16:58:12.51115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:12.183204Z","time spent":"327.893348ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:14.185169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.460904ms","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-05T16:58:14.185243Z","caller":"traceutil/trace.go:171","msg":"trace[1434388561] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1322; }","duration":"270.569728ms","start":"2026-05-05T16:58:13.914658Z","end":"2026-05-05T16:58:14.185227Z","steps":["trace[1434388561] 'range keys from in-memory index tree' (duration: 270.275488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:14.185219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.832784ms","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-05T16:58:14.185303Z","caller":"traceutil/trace.go:171","msg":"trace[736307863] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1322; }","duration":"269.943798ms","start":"2026-05-05T16:58:13.915343Z","end":"2026-05-05T16:58:14.185287Z","steps":["trace[736307863] 'range keys from in-memory index tree' (duration: 269.671609ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:14.185483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.784985ms","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-05T16:58:14.185538Z","caller":"traceutil/trace.go:171","msg":"trace[1388528830] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1322; }","duration":"270.880818ms","start":"2026-05-05T16:58:13.914647Z","end":"2026-05-05T16:58:14.185527Z","steps":["trace[1388528830] 'range keys from in-memory index tree' (duration: 270.641271ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:14.185571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.245659ms","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-05T16:58:14.185608Z","caller":"traceutil/trace.go:171","msg":"trace[1880782960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1322; }","duration":"271.313521ms","start":"2026-05-05T16:58:13.914287Z","end":"2026-05-05T16:58:14.185601Z","steps":["trace[1880782960] 'range keys from in-memory index tree' (duration: 271.150287ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:14.185742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.11244ms","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-05T16:58:14.185791Z","caller":"traceutil/trace.go:171","msg":"trace[1681654482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1322; }","duration":"344.197192ms","start":"2026-05-05T16:58:13.841578Z","end":"2026-05-05T16:58:14.185775Z","steps":["trace[1681654482] 'range keys from in-memory index tree' (duration: 343.949674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:14.185479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.026901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:14.185833Z","caller":"traceutil/trace.go:171","msg":"trace[1294954155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1322; }","duration":"308.435554ms","start":"2026-05-05T16:58:13.87739Z","end":"2026-05-05T16:58:14.185825Z","steps":["trace[1294954155] 'range keys from in-memory index tree' (duration: 307.928658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:14.185832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:13.841556Z","time spent":"344.268374ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T16:58:14.185887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:13.877369Z","time spent":"308.510596ms","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T16:58:14.597632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.493561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:58:14.597844Z","caller":"traceutil/trace.go:171","msg":"trace[369831458] transaction","detail":"{read_only:false; response_revision:1327; number_of_response:1; }","duration":"405.83274ms","start":"2026-05-05T16:58:14.191995Z","end":"2026-05-05T16:58:14.597828Z","steps":["trace[369831458] 'process raft request' (duration: 405.794729ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:14.597904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:14.191983Z","time spent":"405.892853ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:14.598097Z","caller":"traceutil/trace.go:171","msg":"trace[925342821] transaction","detail":"{read_only:false; response_revision:1324; number_of_response:1; }","duration":"407.38377ms","start":"2026-05-05T16:58:14.190702Z","end":"2026-05-05T16:58:14.598085Z","steps":["trace[925342821] 'process raft request' (duration: 285.364063ms)","trace[925342821] 'compare' (duration: 121.384697ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:14.598145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:14.190677Z","time spent":"407.443512ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:14.598172Z","caller":"traceutil/trace.go:171","msg":"trace[1746824079] transaction","detail":"{read_only:false; response_revision:1325; number_of_response:1; }","duration":"407.409471ms","start":"2026-05-05T16:58:14.190706Z","end":"2026-05-05T16:58:14.598116Z","steps":["trace[1746824079] 'process raft request' (duration: 407.009118ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:14.598237Z","caller":"traceutil/trace.go:171","msg":"trace[617435145] transaction","detail":"{read_only:false; response_revision:1326; number_of_response:1; }","duration":"407.445643ms","start":"2026-05-05T16:58:14.19078Z","end":"2026-05-05T16:58:14.598226Z","steps":["trace[617435145] 'process raft request' (duration: 406.979478ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:14.598283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:14.190768Z","time spent":"407.490394ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:14.598344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:14.190684Z","time spent":"407.568857ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:16.804043Z","caller":"traceutil/trace.go:171","msg":"trace[2009654025] transaction","detail":"{read_only:false; response_revision:1337; number_of_response:1; }","duration":"198.661799ms","start":"2026-05-05T16:58:16.605323Z","end":"2026-05-05T16:58:16.803985Z","steps":["trace[2009654025] 'process raft request' (duration: 198.517604ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:16.843817Z","caller":"traceutil/trace.go:171","msg":"trace[1258715762] transaction","detail":"{read_only:false; response_revision:1338; number_of_response:1; }","duration":"238.130836ms","start":"2026-05-05T16:58:16.605664Z","end":"2026-05-05T16:58:16.843795Z","steps":["trace[1258715762] 'process raft request' (duration: 236.626108ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:16.843818Z","caller":"traceutil/trace.go:171","msg":"trace[2089053243] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"237.239237ms","start":"2026-05-05T16:58:16.606554Z","end":"2026-05-05T16:58:16.843793Z","steps":["trace[2089053243] 'process raft request' (duration: 237.198306ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:16.843883Z","caller":"traceutil/trace.go:171","msg":"trace[569197693] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"237.352021ms","start":"2026-05-05T16:58:16.606505Z","end":"2026-05-05T16:58:16.843857Z","steps":["trace[569197693] 'process raft request' (duration: 237.205296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:18.463091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.019271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-05T16:58:18.463194Z","caller":"traceutil/trace.go:171","msg":"trace[1293128547] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1350; }","duration":"114.154346ms","start":"2026-05-05T16:58:18.349016Z","end":"2026-05-05T16:58:18.46317Z","steps":["trace[1293128547] 'range keys from in-memory index tree' (duration: 113.943068ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:18.463283Z","caller":"traceutil/trace.go:171","msg":"trace[1101211877] transaction","detail":"{read_only:false; response_revision:1351; number_of_response:1; }","duration":"102.144889ms","start":"2026-05-05T16:58:18.361114Z","end":"2026-05-05T16:58:18.463259Z","steps":["trace[1101211877] 'process raft request' (duration: 98.650237ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:18.46338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.943711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:98096"} {"level":"info","ts":"2026-05-05T16:58:18.463535Z","caller":"traceutil/trace.go:171","msg":"trace[1105899990] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1350; }","duration":"115.169547ms","start":"2026-05-05T16:58:18.34834Z","end":"2026-05-05T16:58:18.46351Z","steps":["trace[1105899990] 'range keys from in-memory index tree' (duration: 114.681033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:19.692155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.622453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:58:19.692266Z","caller":"traceutil/trace.go:171","msg":"trace[451299907] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"257.149456ms","start":"2026-05-05T16:58:19.435098Z","end":"2026-05-05T16:58:19.692248Z","steps":["trace[451299907] 'process raft request' (duration: 155.052038ms)","trace[451299907] 'compare' (duration: 101.149488ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:19.774859Z","caller":"traceutil/trace.go:171","msg":"trace[1526211159] transaction","detail":"{read_only:false; response_revision:1372; number_of_response:1; }","duration":"339.068597ms","start":"2026-05-05T16:58:19.43533Z","end":"2026-05-05T16:58:19.774399Z","steps":["trace[1526211159] 'process raft request' (duration: 338.818819ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:19.775021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:19.435311Z","time spent":"339.620405ms","remote":"127.0.0.1:33372","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:19.775257Z","caller":"traceutil/trace.go:171","msg":"trace[1347388021] transaction","detail":"{read_only:false; response_revision:1374; number_of_response:1; }","duration":"201.306264ms","start":"2026-05-05T16:58:19.573098Z","end":"2026-05-05T16:58:19.774404Z","steps":["trace[1347388021] 'process raft request' (duration: 201.261003ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:19.775432Z","caller":"traceutil/trace.go:171","msg":"trace[26099114] transaction","detail":"{read_only:false; response_revision:1373; number_of_response:1; }","duration":"333.829459ms","start":"2026-05-05T16:58:19.440587Z","end":"2026-05-05T16:58:19.774416Z","steps":["trace[26099114] 'process raft request' (duration: 333.718665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:19.7755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:19.440559Z","time spent":"334.904123ms","remote":"127.0.0.1:33594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10921,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:19.990449Z","caller":"traceutil/trace.go:171","msg":"trace[370135589] linearizableReadLoop","detail":"{readStateIndex:1431; appliedIndex:1430; }","duration":"112.548834ms","start":"2026-05-05T16:58:19.877885Z","end":"2026-05-05T16:58:19.990434Z","steps":["trace[370135589] 'read index received' (duration: 109.493636ms)","trace[370135589] 'applied index is now lower than readState.Index' (duration: 3.054668ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:19.990562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.656357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:19.990539Z","caller":"traceutil/trace.go:171","msg":"trace[1482616244] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"203.463113ms","start":"2026-05-05T16:58:19.787028Z","end":"2026-05-05T16:58:19.990492Z","steps":["trace[1482616244] 'process raft request' (duration: 200.401374ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:19.99059Z","caller":"traceutil/trace.go:171","msg":"trace[653914860] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1375; }","duration":"112.730819ms","start":"2026-05-05T16:58:19.877851Z","end":"2026-05-05T16:58:19.990582Z","steps":["trace[653914860] 'agreement among raft nodes before linearized reading' (duration: 112.644867ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:22.879397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000230493s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-05T16:58:22.879466Z","caller":"traceutil/trace.go:171","msg":"trace[1117811101] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000342936s","start":"2026-05-05T16:58:20.879108Z","end":"2026-05-05T16:58:22.879451Z","steps":["trace[1117811101] 'agreement among raft nodes before linearized reading' (duration: 2.000254413s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:22.879504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:20.879092Z","time spent":"2.000405079s","remote":"127.0.0.1:33108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/05 16:58:22 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-05T16:58:22.957878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.818556841s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:58:22.958808Z","caller":"traceutil/trace.go:171","msg":"trace[757360893] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"2.138625277s","start":"2026-05-05T16:58:20.820138Z","end":"2026-05-05T16:58:22.958763Z","steps":["trace[757360893] 'process raft request' (duration: 319.072974ms)","trace[757360893] 'compare' (duration: 1.817997133s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:22.959019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:20.820114Z","time spent":"2.138786211s","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:22.959144Z","caller":"traceutil/trace.go:171","msg":"trace[724549919] linearizableReadLoop","detail":"{readStateIndex:1436; appliedIndex:1435; }","duration":"2.104616284s","start":"2026-05-05T16:58:20.854499Z","end":"2026-05-05T16:58:22.959115Z","steps":["trace[724549919] 'read index received' (duration: 284.680189ms)","trace[724549919] 'applied index is now lower than readState.Index' (duration: 1.819933865s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:22.959375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.104868733s","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-05T16:58:22.959421Z","caller":"traceutil/trace.go:171","msg":"trace[1021390427] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1380; }","duration":"2.104959276s","start":"2026-05-05T16:58:20.854451Z","end":"2026-05-05T16:58:22.95941Z","steps":["trace[1021390427] 'agreement among raft nodes before linearized reading' (duration: 2.10478492s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:22.959456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:20.854431Z","time spent":"2.105017558s","remote":"127.0.0.1:33360","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":"info","ts":"2026-05-05T16:58:22.958971Z","caller":"traceutil/trace.go:171","msg":"trace[2143771148] transaction","detail":"{read_only:false; response_revision:1380; number_of_response:1; }","duration":"1.177508588s","start":"2026-05-05T16:58:21.781437Z","end":"2026-05-05T16:58:22.958946Z","steps":["trace[2143771148] 'process raft request' (duration: 1.176689451s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:22.959873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:21.781418Z","time spent":"1.178299014s","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:23.460095Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225964796906728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T16:58:23.960987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225964796906728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T16:58:24.462185Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11521225964796906728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T16:58:24.880295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000358108s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-05-05T16:58:24.880429Z","caller":"traceutil/trace.go:171","msg":"trace[1075302459] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000532454s","start":"2026-05-05T16:58:22.879874Z","end":"2026-05-05T16:58:24.880407Z","steps":["trace[1075302459] 'agreement among raft nodes before linearized reading' (duration: 2.000380179s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:24.880479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:22.879861Z","time spent":"2.000607327s","remote":"127.0.0.1:33110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/05/05 16:58:24 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-05-05T16:58:24.915965Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.178802188s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T16:58:24.916437Z","caller":"traceutil/trace.go:171","msg":"trace[33636579] transaction","detail":"{read_only:false; response_revision:1381; number_of_response:1; }","duration":"2.300836696s","start":"2026-05-05T16:58:22.615574Z","end":"2026-05-05T16:58:24.916411Z","steps":["trace[33636579] 'process raft request' (duration: 2.300703271s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:24.916581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:22.615555Z","time spent":"2.30095359s","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:24.967793Z","caller":"traceutil/trace.go:171","msg":"trace[2115212984] linearizableReadLoop","detail":"{readStateIndex:1439; appliedIndex:1438; }","duration":"2.00849696s","start":"2026-05-05T16:58:22.959206Z","end":"2026-05-05T16:58:24.967703Z","steps":["trace[2115212984] 'read index received' (duration: 1.957464081s)","trace[2115212984] 'applied index is now lower than readState.Index' (duration: 51.026898ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:24.967902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.537869347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T16:58:24.967952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.84352776s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T16:58:24.967968Z","caller":"traceutil/trace.go:171","msg":"trace[1977488895] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1382; }","duration":"2.537991091s","start":"2026-05-05T16:58:22.429959Z","end":"2026-05-05T16:58:24.96795Z","steps":["trace[1977488895] 'agreement among raft nodes before linearized reading' (duration: 2.537799354s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:24.968027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:22.429915Z","time spent":"2.538101755s","remote":"127.0.0.1:33208","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-05-05T16:58:24.968119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.160012354s","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-05T16:58:24.96819Z","caller":"traceutil/trace.go:171","msg":"trace[773932629] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1382; }","duration":"2.160133409s","start":"2026-05-05T16:58:22.808036Z","end":"2026-05-05T16:58:24.968169Z","steps":["trace[773932629] 'agreement among raft nodes before linearized reading' (duration: 2.159918832s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:24.968231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:22.808014Z","time spent":"2.16020869s","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T16:58:24.968227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.639029605s","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-05T16:58:24.96817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.735611364s","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:530"} {"level":"info","ts":"2026-05-05T16:58:24.968277Z","caller":"traceutil/trace.go:171","msg":"trace[163038553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1382; }","duration":"2.639158959s","start":"2026-05-05T16:58:22.329108Z","end":"2026-05-05T16:58:24.968267Z","steps":["trace[163038553] 'agreement among raft nodes before linearized reading' (duration: 2.639010104s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:24.968389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:22.329074Z","time spent":"2.639303103s","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-05T16:58:24.968414Z","caller":"traceutil/trace.go:171","msg":"trace[1387913345] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1382; }","duration":"3.735893433s","start":"2026-05-05T16:58:21.232471Z","end":"2026-05-05T16:58:24.968364Z","steps":["trace[1387913345] 'agreement among raft nodes before linearized reading' (duration: 3.735392987s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:24.968389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.44360804s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-05T16:58:24.968525Z","caller":"traceutil/trace.go:171","msg":"trace[163846003] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1382; }","duration":"2.443775986s","start":"2026-05-05T16:58:22.524734Z","end":"2026-05-05T16:58:24.96851Z","steps":["trace[163846003] 'agreement among raft nodes before linearized reading' (duration: 2.443499747s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:24.968531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:21.232446Z","time spent":"3.736050908s","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":552,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-05T16:58:24.968569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:22.524715Z","time spent":"2.443840557s","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T16:58:24.968601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.178566358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T16:58:24.968639Z","caller":"traceutil/trace.go:171","msg":"trace[568399867] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:1382; }","duration":"3.178636531s","start":"2026-05-05T16:58:21.789991Z","end":"2026-05-05T16:58:24.968627Z","steps":["trace[568399867] 'agreement among raft nodes before linearized reading' (duration: 3.17857996s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:24.968671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:21.789972Z","time spent":"3.178689873s","remote":"127.0.0.1:33636","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":29,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-05-05T16:58:24.968696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.109628042s","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-05T16:58:24.968729Z","caller":"traceutil/trace.go:171","msg":"trace[1676190899] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1382; }","duration":"4.109717205s","start":"2026-05-05T16:58:20.859002Z","end":"2026-05-05T16:58:24.968719Z","steps":["trace[1676190899] 'agreement among raft nodes before linearized reading' (duration: 4.109628042s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:24.968759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:20.858969Z","time spent":"4.109780267s","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T16:58:24.968939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.110871023s","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-05T16:58:24.967928Z","caller":"traceutil/trace.go:171","msg":"trace[237886092] transaction","detail":"{read_only:false; response_revision:1382; number_of_response:1; }","duration":"2.004326856s","start":"2026-05-05T16:58:22.963573Z","end":"2026-05-05T16:58:24.9679Z","steps":["trace[237886092] 'process raft request' (duration: 2.003974025s)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:24.969043Z","caller":"traceutil/trace.go:171","msg":"trace[409782837] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1382; }","duration":"4.111083419s","start":"2026-05-05T16:58:20.857912Z","end":"2026-05-05T16:58:24.968995Z","steps":["trace[409782837] 'agreement among raft nodes before linearized reading' (duration: 4.1107895s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:24.969092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:20.85789Z","time spent":"4.111188043s","remote":"127.0.0.1:33360","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-05T16:58:24.969125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:22.96355Z","time spent":"2.005518404s","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:24.969741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.582529122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:24.969787Z","caller":"traceutil/trace.go:171","msg":"trace[1689647233] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1383; }","duration":"1.582628365s","start":"2026-05-05T16:58:23.387147Z","end":"2026-05-05T16:58:24.969776Z","steps":["trace[1689647233] 'agreement among raft nodes before linearized reading' (duration: 1.582535852s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:24.969818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:23.387122Z","time spent":"1.582687417s","remote":"127.0.0.1:48410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T16:58:24.970554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.520786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T16:58:24.970589Z","caller":"traceutil/trace.go:171","msg":"trace[777953246] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1383; }","duration":"232.595279ms","start":"2026-05-05T16:58:24.737982Z","end":"2026-05-05T16:58:24.970578Z","steps":["trace[777953246] 'agreement among raft nodes before linearized reading' (duration: 232.511786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:24.970754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.024604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:524"} {"level":"info","ts":"2026-05-05T16:58:24.971138Z","caller":"traceutil/trace.go:171","msg":"trace[1243190739] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1382; }","duration":"3.846788754s","start":"2026-05-05T16:58:21.124328Z","end":"2026-05-05T16:58:24.971116Z","steps":["trace[1243190739] 'agreement among raft nodes before linearized reading' (duration: 3.843564341s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:24.971205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:21.124294Z","time spent":"3.846890197s","remote":"127.0.0.1:33556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-05-05T16:58:24.970779Z","caller":"traceutil/trace.go:171","msg":"trace[55663993] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1383; }","duration":"832.084246ms","start":"2026-05-05T16:58:24.138686Z","end":"2026-05-05T16:58:24.970771Z","steps":["trace[55663993] 'agreement among raft nodes before linearized reading' (duration: 832.013884ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:24.971936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:24.138667Z","time spent":"833.253233ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":546,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-05T16:58:25.576783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.355324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:58:25.576921Z","caller":"traceutil/trace.go:171","msg":"trace[1935844824] transaction","detail":"{read_only:false; response_revision:1384; number_of_response:1; }","duration":"601.435191ms","start":"2026-05-05T16:58:24.97546Z","end":"2026-05-05T16:58:25.576895Z","steps":["trace[1935844824] 'process raft request' (duration: 409.86729ms)","trace[1935844824] 'compare' (duration: 191.046424ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:25.577041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:24.975441Z","time spent":"601.556145ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:25.578381Z","caller":"traceutil/trace.go:171","msg":"trace[2060418686] transaction","detail":"{read_only:false; response_revision:1385; number_of_response:1; }","duration":"602.671891ms","start":"2026-05-05T16:58:24.975687Z","end":"2026-05-05T16:58:25.578359Z","steps":["trace[2060418686] 'process raft request' (duration: 602.516496ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:25.578676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:24.975681Z","time spent":"602.939839ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:25.579104Z","caller":"traceutil/trace.go:171","msg":"trace[1275458532] transaction","detail":"{read_only:false; response_revision:1387; number_of_response:1; }","duration":"603.360133ms","start":"2026-05-05T16:58:24.975725Z","end":"2026-05-05T16:58:25.579085Z","steps":["trace[1275458532] 'process raft request' (duration: 602.612129ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:25.57848Z","caller":"traceutil/trace.go:171","msg":"trace[600834158] transaction","detail":"{read_only:false; response_revision:1386; number_of_response:1; }","duration":"602.665641ms","start":"2026-05-05T16:58:24.975719Z","end":"2026-05-05T16:58:25.578384Z","steps":["trace[600834158] 'process raft request' (duration: 602.578508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:25.579526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:24.975715Z","time spent":"603.699414ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:25.579737Z","caller":"traceutil/trace.go:171","msg":"trace[1868329534] transaction","detail":"{read_only:false; response_revision:1388; number_of_response:1; }","duration":"603.351293ms","start":"2026-05-05T16:58:24.976369Z","end":"2026-05-05T16:58:25.579721Z","steps":["trace[1868329534] 'process raft request' (duration: 602.63585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:25.579819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:24.976363Z","time spent":"603.412255ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:25.579879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:24.975713Z","time spent":"604.127558ms","remote":"127.0.0.1:33186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:25.579823Z","caller":"traceutil/trace.go:171","msg":"trace[384489577] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"600.396148ms","start":"2026-05-05T16:58:24.979414Z","end":"2026-05-05T16:58:25.57981Z","steps":["trace[384489577] 'process raft request' (duration: 600.354546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:25.579947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:24.979401Z","time spent":"600.513862ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:25.580094Z","caller":"traceutil/trace.go:171","msg":"trace[913259324] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"601.299437ms","start":"2026-05-05T16:58:24.978769Z","end":"2026-05-05T16:58:25.580068Z","steps":["trace[913259324] 'process raft request' (duration: 600.938475ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:25.580139Z","caller":"traceutil/trace.go:171","msg":"trace[1031871575] transaction","detail":"{read_only:false; response_revision:1391; number_of_response:1; }","duration":"601.119782ms","start":"2026-05-05T16:58:24.979003Z","end":"2026-05-05T16:58:25.580123Z","steps":["trace[1031871575] 'process raft request' (duration: 600.737799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:25.58017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:24.978762Z","time spent":"601.369019ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:25.580195Z","caller":"traceutil/trace.go:171","msg":"trace[452569280] transaction","detail":"{read_only:false; response_revision:1389; number_of_response:1; }","duration":"603.183567ms","start":"2026-05-05T16:58:24.976997Z","end":"2026-05-05T16:58:25.580181Z","steps":["trace[452569280] 'process raft request' (duration: 602.587408ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:25.580221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:24.978985Z","time spent":"601.188033ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:25.58028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:24.976973Z","time spent":"603.26491ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:55.910723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.850747ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:58:55.910863Z","caller":"traceutil/trace.go:171","msg":"trace[1317825018] linearizableReadLoop","detail":"{readStateIndex:1588; appliedIndex:1587; }","duration":"175.273847ms","start":"2026-05-05T16:58:55.735559Z","end":"2026-05-05T16:58:55.910833Z","steps":["trace[1317825018] 'read index received' (duration: 18.204683ms)","trace[1317825018] 'applied index is now lower than readState.Index' (duration: 157.067924ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:55.910942Z","caller":"traceutil/trace.go:171","msg":"trace[901105835] transaction","detail":"{read_only:false; response_revision:1524; number_of_response:1; }","duration":"191.833699ms","start":"2026-05-05T16:58:55.719088Z","end":"2026-05-05T16:58:55.910921Z","steps":["trace[901105835] 'process raft request' (duration: 34.692403ms)","trace[901105835] 'compare' (duration: 156.711372ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:55.911025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.900363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-05T16:58:55.911008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.570132ms","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-05T16:58:55.91107Z","caller":"traceutil/trace.go:171","msg":"trace[775310813] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1524; }","duration":"187.965706ms","start":"2026-05-05T16:58:55.723093Z","end":"2026-05-05T16:58:55.911059Z","steps":["trace[775310813] 'agreement among raft nodes before linearized reading' (duration: 187.831171ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:55.911089Z","caller":"traceutil/trace.go:171","msg":"trace[1732507449] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1524; }","duration":"187.661426ms","start":"2026-05-05T16:58:55.723409Z","end":"2026-05-05T16:58:55.91107Z","steps":["trace[1732507449] 'agreement among raft nodes before linearized reading' (duration: 187.46514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:55.911005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.002893ms","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-05T16:58:55.911195Z","caller":"traceutil/trace.go:171","msg":"trace[1942272215] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1524; }","duration":"177.231261ms","start":"2026-05-05T16:58:55.733951Z","end":"2026-05-05T16:58:55.911182Z","steps":["trace[1942272215] 'agreement among raft nodes before linearized reading' (duration: 176.89623ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:55.916422Z","caller":"traceutil/trace.go:171","msg":"trace[300475389] transaction","detail":"{read_only:false; response_revision:1525; number_of_response:1; }","duration":"176.334662ms","start":"2026-05-05T16:58:55.740068Z","end":"2026-05-05T16:58:55.916403Z","steps":["trace[300475389] 'process raft request' (duration: 176.212958ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:55.919935Z","caller":"traceutil/trace.go:171","msg":"trace[686286985] transaction","detail":"{read_only:false; response_revision:1526; number_of_response:1; }","duration":"106.042835ms","start":"2026-05-05T16:58:55.813879Z","end":"2026-05-05T16:58:55.919922Z","steps":["trace[686286985] 'process raft request' (duration: 105.927211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:55.92059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.173995ms","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":"warn","ts":"2026-05-05T16:58:55.920971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.985199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T16:58:55.921011Z","caller":"traceutil/trace.go:171","msg":"trace[984719566] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1528; }","duration":"156.059312ms","start":"2026-05-05T16:58:55.764941Z","end":"2026-05-05T16:58:55.921Z","steps":["trace[984719566] 'agreement among raft nodes before linearized reading' (duration: 155.274576ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:55.920635Z","caller":"traceutil/trace.go:171","msg":"trace[834164706] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1528; }","duration":"161.252498ms","start":"2026-05-05T16:58:55.759372Z","end":"2026-05-05T16:58:55.920624Z","steps":["trace[834164706] 'agreement among raft nodes before linearized reading' (duration: 161.167795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:55.921214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.132549ms","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-05T16:58:55.921256Z","caller":"traceutil/trace.go:171","msg":"trace[1145287248] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1528; }","duration":"173.199872ms","start":"2026-05-05T16:58:55.748049Z","end":"2026-05-05T16:58:55.921249Z","steps":["trace[1145287248] 'agreement among raft nodes before linearized reading' (duration: 172.116037ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:59:02.081667Z","caller":"traceutil/trace.go:171","msg":"trace[888427726] linearizableReadLoop","detail":"{readStateIndex:1622; appliedIndex:1620; }","duration":"133.137364ms","start":"2026-05-05T16:59:01.948506Z","end":"2026-05-05T16:59:02.081643Z","steps":["trace[888427726] 'read index received' (duration: 26.910164ms)","trace[888427726] 'applied index is now lower than readState.Index' (duration: 106.22604ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:59:02.081688Z","caller":"traceutil/trace.go:171","msg":"trace[56337230] transaction","detail":"{read_only:false; response_revision:1556; number_of_response:1; }","duration":"134.451117ms","start":"2026-05-05T16:59:01.947213Z","end":"2026-05-05T16:59:02.081664Z","steps":["trace[56337230] 'process raft request' (duration: 66.401192ms)","trace[56337230] 'compare' (duration: 67.755975ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:59:02.081825Z","caller":"traceutil/trace.go:171","msg":"trace[143683499] transaction","detail":"{read_only:false; response_revision:1557; number_of_response:1; }","duration":"133.726643ms","start":"2026-05-05T16:59:01.948081Z","end":"2026-05-05T16:59:02.081807Z","steps":["trace[143683499] 'process raft request' (duration: 133.510447ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:59:02.081985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.393221ms","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-05T16:59:02.082022Z","caller":"traceutil/trace.go:171","msg":"trace[1812963283] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1557; }","duration":"132.442672ms","start":"2026-05-05T16:59:01.949565Z","end":"2026-05-05T16:59:02.082008Z","steps":["trace[1812963283] 'agreement among raft nodes before linearized reading' (duration: 132.353639ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:59:02.082237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.437984ms","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-05T16:59:02.082335Z","caller":"traceutil/trace.go:171","msg":"trace[60602979] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1557; }","duration":"133.823077ms","start":"2026-05-05T16:59:01.948492Z","end":"2026-05-05T16:59:02.082315Z","steps":["trace[60602979] 'agreement among raft nodes before linearized reading' (duration: 133.356852ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:59:02.265342Z","caller":"traceutil/trace.go:171","msg":"trace[629847812] transaction","detail":"{read_only:false; response_revision:1559; number_of_response:1; }","duration":"178.945596ms","start":"2026-05-05T16:59:02.086369Z","end":"2026-05-05T16:59:02.265315Z","steps":["trace[629847812] 'process raft request' (duration: 110.619452ms)","trace[629847812] 'compare' (duration: 68.231351ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:59:26.390938Z","caller":"traceutil/trace.go:171","msg":"trace[1221478991] linearizableReadLoop","detail":"{readStateIndex:1737; appliedIndex:1735; }","duration":"113.088319ms","start":"2026-05-05T16:59:26.277832Z","end":"2026-05-05T16:59:26.39092Z","steps":["trace[1221478991] 'read index received' (duration: 24.735704ms)","trace[1221478991] 'applied index is now lower than readState.Index' (duration: 88.352015ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:59:26.391082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.247514ms","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:530"} {"level":"info","ts":"2026-05-05T16:59:26.3911Z","caller":"traceutil/trace.go:171","msg":"trace[83046479] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1667; }","duration":"113.310286ms","start":"2026-05-05T16:59:26.277784Z","end":"2026-05-05T16:59:26.391094Z","steps":["trace[83046479] 'agreement among raft nodes before linearized reading' (duration: 113.203903ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:59:26.391288Z","caller":"traceutil/trace.go:171","msg":"trace[177852906] transaction","detail":"{read_only:false; response_revision:1667; number_of_response:1; }","duration":"142.317547ms","start":"2026-05-05T16:59:26.248925Z","end":"2026-05-05T16:59:26.391242Z","steps":["trace[177852906] 'process raft request' (duration: 96.729834ms)","trace[177852906] 'compare' (duration: 45.19498ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:59:27.692247Z","caller":"traceutil/trace.go:171","msg":"trace[304813355] transaction","detail":"{read_only:false; response_revision:1672; number_of_response:1; }","duration":"110.683182ms","start":"2026-05-05T16:59:27.581541Z","end":"2026-05-05T16:59:27.692224Z","steps":["trace[304813355] 'process raft request' (duration: 110.560898ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:59:34.439002Z","caller":"traceutil/trace.go:171","msg":"trace[416926022] transaction","detail":"{read_only:false; response_revision:1701; number_of_response:1; }","duration":"102.087246ms","start":"2026-05-05T16:59:34.336877Z","end":"2026-05-05T16:59:34.438964Z","steps":["trace[416926022] 'process raft request' (duration: 54.903432ms)","trace[416926022] 'compare' (duration: 46.751751ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:00:17.248775Z","caller":"traceutil/trace.go:171","msg":"trace[720585013] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"100.882498ms","start":"2026-05-05T17:00:17.147863Z","end":"2026-05-05T17:00:17.248746Z","steps":["trace[720585013] 'process raft request' (duration: 100.372872ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:01:01.162782Z","caller":"traceutil/trace.go:171","msg":"trace[310515421] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"153.856765ms","start":"2026-05-05T17:01:01.008899Z","end":"2026-05-05T17:01:01.162756Z","steps":["trace[310515421] 'process raft request' (duration: 130.540179ms)","trace[310515421] 'compare' (duration: 22.868482ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:01:33.65949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.638948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-05-05T17:01:33.659584Z","caller":"traceutil/trace.go:171","msg":"trace[1036052933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2271; }","duration":"265.790793ms","start":"2026-05-05T17:01:33.393775Z","end":"2026-05-05T17:01:33.659566Z","steps":["trace[1036052933] 'range keys from in-memory index tree' (duration: 265.463813ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:01:35.817441Z","caller":"traceutil/trace.go:171","msg":"trace[1742005558] transaction","detail":"{read_only:false; response_revision:2281; number_of_response:1; }","duration":"140.404351ms","start":"2026-05-05T17:01:35.677014Z","end":"2026-05-05T17:01:35.817419Z","steps":["trace[1742005558] 'process raft request' (duration: 140.356509ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:01:35.817529Z","caller":"traceutil/trace.go:171","msg":"trace[1793904753] transaction","detail":"{read_only:false; response_revision:2280; number_of_response:1; }","duration":"141.230307ms","start":"2026-05-05T17:01:35.676202Z","end":"2026-05-05T17:01:35.817432Z","steps":["trace[1793904753] 'process raft request' (duration: 139.856993ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:01:36.598874Z","caller":"traceutil/trace.go:171","msg":"trace[472425933] linearizableReadLoop","detail":"{readStateIndex:2381; appliedIndex:2380; }","duration":"111.48458ms","start":"2026-05-05T17:01:36.487366Z","end":"2026-05-05T17:01:36.598851Z","steps":["trace[472425933] 'read index received' (duration: 111.423558ms)","trace[472425933] 'applied index is now lower than readState.Index' (duration: 59.892µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:01:36.598991Z","caller":"traceutil/trace.go:171","msg":"trace[74343820] transaction","detail":"{read_only:false; response_revision:2284; number_of_response:1; }","duration":"136.571969ms","start":"2026-05-05T17:01:36.462371Z","end":"2026-05-05T17:01:36.598943Z","steps":["trace[74343820] 'process raft request' (duration: 136.329971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:01:36.59913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.750299ms","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-05T17:01:36.599183Z","caller":"traceutil/trace.go:171","msg":"trace[1455455501] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2284; }","duration":"111.869392ms","start":"2026-05-05T17:01:36.487296Z","end":"2026-05-05T17:01:36.599165Z","steps":["trace[1455455501] 'agreement among raft nodes before linearized reading' (duration: 111.686446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:01:36.599638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.649272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-05T17:01:36.599706Z","caller":"traceutil/trace.go:171","msg":"trace[873450931] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2284; }","duration":"105.154029ms","start":"2026-05-05T17:01:36.494535Z","end":"2026-05-05T17:01:36.599689Z","steps":["trace[873450931] 'agreement among raft nodes before linearized reading' (duration: 104.509168ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:01:38.817394Z","caller":"traceutil/trace.go:171","msg":"trace[372425803] transaction","detail":"{read_only:false; response_revision:2298; number_of_response:1; }","duration":"175.804428ms","start":"2026-05-05T17:01:38.641559Z","end":"2026-05-05T17:01:38.817363Z","steps":["trace[372425803] 'process raft request' (duration: 175.627873ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:01:42.911416Z","caller":"traceutil/trace.go:171","msg":"trace[2111659207] transaction","detail":"{read_only:false; response_revision:2318; number_of_response:1; }","duration":"193.584614ms","start":"2026-05-05T17:01:42.717797Z","end":"2026-05-05T17:01:42.911381Z","steps":["trace[2111659207] 'process raft request' (duration: 193.45388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:01:43.45786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.08308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T17:01:43.458005Z","caller":"traceutil/trace.go:171","msg":"trace[665531684] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:2321; }","duration":"259.288905ms","start":"2026-05-05T17:01:43.198663Z","end":"2026-05-05T17:01:43.457952Z","steps":["trace[665531684] 'count revisions from in-memory index tree' (duration: 258.953125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:01:43.458017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.705733ms","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-05T17:01:43.458092Z","caller":"traceutil/trace.go:171","msg":"trace[1985070761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2321; }","duration":"364.838966ms","start":"2026-05-05T17:01:43.093235Z","end":"2026-05-05T17:01:43.458074Z","steps":["trace[1985070761] 'range keys from in-memory index tree' (duration: 364.500856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:01:43.458125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T17:01:43.093211Z","time spent":"364.905999ms","remote":"127.0.0.1:33360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-05T17:01:43.675894Z","caller":"traceutil/trace.go:171","msg":"trace[551381946] transaction","detail":"{read_only:false; response_revision:2323; number_of_response:1; }","duration":"214.787719ms","start":"2026-05-05T17:01:43.461045Z","end":"2026-05-05T17:01:43.675832Z","steps":["trace[551381946] 'process raft request' (duration: 124.150323ms)","trace[551381946] 'compare' (duration: 90.253994ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:01:44.434345Z","caller":"traceutil/trace.go:171","msg":"trace[374157706] transaction","detail":"{read_only:false; response_revision:2327; number_of_response:1; }","duration":"296.165031ms","start":"2026-05-05T17:01:44.138154Z","end":"2026-05-05T17:01:44.434319Z","steps":["trace[374157706] 'process raft request' (duration: 295.976925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:01:50.272107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.864733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:500 ","response":"range_response_count:1 size:7182"} {"level":"info","ts":"2026-05-05T17:01:50.272404Z","caller":"traceutil/trace.go:171","msg":"trace[2133909545] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:2356; }","duration":"102.234935ms","start":"2026-05-05T17:01:50.170141Z","end":"2026-05-05T17:01:50.272376Z","steps":["trace[2133909545] 'range keys from in-memory index tree' (duration: 101.622436ms)"],"step_count":1}