{"level":"warn","ts":"2026-05-07T02:02:58.322802Z","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-07T02:02:58.322894Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.89: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.89:2380","--initial-cluster=instance=https://199.204.45.89:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.89:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.89: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-07T02:02:58.322963Z","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-07T02:02:58.322973Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-05-07T02:02:58.322992Z","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-07T02:02:58.32364Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"]} {"level":"info","ts":"2026-05-07T02:02:58.323712Z","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.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.89: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-07T02:02:58.335575Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.660508ms"} {"level":"info","ts":"2026-05-07T02:02:58.348885Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c3b8446219deb8c1","cluster-id":"fe02a46d8aa9751f"} {"level":"info","ts":"2026-05-07T02:02:58.348988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=()"} {"level":"info","ts":"2026-05-07T02:02:58.349008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 0"} {"level":"info","ts":"2026-05-07T02:02:58.349016Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c3b8446219deb8c1 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-07T02:02:58.349022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became follower at term 1"} {"level":"info","ts":"2026-05-07T02:02:58.349055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"warn","ts":"2026-05-07T02:02:58.357729Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-07T02:02:58.35879Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-07T02:02:58.359512Z","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-07T02:02:58.361025Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c3b8446219deb8c1","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-07T02:02:58.367736Z","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-07T02:02:58.367865Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-05-07T02:02:58.367881Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.89:2380"} {"level":"info","ts":"2026-05-07T02:02:58.368326Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c3b8446219deb8c1","initial-advertise-peer-urls":["https://199.204.45.89:2380"],"listen-peer-urls":["https://199.204.45.89:2380"],"advertise-client-urls":["https://199.204.45.89:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.89:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-07T02:02:58.368416Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-07T02:02:58.368817Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c3b8446219deb8c1","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-07T02:02:58.369058Z","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-07T02:02:58.36916Z","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-07T02:02:58.369173Z","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-07T02:02:58.369312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 switched to configuration voters=(14103097421242218689)"} {"level":"info","ts":"2026-05-07T02:02:58.369433Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","added-peer-id":"c3b8446219deb8c1","added-peer-peer-urls":["https://199.204.45.89:2380"]} {"level":"info","ts":"2026-05-07T02:02:58.549996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 is starting a new election at term 1"} {"level":"info","ts":"2026-05-07T02:02:58.550055Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-07T02:02:58.550079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgPreVoteResp from c3b8446219deb8c1 at term 1"} {"level":"info","ts":"2026-05-07T02:02:58.5501Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became candidate at term 2"} {"level":"info","ts":"2026-05-07T02:02:58.550141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 received MsgVoteResp from c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-05-07T02:02:58.550163Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c3b8446219deb8c1 became leader at term 2"} {"level":"info","ts":"2026-05-07T02:02:58.550192Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c3b8446219deb8c1 elected leader c3b8446219deb8c1 at term 2"} {"level":"info","ts":"2026-05-07T02:02:58.55564Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T02:02:58.565198Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c3b8446219deb8c1","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.89:2379]}","request-path":"/0/members/c3b8446219deb8c1/attributes","cluster-id":"fe02a46d8aa9751f","publish-timeout":"7s"} {"level":"info","ts":"2026-05-07T02:02:58.565209Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T02:02:58.565494Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-07T02:02:58.566516Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-07T02:02:58.566539Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-07T02:02:58.568576Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.89:2379"} {"level":"info","ts":"2026-05-07T02:02:58.569361Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-07T02:02:58.574647Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"fe02a46d8aa9751f","local-member-id":"c3b8446219deb8c1","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T02:02:58.574735Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T02:02:58.574765Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-07T02:03:25.615247Z","caller":"traceutil/trace.go:171","msg":"trace[1693736080] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:486; }","duration":"255.079781ms","start":"2026-05-07T02:03:25.360144Z","end":"2026-05-07T02:03:25.615223Z","steps":["trace[1693736080] 'read index received' (duration: 254.17185ms)","trace[1693736080] 'applied index is now lower than readState.Index' (duration: 907.201µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:03:25.61535Z","caller":"traceutil/trace.go:171","msg":"trace[845925718] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"666.875569ms","start":"2026-05-07T02:03:24.948423Z","end":"2026-05-07T02:03:25.615299Z","steps":["trace[845925718] 'process raft request' (duration: 666.459629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:25.615433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.288206ms","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-07T02:03:25.615509Z","caller":"traceutil/trace.go:171","msg":"trace[2088794603] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:469; }","duration":"255.411999ms","start":"2026-05-07T02:03:25.360084Z","end":"2026-05-07T02:03:25.615496Z","steps":["trace[2088794603] 'agreement among raft nodes before linearized reading' (duration: 255.267216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:25.615922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:24.948399Z","time spent":"667.065193ms","remote":"127.0.0.1:42560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317292,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T02:03:26.016173Z","caller":"traceutil/trace.go:171","msg":"trace[1998537691] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"373.843381ms","start":"2026-05-07T02:03:25.64229Z","end":"2026-05-07T02:03:26.016133Z","steps":["trace[1998537691] 'process raft request' (duration: 373.808651ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:03:26.016262Z","caller":"traceutil/trace.go:171","msg":"trace[464744445] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"397.052185ms","start":"2026-05-07T02:03:25.619184Z","end":"2026-05-07T02:03:26.016236Z","steps":["trace[464744445] 'process raft request' (duration: 391.290208ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:26.016302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:25.642283Z","time spent":"373.963334ms","remote":"127.0.0.1:42686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T02:03:26.016562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:25.619171Z","time spent":"397.154037ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:03:26.016614Z","caller":"traceutil/trace.go:171","msg":"trace[209276329] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"374.429827ms","start":"2026-05-07T02:03:25.64214Z","end":"2026-05-07T02:03:26.01657Z","steps":["trace[209276329] 'process raft request' (duration: 373.886823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:26.016704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:25.642121Z","time spent":"374.542909ms","remote":"127.0.0.1:42686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":505,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-07T02:03:26.919582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.639853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-05-07T02:03:26.919661Z","caller":"traceutil/trace.go:171","msg":"trace[2117275835] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:502; }","duration":"666.775996ms","start":"2026-05-07T02:03:26.252866Z","end":"2026-05-07T02:03:26.919642Z","steps":["trace[2117275835] 'range keys from in-memory index tree' (duration: 666.52226ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:26.919691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:26.252833Z","time spent":"666.852238ms","remote":"127.0.0.1:42686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":562,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" "} {"level":"warn","ts":"2026-05-07T02:03:26.919664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.330739ms","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-07T02:03:26.91974Z","caller":"traceutil/trace.go:171","msg":"trace[1748165145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:502; }","duration":"154.473043ms","start":"2026-05-07T02:03:26.765248Z","end":"2026-05-07T02:03:26.919721Z","steps":["trace[1748165145] 'range keys from in-memory index tree' (duration: 154.196316ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:26.919805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"666.9565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"info","ts":"2026-05-07T02:03:26.919857Z","caller":"traceutil/trace.go:171","msg":"trace[1296457630] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:502; }","duration":"667.051772ms","start":"2026-05-07T02:03:26.252791Z","end":"2026-05-07T02:03:26.919842Z","steps":["trace[1296457630] 'range keys from in-memory index tree' (duration: 666.867398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:26.919891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:26.252776Z","time spent":"667.106173ms","remote":"127.0.0.1:42686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":544,"request content":"key:\"/registry/serviceaccounts/kube-system/cilium\" "} {"level":"warn","ts":"2026-05-07T02:03:26.920001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.517917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329948"} {"level":"info","ts":"2026-05-07T02:03:26.920062Z","caller":"traceutil/trace.go:171","msg":"trace[1778809490] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:502; }","duration":"681.611099ms","start":"2026-05-07T02:03:26.238435Z","end":"2026-05-07T02:03:26.920046Z","steps":["trace[1778809490] 'range keys from in-memory index tree' (duration: 681.173939ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:26.920021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.372398ms","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-07T02:03:26.920173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:26.238417Z","time spent":"681.742803ms","remote":"127.0.0.1:42560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":329972,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-05-07T02:03:26.920175Z","caller":"traceutil/trace.go:171","msg":"trace[633445061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:502; }","duration":"427.562843ms","start":"2026-05-07T02:03:26.492598Z","end":"2026-05-07T02:03:26.920161Z","steps":["trace[633445061] 'range keys from in-memory index tree' (duration: 427.250225ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:26.920217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:26.492574Z","time spent":"427.632385ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T02:03:30.242019Z","caller":"traceutil/trace.go:171","msg":"trace[1107791358] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"440.349587ms","start":"2026-05-07T02:03:29.801619Z","end":"2026-05-07T02:03:30.241968Z","steps":["trace[1107791358] 'read index received' (duration: 440.098891ms)","trace[1107791358] 'applied index is now lower than readState.Index' (duration: 249.176µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:03:30.24225Z","caller":"traceutil/trace.go:171","msg":"trace[1170448744] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"474.203224ms","start":"2026-05-07T02:03:29.768022Z","end":"2026-05-07T02:03:30.242225Z","steps":["trace[1170448744] 'process raft request' (duration: 473.537079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:30.242494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.85661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:7708"} {"level":"info","ts":"2026-05-07T02:03:30.242645Z","caller":"traceutil/trace.go:171","msg":"trace[1044006122] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:605; }","duration":"441.016633ms","start":"2026-05-07T02:03:29.801558Z","end":"2026-05-07T02:03:30.242575Z","steps":["trace[1044006122] 'agreement among raft nodes before linearized reading' (duration: 440.663995ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:30.242712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:29.801528Z","time spent":"441.171936ms","remote":"127.0.0.1:42526","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":7732,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/referencegrants.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-05-07T02:03:30.242558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:29.768004Z","time spent":"474.42566ms","remote":"127.0.0.1:42526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:03:30.249629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.401975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-07T02:03:30.249723Z","caller":"traceutil/trace.go:171","msg":"trace[568601776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:607; }","duration":"223.538949ms","start":"2026-05-07T02:03:30.026162Z","end":"2026-05-07T02:03:30.249701Z","steps":["trace[568601776] 'agreement among raft nodes before linearized reading' (duration: 223.370815ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:03:30.250202Z","caller":"traceutil/trace.go:171","msg":"trace[281311739] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"398.661624ms","start":"2026-05-07T02:03:29.851526Z","end":"2026-05-07T02:03:30.250188Z","steps":["trace[281311739] 'process raft request' (duration: 397.853904ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:30.250292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:29.851489Z","time spent":"398.753847ms","remote":"127.0.0.1:42526","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-07T02:03:30.250445Z","caller":"traceutil/trace.go:171","msg":"trace[1615387451] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"199.116806ms","start":"2026-05-07T02:03:30.051319Z","end":"2026-05-07T02:03:30.250436Z","steps":["trace[1615387451] 'process raft request' (duration: 198.178184ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:30.250612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.170633ms","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-07T02:03:30.25065Z","caller":"traceutil/trace.go:171","msg":"trace[623583540] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:607; }","duration":"138.272736ms","start":"2026-05-07T02:03:30.112368Z","end":"2026-05-07T02:03:30.250641Z","steps":["trace[623583540] 'agreement among raft nodes before linearized reading' (duration: 138.176814ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:03:35.348153Z","caller":"traceutil/trace.go:171","msg":"trace[1620692076] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"109.387847ms","start":"2026-05-07T02:03:35.238744Z","end":"2026-05-07T02:03:35.348132Z","steps":["trace[1620692076] 'process raft request' (duration: 109.295425ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:03:35.85177Z","caller":"traceutil/trace.go:171","msg":"trace[386884147] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"115.851521ms","start":"2026-05-07T02:03:35.73588Z","end":"2026-05-07T02:03:35.851732Z","steps":["trace[386884147] 'process raft request' (duration: 113.504086ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:36.793905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095697068044925,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T02:03:37.294242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095697068044925,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T02:03:37.473223Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.240127905s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T02:03:37.47376Z","caller":"traceutil/trace.go:171","msg":"trace[2076070504] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"1.24078988s","start":"2026-05-07T02:03:36.232944Z","end":"2026-05-07T02:03:37.473734Z","steps":["trace[2076070504] 'process raft request' (duration: 1.240641636s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:37.474006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:36.23292Z","time spent":"1.240912183s","remote":"127.0.0.1:42670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:03:37.633395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.056233ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-07T02:03:37.633504Z","caller":"traceutil/trace.go:171","msg":"trace[1267412334] linearizableReadLoop","detail":"{readStateIndex:701; appliedIndex:699; }","duration":"1.340082067s","start":"2026-05-07T02:03:36.293403Z","end":"2026-05-07T02:03:37.633485Z","steps":["trace[1267412334] 'read index received' (duration: 1.180199356s)","trace[1267412334] 'applied index is now lower than readState.Index' (duration: 159.881391ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:03:37.633808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340380444s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-07T02:03:37.633847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340285353s","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-07T02:03:37.633892Z","caller":"traceutil/trace.go:171","msg":"trace[1272206187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:676; }","duration":"1.340538698s","start":"2026-05-07T02:03:36.293333Z","end":"2026-05-07T02:03:37.633872Z","steps":["trace[1272206187] 'agreement among raft nodes before linearized reading' (duration: 1.340276841s)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:03:37.633898Z","caller":"traceutil/trace.go:171","msg":"trace[883146795] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:676; }","duration":"1.340372975s","start":"2026-05-07T02:03:36.293511Z","end":"2026-05-07T02:03:37.633884Z","steps":["trace[883146795] 'agreement among raft nodes before linearized reading' (duration: 1.340239111s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:37.633941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:36.293494Z","time spent":"1.340438677s","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T02:03:37.633942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:36.293309Z","time spent":"1.340623971s","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T02:03:37.634009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055118525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T02:03:37.634057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.459289ms","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-07T02:03:37.634108Z","caller":"traceutil/trace.go:171","msg":"trace[1570398693] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:676; }","duration":"653.544301ms","start":"2026-05-07T02:03:36.980551Z","end":"2026-05-07T02:03:37.634096Z","steps":["trace[1570398693] 'agreement among raft nodes before linearized reading' (duration: 653.211652ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:03:37.634106Z","caller":"traceutil/trace.go:171","msg":"trace[676165374] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"1.055256037s","start":"2026-05-07T02:03:36.578823Z","end":"2026-05-07T02:03:37.634079Z","steps":["trace[676165374] 'agreement among raft nodes before linearized reading' (duration: 1.055075443s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:37.634152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:36.980517Z","time spent":"653.626673ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T02:03:37.634159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:36.578805Z","time spent":"1.05534669s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T02:03:37.634247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.68408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-07T02:03:37.63428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.901741ms","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-07T02:03:37.634395Z","caller":"traceutil/trace.go:171","msg":"trace[1168403215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:676; }","duration":"662.053624ms","start":"2026-05-07T02:03:36.972322Z","end":"2026-05-07T02:03:37.634376Z","steps":["trace[1168403215] 'agreement among raft nodes before linearized reading' (duration: 661.585283ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:37.63454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:36.972304Z","time spent":"662.223218ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T02:03:37.634328Z","caller":"traceutil/trace.go:171","msg":"trace[1178635447] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:676; }","duration":"436.819454ms","start":"2026-05-07T02:03:37.197489Z","end":"2026-05-07T02:03:37.634309Z","steps":["trace[1178635447] 'agreement among raft nodes before linearized reading' (duration: 436.560738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:37.634839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:37.197468Z","time spent":"437.337525ms","remote":"127.0.0.1:42764","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-07T02:03:37.770241Z","caller":"traceutil/trace.go:171","msg":"trace[1487368128] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"130.984993ms","start":"2026-05-07T02:03:37.639221Z","end":"2026-05-07T02:03:37.770206Z","steps":["trace[1487368128] 'process raft request' (duration: 95.491768ms)","trace[1487368128] 'compare' (duration: 35.227869ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:03:37.77027Z","caller":"traceutil/trace.go:171","msg":"trace[1101175580] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:701; }","duration":"127.360336ms","start":"2026-05-07T02:03:37.642886Z","end":"2026-05-07T02:03:37.770246Z","steps":["trace[1101175580] 'read index received' (duration: 78.805769ms)","trace[1101175580] 'applied index is now lower than readState.Index' (duration: 48.553467ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:03:37.770389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.486369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:03:37.770431Z","caller":"traceutil/trace.go:171","msg":"trace[586381221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"127.568121ms","start":"2026-05-07T02:03:37.642849Z","end":"2026-05-07T02:03:37.770417Z","steps":["trace[586381221] 'agreement among raft nodes before linearized reading' (duration: 127.486319ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:03:37.770498Z","caller":"traceutil/trace.go:171","msg":"trace[755441957] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"131.584657ms","start":"2026-05-07T02:03:37.638903Z","end":"2026-05-07T02:03:37.770488Z","steps":["trace[755441957] 'process raft request' (duration: 131.225558ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:03:37.770608Z","caller":"traceutil/trace.go:171","msg":"trace[347102016] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"131.009553ms","start":"2026-05-07T02:03:37.639558Z","end":"2026-05-07T02:03:37.770567Z","steps":["trace[347102016] 'process raft request' (duration: 130.514421ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:03:37.770686Z","caller":"traceutil/trace.go:171","msg":"trace[965694049] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"130.172073ms","start":"2026-05-07T02:03:37.640494Z","end":"2026-05-07T02:03:37.770666Z","steps":["trace[965694049] 'process raft request' (duration: 129.688052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:38.389501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.223023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T02:03:38.389563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.745095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:03:38.389601Z","caller":"traceutil/trace.go:171","msg":"trace[847599455] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:681; }","duration":"307.369987ms","start":"2026-05-07T02:03:38.082208Z","end":"2026-05-07T02:03:38.389577Z","steps":["trace[847599455] 'count revisions from in-memory index tree' (duration: 307.144072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:38.389592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.561675ms","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-07T02:03:38.389653Z","caller":"traceutil/trace.go:171","msg":"trace[322310157] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:681; }","duration":"192.836478ms","start":"2026-05-07T02:03:38.196793Z","end":"2026-05-07T02:03:38.38963Z","steps":["trace[322310157] 'count revisions from in-memory index tree' (duration: 192.636953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:38.389647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.757937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:03:38.389727Z","caller":"traceutil/trace.go:171","msg":"trace[785251213] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:681; }","duration":"276.872581ms","start":"2026-05-07T02:03:38.112837Z","end":"2026-05-07T02:03:38.38971Z","steps":["trace[785251213] 'range keys from in-memory index tree' (duration: 276.701635ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:03:38.389732Z","caller":"traceutil/trace.go:171","msg":"trace[277330606] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:681; }","duration":"334.668038ms","start":"2026-05-07T02:03:38.055012Z","end":"2026-05-07T02:03:38.38968Z","steps":["trace[277330606] 'count revisions from in-memory index tree' (duration: 334.530755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:38.389668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:38.082193Z","time spent":"307.457468ms","remote":"127.0.0.1:56978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-07T02:03:38.389741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.536996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T02:03:38.389785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:38.055008Z","time spent":"334.76309ms","remote":"127.0.0.1:56970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-05-07T02:03:38.38985Z","caller":"traceutil/trace.go:171","msg":"trace[1429312256] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:681; }","duration":"355.740201ms","start":"2026-05-07T02:03:38.034095Z","end":"2026-05-07T02:03:38.389835Z","steps":["trace[1429312256] 'count revisions from in-memory index tree' (duration: 355.403312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:38.389939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.07221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:03:38.389982Z","caller":"traceutil/trace.go:171","msg":"trace[733738079] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:681; }","duration":"234.144032ms","start":"2026-05-07T02:03:38.155828Z","end":"2026-05-07T02:03:38.389972Z","steps":["trace[733738079] 'range keys from in-memory index tree' (duration: 233.957207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:38.389955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:38.034086Z","time spent":"355.854403ms","remote":"127.0.0.1:56962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-07T02:03:38.390029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.001468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T02:03:38.390131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.567325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T02:03:38.390194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.062399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:03:38.390137Z","caller":"traceutil/trace.go:171","msg":"trace[767556018] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:681; }","duration":"307.127291ms","start":"2026-05-07T02:03:38.082998Z","end":"2026-05-07T02:03:38.390125Z","steps":["trace[767556018] 'range keys from in-memory index tree' (duration: 306.907936ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:03:38.390269Z","caller":"traceutil/trace.go:171","msg":"trace[1779587434] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:681; }","duration":"356.165132ms","start":"2026-05-07T02:03:38.034088Z","end":"2026-05-07T02:03:38.390253Z","steps":["trace[1779587434] 'range keys from in-memory index tree' (duration: 355.974706ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:38.390502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:38.034079Z","time spent":"356.410977ms","remote":"127.0.0.1:56962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 "} {"level":"warn","ts":"2026-05-07T02:03:38.390557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:38.082988Z","time spent":"307.293346ms","remote":"127.0.0.1:56978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:500 "} {"level":"warn","ts":"2026-05-07T02:03:38.390634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.357075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:03:38.390674Z","caller":"traceutil/trace.go:171","msg":"trace[515418953] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:681; }","duration":"335.409436ms","start":"2026-05-07T02:03:38.055255Z","end":"2026-05-07T02:03:38.390664Z","steps":["trace[515418953] 'range keys from in-memory index tree' (duration: 335.332194ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:38.390746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:38.05525Z","time spent":"335.447667ms","remote":"127.0.0.1:56970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-05-07T02:03:38.390745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.964559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:03:38.390837Z","caller":"traceutil/trace.go:171","msg":"trace[887490014] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:681; }","duration":"336.069802ms","start":"2026-05-07T02:03:38.054756Z","end":"2026-05-07T02:03:38.390826Z","steps":["trace[887490014] 'range keys from in-memory index tree' (duration: 335.926479ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:38.390885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.054817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:03:38.39021Z","caller":"traceutil/trace.go:171","msg":"trace[1420303365] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:681; }","duration":"193.612445ms","start":"2026-05-07T02:03:38.19654Z","end":"2026-05-07T02:03:38.390152Z","steps":["trace[1420303365] 'range keys from in-memory index tree' (duration: 193.532503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:38.390901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.389142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:03:38.390999Z","caller":"traceutil/trace.go:171","msg":"trace[1486042066] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:681; }","duration":"399.542145ms","start":"2026-05-07T02:03:37.991445Z","end":"2026-05-07T02:03:38.390987Z","steps":["trace[1486042066] 'range keys from in-memory index tree' (duration: 399.335071ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:38.391686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:37.99143Z","time spent":"400.237612ms","remote":"127.0.0.1:56958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 "} {"level":"warn","ts":"2026-05-07T02:03:38.390903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:38.054748Z","time spent":"336.120233ms","remote":"127.0.0.1:56970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-05-07T02:03:38.3911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.244197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:03:38.392087Z","caller":"traceutil/trace.go:171","msg":"trace[176766781] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:681; }","duration":"358.295691ms","start":"2026-05-07T02:03:38.033776Z","end":"2026-05-07T02:03:38.392072Z","steps":["trace[176766781] 'range keys from in-memory index tree' (duration: 357.169625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:38.392167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:38.033758Z","time spent":"358.393014ms","remote":"127.0.0.1:56962","response type":"/etcdserverpb.KV/Range","request count":0,"request size":117,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 "} {"level":"info","ts":"2026-05-07T02:03:38.390942Z","caller":"traceutil/trace.go:171","msg":"trace[1130260536] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:681; }","duration":"194.157539ms","start":"2026-05-07T02:03:38.196771Z","end":"2026-05-07T02:03:38.390929Z","steps":["trace[1130260536] 'range keys from in-memory index tree' (duration: 193.923143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:38.391134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.568746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T02:03:38.391168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.977482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T02:03:38.391305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.26663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T02:03:38.391389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.427624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T02:03:38.391389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.229951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T02:03:38.389541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.607199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:03:38.392865Z","caller":"traceutil/trace.go:171","msg":"trace[698697723] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:681; }","duration":"236.972769ms","start":"2026-05-07T02:03:38.155853Z","end":"2026-05-07T02:03:38.392825Z","steps":["trace[698697723] 'count revisions from in-memory index tree' (duration: 233.516837ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:03:38.393111Z","caller":"traceutil/trace.go:171","msg":"trace[1737597285] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:681; }","duration":"279.080413ms","start":"2026-05-07T02:03:38.114014Z","end":"2026-05-07T02:03:38.393094Z","steps":["trace[1737597285] 'count revisions from in-memory index tree' (duration: 277.218899ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:03:38.393159Z","caller":"traceutil/trace.go:171","msg":"trace[1842858695] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:681; }","duration":"401.606864ms","start":"2026-05-07T02:03:37.991528Z","end":"2026-05-07T02:03:38.393135Z","steps":["trace[1842858695] 'count revisions from in-memory index tree' (duration: 399.503294ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:03:38.393147Z","caller":"traceutil/trace.go:171","msg":"trace[822711156] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:681; }","duration":"279.224967ms","start":"2026-05-07T02:03:38.113905Z","end":"2026-05-07T02:03:38.393129Z","steps":["trace[822711156] 'range keys from in-memory index tree' (duration: 277.273321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:38.393227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:37.99151Z","time spent":"401.696657ms","remote":"127.0.0.1:56958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-05-07T02:03:38.393331Z","caller":"traceutil/trace.go:171","msg":"trace[1923175290] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:681; }","duration":"311.196749ms","start":"2026-05-07T02:03:38.082122Z","end":"2026-05-07T02:03:38.393319Z","steps":["trace[1923175290] 'range keys from in-memory index tree' (duration: 309.1811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:38.393406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:38.08211Z","time spent":"311.27987ms","remote":"127.0.0.1:56978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" limit:10000 "} {"level":"info","ts":"2026-05-07T02:03:38.393388Z","caller":"traceutil/trace.go:171","msg":"trace[408298073] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:681; }","duration":"401.006221ms","start":"2026-05-07T02:03:37.992144Z","end":"2026-05-07T02:03:38.39315Z","steps":["trace[408298073] 'range keys from in-memory index tree' (duration: 398.923321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:38.39364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:37.992127Z","time spent":"401.468051ms","remote":"127.0.0.1:56958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 "} {"level":"warn","ts":"2026-05-07T02:03:40.274402Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095697068045017,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T02:03:40.488824Z","caller":"traceutil/trace.go:171","msg":"trace[732864246] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:706; }","duration":"714.494053ms","start":"2026-05-07T02:03:39.774307Z","end":"2026-05-07T02:03:40.488802Z","steps":["trace[732864246] 'read index received' (duration: 714.244727ms)","trace[732864246] 'applied index is now lower than readState.Index' (duration: 248.376µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:03:40.488917Z","caller":"traceutil/trace.go:171","msg":"trace[491193863] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"760.019829ms","start":"2026-05-07T02:03:39.728859Z","end":"2026-05-07T02:03:40.488879Z","steps":["trace[491193863] 'process raft request' (duration: 759.781894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:40.489093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.77573ms","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-07T02:03:40.489134Z","caller":"traceutil/trace.go:171","msg":"trace[1451320659] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:682; }","duration":"714.872342ms","start":"2026-05-07T02:03:39.774252Z","end":"2026-05-07T02:03:40.489124Z","steps":["trace[1451320659] 'agreement among raft nodes before linearized reading' (duration: 714.665097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:40.48917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:39.774239Z","time spent":"714.922884ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T02:03:40.489183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:39.728836Z","time spent":"760.227714ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:03:40.489388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.051306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-07T02:03:40.48943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.36708ms","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-07T02:03:40.489483Z","caller":"traceutil/trace.go:171","msg":"trace[1615936804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"715.2124ms","start":"2026-05-07T02:03:39.774251Z","end":"2026-05-07T02:03:40.489464Z","steps":["trace[1615936804] 'agreement among raft nodes before linearized reading' (duration: 714.927423ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:03:40.489502Z","caller":"traceutil/trace.go:171","msg":"trace[783454730] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:682; }","duration":"714.480303ms","start":"2026-05-07T02:03:39.775Z","end":"2026-05-07T02:03:40.48948Z","steps":["trace[783454730] 'agreement among raft nodes before linearized reading' (duration: 714.290509ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:40.489531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:39.774233Z","time spent":"715.288832ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T02:03:40.489549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:39.774973Z","time spent":"714.567076ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-07T02:03:40.489712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.972994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-07T02:03:40.489862Z","caller":"traceutil/trace.go:171","msg":"trace[1941131265] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:682; }","duration":"715.164279ms","start":"2026-05-07T02:03:39.774663Z","end":"2026-05-07T02:03:40.489828Z","steps":["trace[1941131265] 'agreement among raft nodes before linearized reading' (duration: 714.608996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:40.489971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:39.774644Z","time spent":"715.311192ms","remote":"127.0.0.1:42764","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-07T02:03:41.459068Z","caller":"traceutil/trace.go:171","msg":"trace[1500670462] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"208.03055ms","start":"2026-05-07T02:03:41.25101Z","end":"2026-05-07T02:03:41.45904Z","steps":["trace[1500670462] 'process raft request' (duration: 171.316154ms)","trace[1500670462] 'compare' (duration: 36.446959ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:03:44.534898Z","caller":"traceutil/trace.go:171","msg":"trace[1100324115] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"265.334505ms","start":"2026-05-07T02:03:44.269531Z","end":"2026-05-07T02:03:44.534866Z","steps":["trace[1100324115] 'process raft request' (duration: 256.27335ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:03:55.783022Z","caller":"traceutil/trace.go:171","msg":"trace[148008658] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"403.453838ms","start":"2026-05-07T02:03:55.379544Z","end":"2026-05-07T02:03:55.782998Z","steps":["trace[148008658] 'process raft request' (duration: 401.598674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:03:55.783244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:03:55.379528Z","time spent":"403.599901ms","remote":"127.0.0.1:42642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":781,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:04:01.082169Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095697068045260,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T02:04:01.508364Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.058895954s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T02:04:01.508909Z","caller":"traceutil/trace.go:171","msg":"trace[1121783682] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:837; }","duration":"927.628436ms","start":"2026-05-07T02:04:00.581177Z","end":"2026-05-07T02:04:01.508805Z","steps":["trace[1121783682] 'read index received' (duration: 927.427191ms)","trace[1121783682] 'applied index is now lower than readState.Index' (duration: 200.315µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:04:01.508921Z","caller":"traceutil/trace.go:171","msg":"trace[1486582376] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"1.059541909s","start":"2026-05-07T02:04:00.449346Z","end":"2026-05-07T02:04:01.508888Z","steps":["trace[1486582376] 'process raft request' (duration: 1.059326524s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:01.50904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"927.847251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:04:01.509087Z","caller":"traceutil/trace.go:171","msg":"trace[1724747371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"928.141008ms","start":"2026-05-07T02:04:00.580929Z","end":"2026-05-07T02:04:01.50907Z","steps":["trace[1724747371] 'agreement among raft nodes before linearized reading' (duration: 928.060396ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:01.509149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:00.580913Z","time spent":"928.22426ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T02:04:01.509164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:00.449325Z","time spent":"1.059715483s","remote":"127.0.0.1:42734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:04:01.50923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.303022ms","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-07T02:04:01.509309Z","caller":"traceutil/trace.go:171","msg":"trace[1483684979] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:808; }","duration":"662.445556ms","start":"2026-05-07T02:04:00.846843Z","end":"2026-05-07T02:04:01.509289Z","steps":["trace[1483684979] 'agreement among raft nodes before linearized reading' (duration: 662.246191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:01.509327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.848177ms","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-07T02:04:01.509304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.270743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-07T02:04:01.509358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:00.846821Z","time spent":"662.529728ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T02:04:01.509369Z","caller":"traceutil/trace.go:171","msg":"trace[139255255] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:808; }","duration":"824.901319ms","start":"2026-05-07T02:04:00.684454Z","end":"2026-05-07T02:04:01.509355Z","steps":["trace[139255255] 'agreement among raft nodes before linearized reading' (duration: 824.815817ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:01.509401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:00.684444Z","time spent":"824.95053ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T02:04:01.509395Z","caller":"traceutil/trace.go:171","msg":"trace[2105431598] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:808; }","duration":"660.436397ms","start":"2026-05-07T02:04:00.848938Z","end":"2026-05-07T02:04:01.509374Z","steps":["trace[2105431598] 'agreement among raft nodes before linearized reading' (duration: 660.170901ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:01.509451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:00.848917Z","time spent":"660.524999ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T02:04:01.509227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.461362ms","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-07T02:04:01.509493Z","caller":"traceutil/trace.go:171","msg":"trace[2048294652] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:808; }","duration":"832.78428ms","start":"2026-05-07T02:04:00.6767Z","end":"2026-05-07T02:04:01.509484Z","steps":["trace[2048294652] 'agreement among raft nodes before linearized reading' (duration: 832.35957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:01.509526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:00.676678Z","time spent":"832.841601ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T02:04:01.50961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"829.119641ms","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-07T02:04:01.509681Z","caller":"traceutil/trace.go:171","msg":"trace[453224905] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:808; }","duration":"829.234734ms","start":"2026-05-07T02:04:00.680433Z","end":"2026-05-07T02:04:01.509668Z","steps":["trace[453224905] 'agreement among raft nodes before linearized reading' (duration: 828.973048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:01.509745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:00.680413Z","time spent":"829.321796ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T02:04:21.90115Z","caller":"traceutil/trace.go:171","msg":"trace[2109750458] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"171.370269ms","start":"2026-05-07T02:04:21.729762Z","end":"2026-05-07T02:04:21.901132Z","steps":["trace[2109750458] 'read index received' (duration: 171.263656ms)","trace[2109750458] 'applied index is now lower than readState.Index' (duration: 105.943µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:04:21.901216Z","caller":"traceutil/trace.go:171","msg":"trace[1251035724] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"176.659568ms","start":"2026-05-07T02:04:21.724524Z","end":"2026-05-07T02:04:21.901183Z","steps":["trace[1251035724] 'process raft request' (duration: 176.482313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:21.901377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.591645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-07T02:04:21.901404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.086681ms","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-07T02:04:21.901452Z","caller":"traceutil/trace.go:171","msg":"trace[666056269] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:971; }","duration":"171.694357ms","start":"2026-05-07T02:04:21.729737Z","end":"2026-05-07T02:04:21.901432Z","steps":["trace[666056269] 'agreement among raft nodes before linearized reading' (duration: 171.478232ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:21.901483Z","caller":"traceutil/trace.go:171","msg":"trace[340756086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:971; }","duration":"147.209314ms","start":"2026-05-07T02:04:21.754264Z","end":"2026-05-07T02:04:21.901474Z","steps":["trace[340756086] 'agreement among raft nodes before linearized reading' (duration: 147.05595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:21.901517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.870798ms","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-07T02:04:21.901588Z","caller":"traceutil/trace.go:171","msg":"trace[2051695150] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:971; }","duration":"144.944899ms","start":"2026-05-07T02:04:21.756627Z","end":"2026-05-07T02:04:21.901572Z","steps":["trace[2051695150] 'agreement among raft nodes before linearized reading' (duration: 144.773735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:21.901529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.903088ms","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-07T02:04:21.901716Z","caller":"traceutil/trace.go:171","msg":"trace[1692839802] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:971; }","duration":"145.115993ms","start":"2026-05-07T02:04:21.756582Z","end":"2026-05-07T02:04:21.901698Z","steps":["trace[1692839802] 'agreement among raft nodes before linearized reading' (duration: 144.848187ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:22.039512Z","caller":"traceutil/trace.go:171","msg":"trace[1480758801] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"132.035036ms","start":"2026-05-07T02:04:21.907446Z","end":"2026-05-07T02:04:22.039481Z","steps":["trace[1480758801] 'process raft request' (duration: 94.716014ms)","trace[1480758801] 'compare' (duration: 36.941403ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:04:22.039947Z","caller":"traceutil/trace.go:171","msg":"trace[503503877] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"132.443316ms","start":"2026-05-07T02:04:21.90748Z","end":"2026-05-07T02:04:22.039923Z","steps":["trace[503503877] 'process raft request' (duration: 132.079017ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:22.04017Z","caller":"traceutil/trace.go:171","msg":"trace[1881801666] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"132.108127ms","start":"2026-05-07T02:04:21.908047Z","end":"2026-05-07T02:04:22.040155Z","steps":["trace[1881801666] 'process raft request' (duration: 131.687537ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:30.087495Z","caller":"traceutil/trace.go:171","msg":"trace[903845304] linearizableReadLoop","detail":"{readStateIndex:1034; appliedIndex:1033; }","duration":"134.182058ms","start":"2026-05-07T02:04:29.953289Z","end":"2026-05-07T02:04:30.087471Z","steps":["trace[903845304] 'read index received' (duration: 133.997904ms)","trace[903845304] 'applied index is now lower than readState.Index' (duration: 183.144µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:04:30.0876Z","caller":"traceutil/trace.go:171","msg":"trace[696665337] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"141.746612ms","start":"2026-05-07T02:04:29.945825Z","end":"2026-05-07T02:04:30.087571Z","steps":["trace[696665337] 'process raft request' (duration: 141.511257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:30.087699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.394323ms","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-07T02:04:30.087739Z","caller":"traceutil/trace.go:171","msg":"trace[951249903] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:997; }","duration":"134.488015ms","start":"2026-05-07T02:04:29.953241Z","end":"2026-05-07T02:04:30.087729Z","steps":["trace[951249903] 'agreement among raft nodes before linearized reading' (duration: 134.322301ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:32.362247Z","caller":"traceutil/trace.go:171","msg":"trace[1984454677] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"160.475166ms","start":"2026-05-07T02:04:32.201743Z","end":"2026-05-07T02:04:32.362218Z","steps":["trace[1984454677] 'process raft request' (duration: 160.086396ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:36.752281Z","caller":"traceutil/trace.go:171","msg":"trace[165963242] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"370.646702ms","start":"2026-05-07T02:04:36.381608Z","end":"2026-05-07T02:04:36.752254Z","steps":["trace[165963242] 'process raft request' (duration: 370.469778ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:36.75244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:36.381589Z","time spent":"370.769846ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:04:36.797146Z","caller":"traceutil/trace.go:171","msg":"trace[1333642543] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"199.860098ms","start":"2026-05-07T02:04:36.597262Z","end":"2026-05-07T02:04:36.797122Z","steps":["trace[1333642543] 'process raft request' (duration: 199.754585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:37.691423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095697068045716,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T02:04:38.012016Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.036766158s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-07T02:04:38.619549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"607.212148ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-07T02:04:38.619663Z","caller":"traceutil/trace.go:171","msg":"trace[375037902] linearizableReadLoop","detail":"{readStateIndex:1067; appliedIndex:1066; }","duration":"1.428907841s","start":"2026-05-07T02:04:37.190736Z","end":"2026-05-07T02:04:38.619643Z","steps":["trace[375037902] 'read index received' (duration: 821.431586ms)","trace[375037902] 'applied index is now lower than readState.Index' (duration: 607.474785ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:04:38.619817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.429076476s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T02:04:38.619845Z","caller":"traceutil/trace.go:171","msg":"trace[1790006157] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1027; }","duration":"1.429157508s","start":"2026-05-07T02:04:37.190677Z","end":"2026-05-07T02:04:38.619835Z","steps":["trace[1790006157] 'agreement among raft nodes before linearized reading' (duration: 1.429078235s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:38.619882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:37.190657Z","time spent":"1.42921707s","remote":"127.0.0.1:42670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":11,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-05-07T02:04:38.619903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038860358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:04:38.620009Z","caller":"traceutil/trace.go:171","msg":"trace[950893742] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1027; }","duration":"1.039047053s","start":"2026-05-07T02:04:37.580933Z","end":"2026-05-07T02:04:38.61998Z","steps":["trace[950893742] 'agreement among raft nodes before linearized reading' (duration: 1.038832637s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:38.62002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.107137ms","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-07T02:04:38.620136Z","caller":"traceutil/trace.go:171","msg":"trace[883920028] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1027; }","duration":"374.276311ms","start":"2026-05-07T02:04:38.245837Z","end":"2026-05-07T02:04:38.620113Z","steps":["trace[883920028] 'agreement among raft nodes before linearized reading' (duration: 373.985604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:38.620139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.361248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-07T02:04:38.620185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:38.245793Z","time spent":"374.379244ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-07T02:04:38.620217Z","caller":"traceutil/trace.go:171","msg":"trace[2054408801] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1027; }","duration":"468.471561ms","start":"2026-05-07T02:04:38.151725Z","end":"2026-05-07T02:04:38.620196Z","steps":["trace[2054408801] 'agreement among raft nodes before linearized reading' (duration: 468.274057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:38.620249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.350411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-05-07T02:04:38.620257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:38.151707Z","time spent":"468.541022ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T02:04:38.620483Z","caller":"traceutil/trace.go:171","msg":"trace[873046640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1027; }","duration":"376.605038ms","start":"2026-05-07T02:04:38.243858Z","end":"2026-05-07T02:04:38.620463Z","steps":["trace[873046640] 'agreement among raft nodes before linearized reading' (duration: 376.091375ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:38.620068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:37.580917Z","time spent":"1.039137676s","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T02:04:38.62052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:38.243838Z","time spent":"376.674829ms","remote":"127.0.0.1:42764","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-07T02:04:38.620139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.926661ms","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-07T02:04:38.620576Z","caller":"traceutil/trace.go:171","msg":"trace[2046866925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1027; }","duration":"357.395172ms","start":"2026-05-07T02:04:38.26316Z","end":"2026-05-07T02:04:38.620555Z","steps":["trace[2046866925] 'agreement among raft nodes before linearized reading' (duration: 356.795327ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:38.620631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:38.263145Z","time spent":"357.468404ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T02:04:38.620941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.222489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T02:04:38.620985Z","caller":"traceutil/trace.go:171","msg":"trace[412151648] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1027; }","duration":"343.304591ms","start":"2026-05-07T02:04:38.277668Z","end":"2026-05-07T02:04:38.620973Z","steps":["trace[412151648] 'agreement among raft nodes before linearized reading' (duration: 342.172944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:38.621024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:38.27766Z","time spent":"343.354963ms","remote":"127.0.0.1:42928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":31,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-05-07T02:04:39.129684Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095697068045720,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T02:04:39.497849Z","caller":"traceutil/trace.go:171","msg":"trace[670716258] transaction","detail":"{read_only:false; response_revision:1028; number_of_response:1; }","duration":"873.510125ms","start":"2026-05-07T02:04:38.624319Z","end":"2026-05-07T02:04:39.49783Z","steps":["trace[670716258] 'process raft request' (duration: 873.363072ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:39.497965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:38.624304Z","time spent":"873.604158ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:04:39.503056Z","caller":"traceutil/trace.go:171","msg":"trace[1443337820] transaction","detail":"{read_only:false; response_revision:1029; number_of_response:1; }","duration":"878.263321ms","start":"2026-05-07T02:04:38.624774Z","end":"2026-05-07T02:04:39.503038Z","steps":["trace[1443337820] 'process raft request' (duration: 878.035266ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:39.503104Z","caller":"traceutil/trace.go:171","msg":"trace[1099185948] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"877.751529ms","start":"2026-05-07T02:04:38.625346Z","end":"2026-05-07T02:04:39.503098Z","steps":["trace[1099185948] 'process raft request' (duration: 877.642796ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:39.50309Z","caller":"traceutil/trace.go:171","msg":"trace[1844294812] linearizableReadLoop","detail":"{readStateIndex:1072; appliedIndex:1067; }","duration":"874.558061ms","start":"2026-05-07T02:04:38.628509Z","end":"2026-05-07T02:04:39.503067Z","steps":["trace[1844294812] 'read index received' (duration: 869.285493ms)","trace[1844294812] 'applied index is now lower than readState.Index' (duration: 5.271808ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:04:39.503152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:38.625338Z","time spent":"877.787049ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:04:39.503161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:38.624752Z","time spent":"878.351853ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:04:39.503067Z","caller":"traceutil/trace.go:171","msg":"trace[401942943] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"877.871702ms","start":"2026-05-07T02:04:38.625181Z","end":"2026-05-07T02:04:39.503052Z","steps":["trace[401942943] 'process raft request' (duration: 877.769899ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:39.503232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:38.625154Z","time spent":"878.061657ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:04:39.503233Z","caller":"traceutil/trace.go:171","msg":"trace[2070532225] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"701.872451ms","start":"2026-05-07T02:04:38.801349Z","end":"2026-05-07T02:04:39.503221Z","steps":["trace[2070532225] 'process raft request' (duration: 701.671156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:39.503299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.769506ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:04:39.503332Z","caller":"traceutil/trace.go:171","msg":"trace[1916926696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1032; }","duration":"874.852798ms","start":"2026-05-07T02:04:38.628471Z","end":"2026-05-07T02:04:39.503324Z","steps":["trace[1916926696] 'agreement among raft nodes before linearized reading' (duration: 874.781607ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:39.503359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:38.801331Z","time spent":"701.992764ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:04:39.50337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:38.628455Z","time spent":"874.903339ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T02:04:39.503372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.25216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:04:39.503418Z","caller":"traceutil/trace.go:171","msg":"trace[665939754] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1032; }","duration":"134.337132ms","start":"2026-05-07T02:04:39.369067Z","end":"2026-05-07T02:04:39.503404Z","steps":["trace[665939754] 'agreement among raft nodes before linearized reading' (duration: 134.25877ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:39.503515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.010743ms","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-07T02:04:39.503588Z","caller":"traceutil/trace.go:171","msg":"trace[633539229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1032; }","duration":"747.132366ms","start":"2026-05-07T02:04:38.756437Z","end":"2026-05-07T02:04:39.503569Z","steps":["trace[633539229] 'agreement among raft nodes before linearized reading' (duration: 746.89023ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:39.503654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:38.756423Z","time spent":"747.213469ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-07T02:04:41.779653Z","caller":"traceutil/trace.go:171","msg":"trace[1377228560] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"271.218195ms","start":"2026-05-07T02:04:41.508406Z","end":"2026-05-07T02:04:41.779625Z","steps":["trace[1377228560] 'process raft request' (duration: 271.063201ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:41.78229Z","caller":"traceutil/trace.go:171","msg":"trace[1036492665] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"273.340537ms","start":"2026-05-07T02:04:41.50892Z","end":"2026-05-07T02:04:41.782261Z","steps":["trace[1036492665] 'process raft request' (duration: 273.115472ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:41.782809Z","caller":"traceutil/trace.go:171","msg":"trace[304921443] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"272.711903ms","start":"2026-05-07T02:04:41.510079Z","end":"2026-05-07T02:04:41.782791Z","steps":["trace[304921443] 'process raft request' (duration: 272.052586ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:41.782821Z","caller":"traceutil/trace.go:171","msg":"trace[1490933812] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"272.667052ms","start":"2026-05-07T02:04:41.510132Z","end":"2026-05-07T02:04:41.782799Z","steps":["trace[1490933812] 'process raft request' (duration: 272.59652ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:41.782978Z","caller":"traceutil/trace.go:171","msg":"trace[2067299586] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1073; }","duration":"268.606093ms","start":"2026-05-07T02:04:41.514361Z","end":"2026-05-07T02:04:41.782967Z","steps":["trace[2067299586] 'read index received' (duration: 265.149558ms)","trace[2067299586] 'applied index is now lower than readState.Index' (duration: 3.455685ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:04:41.783073Z","caller":"traceutil/trace.go:171","msg":"trace[383100231] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"272.137158ms","start":"2026-05-07T02:04:41.510903Z","end":"2026-05-07T02:04:41.78304Z","steps":["trace[383100231] 'process raft request' (duration: 271.867441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:41.783161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.785477ms","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-07T02:04:41.783199Z","caller":"traceutil/trace.go:171","msg":"trace[1337624120] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1038; }","duration":"268.858999ms","start":"2026-05-07T02:04:41.514331Z","end":"2026-05-07T02:04:41.78319Z","steps":["trace[1337624120] 'agreement among raft nodes before linearized reading' (duration: 268.716936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:41.78326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.078214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:04:41.7833Z","caller":"traceutil/trace.go:171","msg":"trace[1204955055] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1038; }","duration":"200.158805ms","start":"2026-05-07T02:04:41.583131Z","end":"2026-05-07T02:04:41.783289Z","steps":["trace[1204955055] 'agreement among raft nodes before linearized reading' (duration: 200.089274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:41.783386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.682939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-07T02:04:41.783428Z","caller":"traceutil/trace.go:171","msg":"trace[1298966661] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:1038; }","duration":"167.762922ms","start":"2026-05-07T02:04:41.615654Z","end":"2026-05-07T02:04:41.783417Z","steps":["trace[1298966661] 'agreement among raft nodes before linearized reading' (duration: 167.633458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:44.292528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095697068045759,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-07T02:04:44.724913Z","caller":"traceutil/trace.go:171","msg":"trace[95958791] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"934.027331ms","start":"2026-05-07T02:04:43.790862Z","end":"2026-05-07T02:04:44.724889Z","steps":["trace[95958791] 'process raft request' (duration: 932.093124ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:44.725037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:43.79085Z","time spent":"934.120294ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:04:44.725021Z","caller":"traceutil/trace.go:171","msg":"trace[1551749454] linearizableReadLoop","detail":"{readStateIndex:1087; appliedIndex:1084; }","duration":"932.526434ms","start":"2026-05-07T02:04:43.792469Z","end":"2026-05-07T02:04:44.724996Z","steps":["trace[1551749454] 'read index received' (duration: 930.423853ms)","trace[1551749454] 'applied index is now lower than readState.Index' (duration: 2.085211ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:04:44.725082Z","caller":"traceutil/trace.go:171","msg":"trace[583118917] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"934.038832ms","start":"2026-05-07T02:04:43.791021Z","end":"2026-05-07T02:04:44.72506Z","steps":["trace[583118917] 'process raft request' (duration: 933.794326ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:44.725161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.028636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:04:44.725194Z","caller":"traceutil/trace.go:171","msg":"trace[710842255] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1046; }","duration":"144.092909ms","start":"2026-05-07T02:04:44.581092Z","end":"2026-05-07T02:04:44.725185Z","steps":["trace[710842255] 'agreement among raft nodes before linearized reading' (duration: 144.041847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:44.725195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:43.791Z","time spent":"934.138114ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:04:44.725187Z","caller":"traceutil/trace.go:171","msg":"trace[1490614435] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"934.076102ms","start":"2026-05-07T02:04:43.791071Z","end":"2026-05-07T02:04:44.725147Z","steps":["trace[1490614435] 'process raft request' (duration: 933.849796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:44.725273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.78422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-07T02:04:44.725341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:43.791055Z","time spent":"934.190085ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:04:44.725356Z","caller":"traceutil/trace.go:171","msg":"trace[1053553053] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1046; }","duration":"932.890512ms","start":"2026-05-07T02:04:43.79245Z","end":"2026-05-07T02:04:44.725341Z","steps":["trace[1053553053] 'agreement among raft nodes before linearized reading' (duration: 932.682807ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:44.725388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:43.792437Z","time spent":"932.943494ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-07T02:04:50.925043Z","caller":"traceutil/trace.go:171","msg":"trace[1046563238] linearizableReadLoop","detail":"{readStateIndex:1105; appliedIndex:1104; }","duration":"156.227362ms","start":"2026-05-07T02:04:50.768793Z","end":"2026-05-07T02:04:50.925021Z","steps":["trace[1046563238] 'read index received' (duration: 156.199822ms)","trace[1046563238] 'applied index is now lower than readState.Index' (duration: 26.62µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:04:50.925198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.356205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T02:04:50.925277Z","caller":"traceutil/trace.go:171","msg":"trace[365886887] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1063; }","duration":"156.484128ms","start":"2026-05-07T02:04:50.768776Z","end":"2026-05-07T02:04:50.925261Z","steps":["trace[365886887] 'agreement among raft nodes before linearized reading' (duration: 156.318284ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:50.92561Z","caller":"traceutil/trace.go:171","msg":"trace[797727835] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"157.293057ms","start":"2026-05-07T02:04:50.768305Z","end":"2026-05-07T02:04:50.925598Z","steps":["trace[797727835] 'process raft request' (duration: 156.595671ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:51.110556Z","caller":"traceutil/trace.go:171","msg":"trace[315274311] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"340.378869ms","start":"2026-05-07T02:04:50.770156Z","end":"2026-05-07T02:04:51.110535Z","steps":["trace[315274311] 'process raft request' (duration: 284.323642ms)","trace[315274311] 'compare' (duration: 55.935834ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:04:51.110693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:50.770144Z","time spent":"340.488723ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:04:51.110744Z","caller":"traceutil/trace.go:171","msg":"trace[676335066] linearizableReadLoop","detail":"{readStateIndex:1107; appliedIndex:1105; }","duration":"185.615513ms","start":"2026-05-07T02:04:50.925097Z","end":"2026-05-07T02:04:51.110712Z","steps":["trace[676335066] 'read index received' (duration: 129.442744ms)","trace[676335066] 'applied index is now lower than readState.Index' (duration: 56.171849ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:04:51.110895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.278496ms","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-07T02:04:51.110917Z","caller":"traceutil/trace.go:171","msg":"trace[667951473] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1065; }","duration":"266.359528ms","start":"2026-05-07T02:04:50.844551Z","end":"2026-05-07T02:04:51.11091Z","steps":["trace[667951473] 'agreement among raft nodes before linearized reading' (duration: 266.237315ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:04:51.111063Z","caller":"traceutil/trace.go:171","msg":"trace[54895539] transaction","detail":"{read_only:false; response_revision:1065; number_of_response:1; }","duration":"340.889482ms","start":"2026-05-07T02:04:50.770165Z","end":"2026-05-07T02:04:51.111054Z","steps":["trace[54895539] 'process raft request' (duration: 340.340828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:04:51.111123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:04:50.770154Z","time spent":"340.922572ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:04:51.319027Z","caller":"traceutil/trace.go:171","msg":"trace[950067524] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"202.490732ms","start":"2026-05-07T02:04:51.116506Z","end":"2026-05-07T02:04:51.318997Z","steps":["trace[950067524] 'process raft request' (duration: 116.430319ms)","trace[950067524] 'compare' (duration: 85.92158ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:04:55.418106Z","caller":"traceutil/trace.go:171","msg":"trace[1265359256] transaction","detail":"{read_only:false; response_revision:1080; number_of_response:1; }","duration":"126.344838ms","start":"2026-05-07T02:04:55.291729Z","end":"2026-05-07T02:04:55.418074Z","steps":["trace[1265359256] 'process raft request' (duration: 126.102013ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:01.16341Z","caller":"traceutil/trace.go:171","msg":"trace[1663660475] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"168.249831ms","start":"2026-05-07T02:05:00.995114Z","end":"2026-05-07T02:05:01.163364Z","steps":["trace[1663660475] 'process raft request' (duration: 167.934143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:01.929767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.586231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T02:05:01.92992Z","caller":"traceutil/trace.go:171","msg":"trace[396670640] linearizableReadLoop","detail":"{readStateIndex:1141; appliedIndex:1140; }","duration":"702.786762ms","start":"2026-05-07T02:05:01.22711Z","end":"2026-05-07T02:05:01.929897Z","steps":["trace[396670640] 'read index received' (duration: 440.978935ms)","trace[396670640] 'applied index is now lower than readState.Index' (duration: 261.805907ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:01.930205Z","caller":"traceutil/trace.go:171","msg":"trace[1450918266] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"746.085618ms","start":"2026-05-07T02:05:01.184034Z","end":"2026-05-07T02:05:01.930119Z","steps":["trace[1450918266] 'process raft request' (duration: 484.043504ms)","trace[1450918266] 'compare' (duration: 261.290404ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:01.930389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:01.184008Z","time spent":"746.282323ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:01.930403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.290837ms","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-07T02:05:01.93046Z","caller":"traceutil/trace.go:171","msg":"trace[1779092210] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1097; }","duration":"703.357248ms","start":"2026-05-07T02:05:01.227089Z","end":"2026-05-07T02:05:01.930446Z","steps":["trace[1779092210] 'agreement among raft nodes before linearized reading' (duration: 703.168464ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:01.930489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:01.227013Z","time spent":"703.470331ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T02:05:02.303695Z","caller":"traceutil/trace.go:171","msg":"trace[1287021289] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"411.198187ms","start":"2026-05-07T02:05:01.892469Z","end":"2026-05-07T02:05:02.303667Z","steps":["trace[1287021289] 'process raft request' (duration: 411.037873ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:02.303726Z","caller":"traceutil/trace.go:171","msg":"trace[1029202084] linearizableReadLoop","detail":"{readStateIndex:1142; appliedIndex:1142; }","duration":"373.737381ms","start":"2026-05-07T02:05:01.929952Z","end":"2026-05-07T02:05:02.303689Z","steps":["trace[1029202084] 'read index received' (duration: 373.723661ms)","trace[1029202084] 'applied index is now lower than readState.Index' (duration: 11.81µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:02.303878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:01.892443Z","time spent":"411.33261ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:02.303934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.895601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:02.303994Z","caller":"traceutil/trace.go:171","msg":"trace[1489625301] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1098; }","duration":"722.011584ms","start":"2026-05-07T02:05:01.581968Z","end":"2026-05-07T02:05:02.30398Z","steps":["trace[1489625301] 'agreement among raft nodes before linearized reading' (duration: 721.86975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:02.304061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:01.581947Z","time spent":"722.099636ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T02:05:02.304062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.212201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-07T02:05:02.304148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"688.628293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-07T02:05:02.304223Z","caller":"traceutil/trace.go:171","msg":"trace[1046295718] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1098; }","duration":"379.425605ms","start":"2026-05-07T02:05:01.92477Z","end":"2026-05-07T02:05:02.304196Z","steps":["trace[1046295718] 'agreement among raft nodes before linearized reading' (duration: 379.006085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:02.304286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:01.924757Z","time spent":"379.516638ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T02:05:02.304251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.159059ms","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-07T02:05:02.304248Z","caller":"traceutil/trace.go:171","msg":"trace[1460558196] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:1098; }","duration":"688.726885ms","start":"2026-05-07T02:05:01.615503Z","end":"2026-05-07T02:05:02.304229Z","steps":["trace[1460558196] 'agreement among raft nodes before linearized reading' (duration: 688.221972ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:02.30437Z","caller":"traceutil/trace.go:171","msg":"trace[1947061712] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1098; }","duration":"835.327913ms","start":"2026-05-07T02:05:01.468997Z","end":"2026-05-07T02:05:02.304324Z","steps":["trace[1947061712] 'agreement among raft nodes before linearized reading' (duration: 834.915183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:02.304354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:01.615452Z","time spent":"688.885759ms","remote":"127.0.0.1:42514","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.89\" "} {"level":"warn","ts":"2026-05-07T02:05:02.304439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:01.468964Z","time spent":"835.465997ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-07T02:05:02.333306Z","caller":"traceutil/trace.go:171","msg":"trace[270883446] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"397.22237ms","start":"2026-05-07T02:05:01.936054Z","end":"2026-05-07T02:05:02.333276Z","steps":["trace[270883446] 'process raft request' (duration: 397.078156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:02.333549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.529459ms","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":"info","ts":"2026-05-07T02:05:02.333621Z","caller":"traceutil/trace.go:171","msg":"trace[1245750144] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1099; }","duration":"117.680722ms","start":"2026-05-07T02:05:02.215923Z","end":"2026-05-07T02:05:02.333603Z","steps":["trace[1245750144] 'agreement among raft nodes before linearized reading' (duration: 117.539488ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:02.334185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:01.936034Z","time spent":"398.00866ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:05:03.730523Z","caller":"traceutil/trace.go:171","msg":"trace[1080186146] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1148; }","duration":"149.635216ms","start":"2026-05-07T02:05:03.58083Z","end":"2026-05-07T02:05:03.730465Z","steps":["trace[1080186146] 'read index received' (duration: 149.436571ms)","trace[1080186146] 'applied index is now lower than readState.Index' (duration: 197.615µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:03.730954Z","caller":"traceutil/trace.go:171","msg":"trace[1036114500] transaction","detail":"{read_only:false; response_revision:1104; number_of_response:1; }","duration":"398.02438ms","start":"2026-05-07T02:05:03.332893Z","end":"2026-05-07T02:05:03.730918Z","steps":["trace[1036114500] 'process raft request' (duration: 397.449716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:03.731098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:03.332873Z","time spent":"398.159314ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:03.731659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.817426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:03.731711Z","caller":"traceutil/trace.go:171","msg":"trace[1613727022] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1104; }","duration":"150.915759ms","start":"2026-05-07T02:05:03.580783Z","end":"2026-05-07T02:05:03.731698Z","steps":["trace[1613727022] 'agreement among raft nodes before linearized reading' (duration: 150.821407ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:04.294822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.147229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:04.294912Z","caller":"traceutil/trace.go:171","msg":"trace[399377997] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1104; }","duration":"279.308443ms","start":"2026-05-07T02:05:04.01557Z","end":"2026-05-07T02:05:04.294879Z","steps":["trace[399377997] 'count revisions from in-memory index tree' (duration: 279.051026ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:04.29528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.612281ms","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-07T02:05:04.295393Z","caller":"traceutil/trace.go:171","msg":"trace[130154023] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1104; }","duration":"359.791795ms","start":"2026-05-07T02:05:03.935576Z","end":"2026-05-07T02:05:04.295368Z","steps":["trace[130154023] 'range keys from in-memory index tree' (duration: 359.379285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:04.295452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:03.935553Z","time spent":"359.882368ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T02:05:04.591206Z","caller":"traceutil/trace.go:171","msg":"trace[221146069] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"193.474744ms","start":"2026-05-07T02:05:04.397704Z","end":"2026-05-07T02:05:04.591178Z","steps":["trace[221146069] 'process raft request' (duration: 164.455745ms)","trace[221146069] 'compare' (duration: 28.758973ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:04.678972Z","caller":"traceutil/trace.go:171","msg":"trace[1415998817] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"281.12856ms","start":"2026-05-07T02:05:04.397819Z","end":"2026-05-07T02:05:04.678947Z","steps":["trace[1415998817] 'process raft request' (duration: 280.960576ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:04.679189Z","caller":"traceutil/trace.go:171","msg":"trace[1463844516] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"280.965305ms","start":"2026-05-07T02:05:04.398189Z","end":"2026-05-07T02:05:04.679154Z","steps":["trace[1463844516] 'process raft request' (duration: 280.723469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:07.084849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"727.308528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:07.084955Z","caller":"traceutil/trace.go:171","msg":"trace[1964262766] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1112; }","duration":"727.464722ms","start":"2026-05-07T02:05:06.357467Z","end":"2026-05-07T02:05:07.084931Z","steps":["trace[1964262766] 'agreement among raft nodes before linearized reading' (duration: 82.030232ms)","trace[1964262766] 'count revisions from in-memory index tree' (duration: 645.298667ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:07.085002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:06.357446Z","time spent":"727.544774ms","remote":"127.0.0.1:56978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-07T02:05:07.085067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.777777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T02:05:07.085271Z","caller":"traceutil/trace.go:171","msg":"trace[230653598] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"685.942213ms","start":"2026-05-07T02:05:06.399294Z","end":"2026-05-07T02:05:07.085236Z","steps":["trace[230653598] 'process raft request' (duration: 92.907609ms)","trace[230653598] 'compare' (duration: 592.503811ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:07.085295Z","caller":"traceutil/trace.go:171","msg":"trace[474627452] linearizableReadLoop","detail":"{readStateIndex:1158; appliedIndex:1157; }","duration":"490.053818ms","start":"2026-05-07T02:05:06.595205Z","end":"2026-05-07T02:05:07.085259Z","steps":["trace[474627452] 'read index received' (duration: 47.771µs)","trace[474627452] 'applied index is now lower than readState.Index' (duration: 490.003827ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:07.085388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:06.399273Z","time spent":"686.049516ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:07.893691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"808.512237ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-07T02:05:07.893806Z","caller":"traceutil/trace.go:171","msg":"trace[827793345] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1158; }","duration":"808.419346ms","start":"2026-05-07T02:05:07.085368Z","end":"2026-05-07T02:05:07.893787Z","steps":["trace[827793345] 'read index received' (duration: 53.442µs)","trace[827793345] 'applied index is now lower than readState.Index' (duration: 808.364143ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:07.894075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.03654ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-07T02:05:07.894115Z","caller":"traceutil/trace.go:171","msg":"trace[521616422] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1113; }","duration":"660.131772ms","start":"2026-05-07T02:05:07.233973Z","end":"2026-05-07T02:05:07.894105Z","steps":["trace[521616422] 'agreement among raft nodes before linearized reading' (duration: 659.967648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:07.894075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.652493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-07T02:05:07.894149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:07.233947Z","time spent":"660.194674ms","remote":"127.0.0.1:42764","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-07T02:05:07.894131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.298921694s","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-07T02:05:07.894171Z","caller":"traceutil/trace.go:171","msg":"trace[808247209] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1113; }","duration":"312.791206ms","start":"2026-05-07T02:05:07.581361Z","end":"2026-05-07T02:05:07.894153Z","steps":["trace[808247209] 'agreement among raft nodes before linearized reading' (duration: 312.642592ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:07.894202Z","caller":"traceutil/trace.go:171","msg":"trace[990540056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1113; }","duration":"1.299025786s","start":"2026-05-07T02:05:06.595155Z","end":"2026-05-07T02:05:07.894181Z","steps":["trace[990540056] 'agreement among raft nodes before linearized reading' (duration: 490.224382ms)","trace[990540056] 'range keys from in-memory index tree' (duration: 808.660061ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:07.894224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:07.581341Z","time spent":"312.874959ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T02:05:07.894253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:06.595133Z","time spent":"1.2991114s","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T02:05:07.894258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211847354s","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-07T02:05:07.89428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.20719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T02:05:07.894311Z","caller":"traceutil/trace.go:171","msg":"trace[1223810189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1113; }","duration":"1.211939016s","start":"2026-05-07T02:05:06.682359Z","end":"2026-05-07T02:05:07.894298Z","steps":["trace[1223810189] 'agreement among raft nodes before linearized reading' (duration: 1.211793372s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:07.89413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.211383732s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-07T02:05:07.89436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:06.682338Z","time spent":"1.212014039s","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-07T02:05:07.894362Z","caller":"traceutil/trace.go:171","msg":"trace[345920810] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1113; }","duration":"1.21166813s","start":"2026-05-07T02:05:06.682686Z","end":"2026-05-07T02:05:07.894354Z","steps":["trace[345920810] 'agreement among raft nodes before linearized reading' (duration: 1.211241988s)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:07.894364Z","caller":"traceutil/trace.go:171","msg":"trace[1542369264] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1113; }","duration":"428.342964ms","start":"2026-05-07T02:05:07.466002Z","end":"2026-05-07T02:05:07.894345Z","steps":["trace[1542369264] 'agreement among raft nodes before linearized reading' (duration: 428.141747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:07.894393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:06.682664Z","time spent":"1.211722711s","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T02:05:07.89445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:07.46598Z","time spent":"428.457416ms","remote":"127.0.0.1:42876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":31,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-05-07T02:05:07.895148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.269947ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T02:05:07.895402Z","caller":"traceutil/trace.go:171","msg":"trace[1884133984] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1113; }","duration":"439.193791ms","start":"2026-05-07T02:05:07.456186Z","end":"2026-05-07T02:05:07.895379Z","steps":["trace[1884133984] 'agreement among raft nodes before linearized reading' (duration: 438.256127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:07.895474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:07.45616Z","time spent":"439.296142ms","remote":"127.0.0.1:42658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-05-07T02:05:08.168144Z","caller":"traceutil/trace.go:171","msg":"trace[522034053] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"269.724898ms","start":"2026-05-07T02:05:07.898382Z","end":"2026-05-07T02:05:08.168107Z","steps":["trace[522034053] 'process raft request' (duration: 269.570124ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:08.252345Z","caller":"traceutil/trace.go:171","msg":"trace[1905307669] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1160; }","duration":"159.108547ms","start":"2026-05-07T02:05:08.093211Z","end":"2026-05-07T02:05:08.25232Z","steps":["trace[1905307669] 'read index received' (duration: 75.066254ms)","trace[1905307669] 'applied index is now lower than readState.Index' (duration: 84.041193ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:08.252473Z","caller":"traceutil/trace.go:171","msg":"trace[1166956116] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"353.199706ms","start":"2026-05-07T02:05:07.899241Z","end":"2026-05-07T02:05:08.25244Z","steps":["trace[1166956116] 'process raft request' (duration: 352.820246ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:08.252529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.301381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T02:05:08.252564Z","caller":"traceutil/trace.go:171","msg":"trace[1406589819] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1117; }","duration":"159.393504ms","start":"2026-05-07T02:05:08.09316Z","end":"2026-05-07T02:05:08.252554Z","steps":["trace[1406589819] 'agreement among raft nodes before linearized reading' (duration: 159.272971ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:08.252775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:07.899219Z","time spent":"353.317869ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:05:08.252856Z","caller":"traceutil/trace.go:171","msg":"trace[2103623583] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"353.380172ms","start":"2026-05-07T02:05:07.899464Z","end":"2026-05-07T02:05:08.252845Z","steps":["trace[2103623583] 'process raft request' (duration: 352.753465ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:08.253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:07.899439Z","time spent":"353.489804ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:05:08.253001Z","caller":"traceutil/trace.go:171","msg":"trace[1907362984] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"353.425982ms","start":"2026-05-07T02:05:07.899556Z","end":"2026-05-07T02:05:08.252982Z","steps":["trace[1907362984] 'process raft request' (duration: 352.726034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:08.253131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:07.899545Z","time spent":"353.507855ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:12.539893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.535889ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T02:05:12.540148Z","caller":"traceutil/trace.go:171","msg":"trace[2124387893] linearizableReadLoop","detail":"{readStateIndex:1177; appliedIndex:1174; }","duration":"132.089768ms","start":"2026-05-07T02:05:12.408036Z","end":"2026-05-07T02:05:12.540126Z","steps":["trace[2124387893] 'read index received' (duration: 65.037459ms)","trace[2124387893] 'applied index is now lower than readState.Index' (duration: 67.051189ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:12.540162Z","caller":"traceutil/trace.go:171","msg":"trace[1229595060] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"267.792619ms","start":"2026-05-07T02:05:12.27235Z","end":"2026-05-07T02:05:12.540143Z","steps":["trace[1229595060] 'process raft request' (duration: 267.658365ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:12.540217Z","caller":"traceutil/trace.go:171","msg":"trace[1846618372] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"266.986557ms","start":"2026-05-07T02:05:12.27322Z","end":"2026-05-07T02:05:12.540207Z","steps":["trace[1846618372] 'process raft request' (duration: 266.855994ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:12.540137Z","caller":"traceutil/trace.go:171","msg":"trace[1447379528] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"268.102526ms","start":"2026-05-07T02:05:12.271989Z","end":"2026-05-07T02:05:12.540091Z","steps":["trace[1447379528] 'process raft request' (duration: 97.26945ms)","trace[1447379528] 'compare' (duration: 169.685457ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:12.540299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.260672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T02:05:12.540358Z","caller":"traceutil/trace.go:171","msg":"trace[787040362] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1130; }","duration":"132.354245ms","start":"2026-05-07T02:05:12.407984Z","end":"2026-05-07T02:05:12.540339Z","steps":["trace[787040362] 'agreement among raft nodes before linearized reading' (duration: 132.266452ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:18.721153Z","caller":"traceutil/trace.go:171","msg":"trace[354057806] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"139.562758ms","start":"2026-05-07T02:05:18.581566Z","end":"2026-05-07T02:05:18.721128Z","steps":["trace[354057806] 'process raft request' (duration: 139.413505ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:19.01724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.275696ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T02:05:19.017496Z","caller":"traceutil/trace.go:171","msg":"trace[1185079458] linearizableReadLoop","detail":"{readStateIndex:1251; appliedIndex:1248; }","duration":"414.775168ms","start":"2026-05-07T02:05:18.602699Z","end":"2026-05-07T02:05:19.017474Z","steps":["trace[1185079458] 'read index received' (duration: 118.380589ms)","trace[1185079458] 'applied index is now lower than readState.Index' (duration: 296.393099ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:19.017645Z","caller":"traceutil/trace.go:171","msg":"trace[1826188915] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"435.29565ms","start":"2026-05-07T02:05:18.582326Z","end":"2026-05-07T02:05:19.017621Z","steps":["trace[1826188915] 'process raft request' (duration: 276.524332ms)","trace[1826188915] 'compare' (duration: 157.925377ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:19.017699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.003353ms","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-07T02:05:19.017751Z","caller":"traceutil/trace.go:171","msg":"trace[1563965107] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1201; }","duration":"415.054994ms","start":"2026-05-07T02:05:18.60268Z","end":"2026-05-07T02:05:19.017735Z","steps":["trace[1563965107] 'agreement among raft nodes before linearized reading' (duration: 414.8672ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:19.017736Z","caller":"traceutil/trace.go:171","msg":"trace[1720820714] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"434.250484ms","start":"2026-05-07T02:05:18.583447Z","end":"2026-05-07T02:05:19.017698Z","steps":["trace[1720820714] 'process raft request' (duration: 433.935596ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:19.017806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:18.602619Z","time spent":"415.177708ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T02:05:19.017754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:18.582312Z","time spent":"435.393573ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:19.017914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:18.58344Z","time spent":"434.374227ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:05:20.949329Z","caller":"traceutil/trace.go:171","msg":"trace[1187303585] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"686.147638ms","start":"2026-05-07T02:05:20.26316Z","end":"2026-05-07T02:05:20.949308Z","steps":["trace[1187303585] 'process raft request' (duration: 685.966923ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:20.949355Z","caller":"traceutil/trace.go:171","msg":"trace[1762182163] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1253; }","duration":"368.820716ms","start":"2026-05-07T02:05:20.580503Z","end":"2026-05-07T02:05:20.949324Z","steps":["trace[1762182163] 'read index received' (duration: 368.57092ms)","trace[1762182163] 'applied index is now lower than readState.Index' (duration: 247.996µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:20.949429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:20.263148Z","time spent":"686.23169ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:20.949518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.997161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:20.949561Z","caller":"traceutil/trace.go:171","msg":"trace[76441123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1204; }","duration":"369.088403ms","start":"2026-05-07T02:05:20.580463Z","end":"2026-05-07T02:05:20.949552Z","steps":["trace[76441123] 'agreement among raft nodes before linearized reading' (duration: 368.990461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:20.949609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:20.580446Z","time spent":"369.149024ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T02:05:20.9497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.369931ms","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-07T02:05:20.949813Z","caller":"traceutil/trace.go:171","msg":"trace[459898241] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1204; }","duration":"224.514255ms","start":"2026-05-07T02:05:20.725283Z","end":"2026-05-07T02:05:20.949797Z","steps":["trace[459898241] 'agreement among raft nodes before linearized reading' (duration: 224.197227ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:27.232948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.238833ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T02:05:27.233111Z","caller":"traceutil/trace.go:171","msg":"trace[1350862810] linearizableReadLoop","detail":"{readStateIndex:1281; appliedIndex:1278; }","duration":"102.434063ms","start":"2026-05-07T02:05:27.130666Z","end":"2026-05-07T02:05:27.2331Z","steps":["trace[1350862810] 'read index received' (duration: 101.956171ms)","trace[1350862810] 'applied index is now lower than readState.Index' (duration: 477.332µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:27.233154Z","caller":"traceutil/trace.go:171","msg":"trace[2114244923] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"161.95152ms","start":"2026-05-07T02:05:27.071197Z","end":"2026-05-07T02:05:27.233149Z","steps":["trace[2114244923] 'process raft request' (duration: 161.885338ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:27.233139Z","caller":"traceutil/trace.go:171","msg":"trace[242376268] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"164.176707ms","start":"2026-05-07T02:05:27.068944Z","end":"2026-05-07T02:05:27.233121Z","steps":["trace[242376268] 'process raft request' (duration: 40.692208ms)","trace[242376268] 'compare' (duration: 123.028878ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:27.233325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.661318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:27.23335Z","caller":"traceutil/trace.go:171","msg":"trace[64329362] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1229; }","duration":"102.693289ms","start":"2026-05-07T02:05:27.13065Z","end":"2026-05-07T02:05:27.233344Z","steps":["trace[64329362] 'agreement among raft nodes before linearized reading' (duration: 102.594786ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:27.233229Z","caller":"traceutil/trace.go:171","msg":"trace[234588395] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"163.965882ms","start":"2026-05-07T02:05:27.069237Z","end":"2026-05-07T02:05:27.233203Z","steps":["trace[234588395] 'process raft request' (duration: 163.781447ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:34.528307Z","caller":"traceutil/trace.go:171","msg":"trace[1974571781] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"228.480756ms","start":"2026-05-07T02:05:34.299803Z","end":"2026-05-07T02:05:34.528284Z","steps":["trace[1974571781] 'process raft request' (duration: 228.349913ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:37.837592Z","caller":"traceutil/trace.go:171","msg":"trace[841552880] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"144.285139ms","start":"2026-05-07T02:05:37.693283Z","end":"2026-05-07T02:05:37.837568Z","steps":["trace[841552880] 'process raft request' (duration: 144.137745ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:38.112937Z","caller":"traceutil/trace.go:171","msg":"trace[819286730] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"270.237391ms","start":"2026-05-07T02:05:37.842677Z","end":"2026-05-07T02:05:38.112915Z","steps":["trace[819286730] 'process raft request' (duration: 270.100788ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:38.141911Z","caller":"traceutil/trace.go:171","msg":"trace[507369451] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"297.64234ms","start":"2026-05-07T02:05:37.844247Z","end":"2026-05-07T02:05:38.14189Z","steps":["trace[507369451] 'process raft request' (duration: 297.488146ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:38.142271Z","caller":"traceutil/trace.go:171","msg":"trace[486999882] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"294.407178ms","start":"2026-05-07T02:05:37.847843Z","end":"2026-05-07T02:05:38.14225Z","steps":["trace[486999882] 'process raft request' (duration: 294.004638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:39.056509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.10686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T02:05:39.056566Z","caller":"traceutil/trace.go:171","msg":"trace[1233866007] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1283; }","duration":"285.175782ms","start":"2026-05-07T02:05:38.771377Z","end":"2026-05-07T02:05:39.056553Z","steps":["trace[1233866007] 'count revisions from in-memory index tree' (duration: 285.028029ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:39.520907Z","caller":"traceutil/trace.go:171","msg":"trace[1170287036] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"411.681989ms","start":"2026-05-07T02:05:39.109198Z","end":"2026-05-07T02:05:39.52088Z","steps":["trace[1170287036] 'read index received' (duration: 411.476544ms)","trace[1170287036] 'applied index is now lower than readState.Index' (duration: 204.195µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:39.520991Z","caller":"traceutil/trace.go:171","msg":"trace[1281396062] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"459.533799ms","start":"2026-05-07T02:05:39.061411Z","end":"2026-05-07T02:05:39.520945Z","steps":["trace[1281396062] 'process raft request' (duration: 459.299533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:39.521179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:39.061396Z","time spent":"459.691023ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:39.521246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.931295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-07T02:05:39.521347Z","caller":"traceutil/trace.go:171","msg":"trace[677980987] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1284; }","duration":"412.13909ms","start":"2026-05-07T02:05:39.109142Z","end":"2026-05-07T02:05:39.521281Z","steps":["trace[677980987] 'agreement among raft nodes before linearized reading' (duration: 411.864183ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:39.5214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:39.109061Z","time spent":"412.325345ms","remote":"127.0.0.1:42764","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-07T02:05:39.52506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.980444ms","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-07T02:05:39.525135Z","caller":"traceutil/trace.go:171","msg":"trace[189958277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1285; }","duration":"210.056106ms","start":"2026-05-07T02:05:39.315062Z","end":"2026-05-07T02:05:39.525118Z","steps":["trace[189958277] 'agreement among raft nodes before linearized reading' (duration: 209.846651ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:39.525606Z","caller":"traceutil/trace.go:171","msg":"trace[1058918143] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"215.755752ms","start":"2026-05-07T02:05:39.309832Z","end":"2026-05-07T02:05:39.525588Z","steps":["trace[1058918143] 'process raft request' (duration: 214.981913ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:39.525909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.11665ms","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-07T02:05:39.525966Z","caller":"traceutil/trace.go:171","msg":"trace[265450315] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1285; }","duration":"205.203302ms","start":"2026-05-07T02:05:39.320748Z","end":"2026-05-07T02:05:39.525951Z","steps":["trace[265450315] 'agreement among raft nodes before linearized reading' (duration: 205.072319ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:39.526222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.80428ms","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-07T02:05:39.526276Z","caller":"traceutil/trace.go:171","msg":"trace[904059362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1285; }","duration":"209.892902ms","start":"2026-05-07T02:05:39.316369Z","end":"2026-05-07T02:05:39.526262Z","steps":["trace[904059362] 'agreement among raft nodes before linearized reading' (duration: 209.766109ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:39.769613Z","caller":"traceutil/trace.go:171","msg":"trace[67630894] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1340; }","duration":"189.36845ms","start":"2026-05-07T02:05:39.580227Z","end":"2026-05-07T02:05:39.769595Z","steps":["trace[67630894] 'read index received' (duration: 189.138704ms)","trace[67630894] 'applied index is now lower than readState.Index' (duration: 229.076µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:39.769658Z","caller":"traceutil/trace.go:171","msg":"trace[1109555733] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"240.024702ms","start":"2026-05-07T02:05:39.529605Z","end":"2026-05-07T02:05:39.76963Z","steps":["trace[1109555733] 'process raft request' (duration: 239.807276ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:39.769703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.467322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:39.769728Z","caller":"traceutil/trace.go:171","msg":"trace[1842360919] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1289; }","duration":"189.508073ms","start":"2026-05-07T02:05:39.580213Z","end":"2026-05-07T02:05:39.769721Z","steps":["trace[1842360919] 'agreement among raft nodes before linearized reading' (duration: 189.451952ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:39.769795Z","caller":"traceutil/trace.go:171","msg":"trace[1488813901] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"240.159855ms","start":"2026-05-07T02:05:39.529627Z","end":"2026-05-07T02:05:39.769787Z","steps":["trace[1488813901] 'process raft request' (duration: 239.915429ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:39.769879Z","caller":"traceutil/trace.go:171","msg":"trace[998106351] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"240.171335ms","start":"2026-05-07T02:05:39.52967Z","end":"2026-05-07T02:05:39.769842Z","steps":["trace[998106351] 'process raft request' (duration: 239.898628ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:41.798453Z","caller":"traceutil/trace.go:171","msg":"trace[832047707] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"268.99162ms","start":"2026-05-07T02:05:41.529434Z","end":"2026-05-07T02:05:41.798426Z","steps":["trace[832047707] 'process raft request' (duration: 268.832576ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:41.847022Z","caller":"traceutil/trace.go:171","msg":"trace[931929850] linearizableReadLoop","detail":"{readStateIndex:1346; appliedIndex:1344; }","duration":"286.279461ms","start":"2026-05-07T02:05:41.560714Z","end":"2026-05-07T02:05:41.846993Z","steps":["trace[931929850] 'read index received' (duration: 237.539848ms)","trace[931929850] 'applied index is now lower than readState.Index' (duration: 48.738363ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:41.847152Z","caller":"traceutil/trace.go:171","msg":"trace[1192188920] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"315.805894ms","start":"2026-05-07T02:05:41.531304Z","end":"2026-05-07T02:05:41.84711Z","steps":["trace[1192188920] 'process raft request' (duration: 315.529786ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:41.847266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.538188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-07T02:05:41.847315Z","caller":"traceutil/trace.go:171","msg":"trace[1252794273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1292; }","duration":"286.638331ms","start":"2026-05-07T02:05:41.560658Z","end":"2026-05-07T02:05:41.847296Z","steps":["trace[1252794273] 'agreement among raft nodes before linearized reading' (duration: 286.473697ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:41.847374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:41.531273Z","time spent":"315.974139ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:41.847472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.494872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:41.847503Z","caller":"traceutil/trace.go:171","msg":"trace[1347564408] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1292; }","duration":"267.548814ms","start":"2026-05-07T02:05:41.579947Z","end":"2026-05-07T02:05:41.847495Z","steps":["trace[1347564408] 'agreement among raft nodes before linearized reading' (duration: 267.504593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:41.847737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.292758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-07T02:05:41.847765Z","caller":"traceutil/trace.go:171","msg":"trace[608037887] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:1292; }","duration":"229.340448ms","start":"2026-05-07T02:05:41.618416Z","end":"2026-05-07T02:05:41.847756Z","steps":["trace[608037887] 'agreement among raft nodes before linearized reading' (duration: 229.256766ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:42.105436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.206614ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"info","ts":"2026-05-07T02:05:42.254686Z","caller":"traceutil/trace.go:171","msg":"trace[807790834] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"403.445028ms","start":"2026-05-07T02:05:41.851219Z","end":"2026-05-07T02:05:42.254664Z","steps":["trace[807790834] 'process raft request' (duration: 365.496851ms)","trace[807790834] 'compare' (duration: 37.787633ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:42.254849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:41.851194Z","time spent":"403.595732ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:05:42.254936Z","caller":"traceutil/trace.go:171","msg":"trace[1967393433] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"402.657549ms","start":"2026-05-07T02:05:41.852261Z","end":"2026-05-07T02:05:42.254918Z","steps":["trace[1967393433] 'process raft request' (duration: 402.360301ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:42.25497Z","caller":"traceutil/trace.go:171","msg":"trace[851859168] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1347; }","duration":"285.614184ms","start":"2026-05-07T02:05:41.969337Z","end":"2026-05-07T02:05:42.254952Z","steps":["trace[851859168] 'read index received' (duration: 247.389389ms)","trace[851859168] 'applied index is now lower than readState.Index' (duration: 38.224105ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:42.255028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:41.852246Z","time spent":"402.740311ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:05:42.25506Z","caller":"traceutil/trace.go:171","msg":"trace[673519164] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"402.634038ms","start":"2026-05-07T02:05:41.85242Z","end":"2026-05-07T02:05:42.255055Z","steps":["trace[673519164] 'process raft request' (duration: 402.400922ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:42.255132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:41.85221Z","time spent":"402.867825ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:05:42.255133Z","caller":"traceutil/trace.go:171","msg":"trace[58258869] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"148.229921ms","start":"2026-05-07T02:05:42.10689Z","end":"2026-05-07T02:05:42.25512Z","steps":["trace[58258869] 'process raft request' (duration: 148.045536ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:42.255217Z","caller":"traceutil/trace.go:171","msg":"trace[717960208] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"402.729031ms","start":"2026-05-07T02:05:41.852483Z","end":"2026-05-07T02:05:42.255212Z","steps":["trace[717960208] 'process raft request' (duration: 402.424663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:42.255245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:41.852476Z","time spent":"402.754352ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:42.25531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.972013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:42.255343Z","caller":"traceutil/trace.go:171","msg":"trace[190122737] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1298; }","duration":"286.062605ms","start":"2026-05-07T02:05:41.969272Z","end":"2026-05-07T02:05:42.255335Z","steps":["trace[190122737] 'agreement among raft nodes before linearized reading' (duration: 286.020084ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:42.44676Z","caller":"traceutil/trace.go:171","msg":"trace[320413976] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"133.458274ms","start":"2026-05-07T02:05:42.313279Z","end":"2026-05-07T02:05:42.446738Z","steps":["trace[320413976] 'process raft request' (duration: 133.260619ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:42.475368Z","caller":"traceutil/trace.go:171","msg":"trace[1170479585] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"150.451427ms","start":"2026-05-07T02:05:42.324896Z","end":"2026-05-07T02:05:42.475348Z","steps":["trace[1170479585] 'process raft request' (duration: 150.409106ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:42.475704Z","caller":"traceutil/trace.go:171","msg":"trace[896201037] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"160.185345ms","start":"2026-05-07T02:05:42.315508Z","end":"2026-05-07T02:05:42.475693Z","steps":["trace[896201037] 'process raft request' (duration: 159.682482ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:42.609264Z","caller":"traceutil/trace.go:171","msg":"trace[468932120] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"129.830671ms","start":"2026-05-07T02:05:42.479391Z","end":"2026-05-07T02:05:42.609222Z","steps":["trace[468932120] 'process raft request' (duration: 54.06063ms)","trace[468932120] 'compare' (duration: 75.604627ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:44.205743Z","caller":"traceutil/trace.go:171","msg":"trace[1899387434] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"352.204562ms","start":"2026-05-07T02:05:43.853513Z","end":"2026-05-07T02:05:44.205718Z","steps":["trace[1899387434] 'process raft request' (duration: 352.055937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:44.205922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:43.853491Z","time spent":"352.327415ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:45.021718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.964915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T02:05:45.022057Z","caller":"traceutil/trace.go:171","msg":"trace[1721304508] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1360; }","duration":"441.13492ms","start":"2026-05-07T02:05:44.580902Z","end":"2026-05-07T02:05:45.022037Z","steps":["trace[1721304508] 'read index received' (duration: 99.022386ms)","trace[1721304508] 'applied index is now lower than readState.Index' (duration: 342.111244ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:45.022212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.321605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:45.022284Z","caller":"traceutil/trace.go:171","msg":"trace[1783879277] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1309; }","duration":"441.446028ms","start":"2026-05-07T02:05:44.580826Z","end":"2026-05-07T02:05:45.022272Z","steps":["trace[1783879277] 'agreement among raft nodes before linearized reading' (duration: 441.307245ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:45.022352Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:44.580792Z","time spent":"441.549441ms","remote":"127.0.0.1:42492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T02:05:45.022374Z","caller":"traceutil/trace.go:171","msg":"trace[357482608] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"757.111268ms","start":"2026-05-07T02:05:44.265239Z","end":"2026-05-07T02:05:45.02235Z","steps":["trace[357482608] 'process raft request' (duration: 756.724318ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:45.022542Z","caller":"traceutil/trace.go:171","msg":"trace[1231087662] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"757.112508ms","start":"2026-05-07T02:05:44.265395Z","end":"2026-05-07T02:05:45.022507Z","steps":["trace[1231087662] 'process raft request' (duration: 756.607855ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:45.022552Z","caller":"traceutil/trace.go:171","msg":"trace[603870532] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"757.525539ms","start":"2026-05-07T02:05:44.265001Z","end":"2026-05-07T02:05:45.022526Z","steps":["trace[603870532] 'process raft request' (duration: 108.676391ms)","trace[603870532] 'compare' (duration: 647.794261ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:45.022809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:44.265388Z","time spent":"757.254741ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:45.022552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:44.265197Z","time spent":"757.259822ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:05:45.022232Z","caller":"traceutil/trace.go:171","msg":"trace[1459527239] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"757.102167ms","start":"2026-05-07T02:05:44.265093Z","end":"2026-05-07T02:05:45.022195Z","steps":["trace[1459527239] 'process raft request' (duration: 756.81724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:45.022759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:44.264979Z","time spent":"757.682602ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:45.02326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:44.265076Z","time spent":"757.9782ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:05:45.462476Z","caller":"traceutil/trace.go:171","msg":"trace[1137417180] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"626.947478ms","start":"2026-05-07T02:05:44.835491Z","end":"2026-05-07T02:05:45.462438Z","steps":["trace[1137417180] 'process raft request' (duration: 557.337343ms)","trace[1137417180] 'compare' (duration: 69.360509ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:45.462693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:44.835467Z","time spent":"627.093202ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:45.733209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.639343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:45.73332Z","caller":"traceutil/trace.go:171","msg":"trace[852508897] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1310; }","duration":"152.91443ms","start":"2026-05-07T02:05:45.580377Z","end":"2026-05-07T02:05:45.733291Z","steps":["trace[852508897] 'range keys from in-memory index tree' (duration: 152.53323ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:46.025822Z","caller":"traceutil/trace.go:171","msg":"trace[237727838] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"166.323281ms","start":"2026-05-07T02:05:45.859472Z","end":"2026-05-07T02:05:46.025795Z","steps":["trace[237727838] 'process raft request' (duration: 166.144206ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:46.379665Z","caller":"traceutil/trace.go:171","msg":"trace[1850118749] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"167.621615ms","start":"2026-05-07T02:05:46.211999Z","end":"2026-05-07T02:05:46.379621Z","steps":["trace[1850118749] 'process raft request' (duration: 167.248844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:51.54667Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13313095697068046461,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-07T02:05:51.793673Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.023723556s","expected-duration":"1s"} {"level":"info","ts":"2026-05-07T02:05:51.794223Z","caller":"traceutil/trace.go:171","msg":"trace[1283939254] linearizableReadLoop","detail":"{readStateIndex:1411; appliedIndex:1410; }","duration":"748.049287ms","start":"2026-05-07T02:05:51.046147Z","end":"2026-05-07T02:05:51.794196Z","steps":["trace[1283939254] 'read index received' (duration: 747.726778ms)","trace[1283939254] 'applied index is now lower than readState.Index' (duration: 320.569µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:51.794426Z","caller":"traceutil/trace.go:171","msg":"trace[933036916] transaction","detail":"{read_only:false; response_revision:1355; number_of_response:1; }","duration":"1.024671591s","start":"2026-05-07T02:05:50.769734Z","end":"2026-05-07T02:05:51.794406Z","steps":["trace[933036916] 'process raft request' (duration: 1.024176188s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:51.794749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.099831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.89\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-05-07T02:05:51.79475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.217711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-07T02:05:51.7948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.038586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-07T02:05:51.794813Z","caller":"traceutil/trace.go:171","msg":"trace[2124899782] range","detail":"{range_begin:/registry/masterleases/199.204.45.89; range_end:; response_count:1; response_revision:1355; }","duration":"176.226874ms","start":"2026-05-07T02:05:51.61857Z","end":"2026-05-07T02:05:51.794797Z","steps":["trace[2124899782] 'agreement among raft nodes before linearized reading' (duration: 176.035028ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:51.794834Z","caller":"traceutil/trace.go:171","msg":"trace[1408411168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1355; }","duration":"746.340194ms","start":"2026-05-07T02:05:51.048471Z","end":"2026-05-07T02:05:51.794811Z","steps":["trace[1408411168] 'agreement among raft nodes before linearized reading' (duration: 746.086127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:51.794858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.338684ms","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-07T02:05:51.794878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:51.04846Z","time spent":"746.409836ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-07T02:05:51.794904Z","caller":"traceutil/trace.go:171","msg":"trace[145305967] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1355; }","duration":"746.428156ms","start":"2026-05-07T02:05:51.048461Z","end":"2026-05-07T02:05:51.794889Z","steps":["trace[145305967] 'agreement among raft nodes before linearized reading' (duration: 746.344814ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:51.794916Z","caller":"traceutil/trace.go:171","msg":"trace[1224332746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1355; }","duration":"746.123528ms","start":"2026-05-07T02:05:51.048717Z","end":"2026-05-07T02:05:51.794841Z","steps":["trace[1224332746] 'agreement among raft nodes before linearized reading' (duration: 745.961074ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:51.795035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:51.048663Z","time spent":"746.354534ms","remote":"127.0.0.1:42764","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-07T02:05:51.794762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.603861ms","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-07T02:05:51.795348Z","caller":"traceutil/trace.go:171","msg":"trace[108446958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1355; }","duration":"749.254588ms","start":"2026-05-07T02:05:51.046075Z","end":"2026-05-07T02:05:51.795329Z","steps":["trace[108446958] 'agreement among raft nodes before linearized reading' (duration: 748.541699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:51.795063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:50.769709Z","time spent":"1.024804504s","remote":"127.0.0.1:42670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13705,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:51.795412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:51.046057Z","time spent":"749.33113ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-07T02:05:51.794936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:51.048446Z","time spent":"746.482547ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-07T02:05:52.452077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:51.797227Z","time spent":"654.84412ms","remote":"127.0.0.1:42514","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-07T02:05:52.841806Z","caller":"traceutil/trace.go:171","msg":"trace[474844] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"1.04072734s","start":"2026-05-07T02:05:51.801047Z","end":"2026-05-07T02:05:52.841774Z","steps":["trace[474844] 'process raft request' (duration: 1.040540375s)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:52.841778Z","caller":"traceutil/trace.go:171","msg":"trace[2083712038] transaction","detail":"{read_only:false; response_revision:1356; number_of_response:1; }","duration":"1.040757781s","start":"2026-05-07T02:05:51.800965Z","end":"2026-05-07T02:05:52.841723Z","steps":["trace[2083712038] 'process raft request' (duration: 1.040397662s)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:52.841898Z","caller":"traceutil/trace.go:171","msg":"trace[35488988] transaction","detail":"{read_only:false; response_revision:1360; number_of_response:1; }","duration":"1.040785692s","start":"2026-05-07T02:05:51.801084Z","end":"2026-05-07T02:05:52.841869Z","steps":["trace[35488988] 'process raft request' (duration: 1.040681689s)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:52.842007Z","caller":"traceutil/trace.go:171","msg":"trace[1697040427] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"1.040938295s","start":"2026-05-07T02:05:51.801032Z","end":"2026-05-07T02:05:52.841971Z","steps":["trace[1697040427] 'process raft request' (duration: 1.040631967s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:52.842019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:51.80095Z","time spent":"1.040967697s","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:52.842164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:51.80101Z","time spent":"1.041073169s","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:05:52.842182Z","caller":"traceutil/trace.go:171","msg":"trace[1553652967] linearizableReadLoop","detail":"{readStateIndex:1419; appliedIndex:1411; }","duration":"739.677364ms","start":"2026-05-07T02:05:52.102485Z","end":"2026-05-07T02:05:52.842162Z","steps":["trace[1553652967] 'read index received' (duration: 329.595396ms)","trace[1553652967] 'applied index is now lower than readState.Index' (duration: 410.080888ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:52.842218Z","caller":"traceutil/trace.go:171","msg":"trace[1395205130] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"1.041141631s","start":"2026-05-07T02:05:51.801058Z","end":"2026-05-07T02:05:52.8422Z","steps":["trace[1395205130] 'process raft request' (duration: 1.040658839s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:52.842083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:51.801072Z","time spent":"1.040906274s","remote":"127.0.0.1:42642","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":925,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:52.84232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:51.801045Z","time spent":"1.041220683s","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:52.84245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:51.801041Z","time spent":"1.040848983s","remote":"127.0.0.1:42780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:05:52.842494Z","caller":"traceutil/trace.go:171","msg":"trace[1361865134] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"414.46011ms","start":"2026-05-07T02:05:52.428017Z","end":"2026-05-07T02:05:52.842477Z","steps":["trace[1361865134] 'process raft request' (duration: 414.0943ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:52.842274Z","caller":"traceutil/trace.go:171","msg":"trace[1454223060] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"1.040607307s","start":"2026-05-07T02:05:51.801655Z","end":"2026-05-07T02:05:52.842262Z","steps":["trace[1454223060] 'process raft request' (duration: 1.040168656s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:52.842765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:52.427992Z","time spent":"414.541152ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:52.842793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"740.30624ms","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":"warn","ts":"2026-05-07T02:05:52.84283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:51.801646Z","time spent":"1.041128851s","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:05:52.842864Z","caller":"traceutil/trace.go:171","msg":"trace[291434913] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1363; }","duration":"740.432253ms","start":"2026-05-07T02:05:52.102416Z","end":"2026-05-07T02:05:52.842848Z","steps":["trace[291434913] 'agreement among raft nodes before linearized reading' (duration: 740.136466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:52.842908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:52.102397Z","time spent":"740.499025ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-05-07T02:05:52.843024Z","caller":"traceutil/trace.go:171","msg":"trace[765403473] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"1.038145285s","start":"2026-05-07T02:05:51.804833Z","end":"2026-05-07T02:05:52.842979Z","steps":["trace[765403473] 'process raft request' (duration: 1.037195361s)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:52.843125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:51.804821Z","time spent":"1.038236777s","remote":"127.0.0.1:43040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10921,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:05:52.858193Z","caller":"traceutil/trace.go:171","msg":"trace[942559417] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"405.11396ms","start":"2026-05-07T02:05:52.453063Z","end":"2026-05-07T02:05:52.858177Z","steps":["trace[942559417] 'process raft request' (duration: 405.016558ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:52.858312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:52.453038Z","time spent":"405.213713ms","remote":"127.0.0.1:42514","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:05:52.858334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.116432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:05:52.858403Z","caller":"traceutil/trace.go:171","msg":"trace[1749924005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1364; }","duration":"278.248236ms","start":"2026-05-07T02:05:52.580139Z","end":"2026-05-07T02:05:52.858387Z","steps":["trace[1749924005] 'agreement among raft nodes before linearized reading' (duration: 278.138403ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:52.858411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"689.715599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-07T02:05:52.858496Z","caller":"traceutil/trace.go:171","msg":"trace[175722315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1364; }","duration":"689.798761ms","start":"2026-05-07T02:05:52.16864Z","end":"2026-05-07T02:05:52.858439Z","steps":["trace[175722315] 'agreement among raft nodes before linearized reading' (duration: 689.563855ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:52.858595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:52.168615Z","time spent":"689.954335ms","remote":"127.0.0.1:42764","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-07T02:05:52.858676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.322434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-07T02:05:52.858753Z","caller":"traceutil/trace.go:171","msg":"trace[1682957191] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1364; }","duration":"421.460617ms","start":"2026-05-07T02:05:52.437272Z","end":"2026-05-07T02:05:52.858732Z","steps":["trace[1682957191] 'agreement among raft nodes before linearized reading' (duration: 420.988925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:52.858797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:52.437247Z","time spent":"421.54059ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-07T02:05:52.858986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.005459ms","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-07T02:05:52.859029Z","caller":"traceutil/trace.go:171","msg":"trace[75640103] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1364; }","duration":"367.108481ms","start":"2026-05-07T02:05:52.49191Z","end":"2026-05-07T02:05:52.859018Z","steps":["trace[75640103] 'agreement among raft nodes before linearized reading' (duration: 366.311871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:05:52.859058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:05:52.491895Z","time spent":"367.154193ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":497,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-07T02:05:55.029963Z","caller":"traceutil/trace.go:171","msg":"trace[1205558408] linearizableReadLoop","detail":"{readStateIndex:1433; appliedIndex:1432; }","duration":"104.937246ms","start":"2026-05-07T02:05:54.925001Z","end":"2026-05-07T02:05:55.029938Z","steps":["trace[1205558408] 'read index received' (duration: 60.656727ms)","trace[1205558408] 'applied index is now lower than readState.Index' (duration: 44.279169ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:05:55.030001Z","caller":"traceutil/trace.go:171","msg":"trace[904552412] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"113.912275ms","start":"2026-05-07T02:05:54.91606Z","end":"2026-05-07T02:05:55.029972Z","steps":["trace[904552412] 'process raft request' (duration: 69.558834ms)","trace[904552412] 'compare' (duration: 44.182886ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:05:55.030291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.267245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-07T02:05:55.030475Z","caller":"traceutil/trace.go:171","msg":"trace[1693420180] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1376; }","duration":"105.498061ms","start":"2026-05-07T02:05:54.924954Z","end":"2026-05-07T02:05:55.030452Z","steps":["trace[1693420180] 'agreement among raft nodes before linearized reading' (duration: 105.12803ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:05:55.180596Z","caller":"traceutil/trace.go:171","msg":"trace[409615947] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"144.465953ms","start":"2026-05-07T02:05:55.036106Z","end":"2026-05-07T02:05:55.180572Z","steps":["trace[409615947] 'process raft request' (duration: 144.256788ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:06:07.007773Z","caller":"traceutil/trace.go:171","msg":"trace[450491066] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"112.634273ms","start":"2026-05-07T02:06:06.895117Z","end":"2026-05-07T02:06:07.007751Z","steps":["trace[450491066] 'process raft request' (duration: 112.481069ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:06:07.193991Z","caller":"traceutil/trace.go:171","msg":"trace[117124582] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"181.507091ms","start":"2026-05-07T02:06:07.012459Z","end":"2026-05-07T02:06:07.193966Z","steps":["trace[117124582] 'process raft request' (duration: 175.54712ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:06:07.194502Z","caller":"traceutil/trace.go:171","msg":"trace[1327224675] linearizableReadLoop","detail":"{readStateIndex:1491; appliedIndex:1487; }","duration":"114.069139ms","start":"2026-05-07T02:06:07.080411Z","end":"2026-05-07T02:06:07.19448Z","steps":["trace[1327224675] 'read index received' (duration: 107.592205ms)","trace[1327224675] 'applied index is now lower than readState.Index' (duration: 6.475594ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:06:07.194612Z","caller":"traceutil/trace.go:171","msg":"trace[1211422399] transaction","detail":"{read_only:false; response_revision:1430; number_of_response:1; }","duration":"182.095866ms","start":"2026-05-07T02:06:07.012503Z","end":"2026-05-07T02:06:07.194599Z","steps":["trace[1211422399] 'process raft request' (duration: 181.419209ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:06:07.194741Z","caller":"traceutil/trace.go:171","msg":"trace[2074919994] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"158.0649ms","start":"2026-05-07T02:06:07.036662Z","end":"2026-05-07T02:06:07.194727Z","steps":["trace[2074919994] 'process raft request' (duration: 157.767273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:07.194933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.513531ms","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-07T02:06:07.194988Z","caller":"traceutil/trace.go:171","msg":"trace[1138305102] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1432; }","duration":"114.623713ms","start":"2026-05-07T02:06:07.08035Z","end":"2026-05-07T02:06:07.194974Z","steps":["trace[1138305102] 'agreement among raft nodes before linearized reading' (duration: 114.454939ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:06:07.194521Z","caller":"traceutil/trace.go:171","msg":"trace[258436123] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"181.89264ms","start":"2026-05-07T02:06:07.012603Z","end":"2026-05-07T02:06:07.194496Z","steps":["trace[258436123] 'process raft request' (duration: 181.701156ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:06:14.155613Z","caller":"traceutil/trace.go:171","msg":"trace[1377127203] transaction","detail":"{read_only:false; response_revision:1463; number_of_response:1; }","duration":"362.983242ms","start":"2026-05-07T02:06:13.792601Z","end":"2026-05-07T02:06:14.155584Z","steps":["trace[1377127203] 'process raft request' (duration: 362.829838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:14.155756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:13.792578Z","time spent":"363.103584ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:06:43.206831Z","caller":"traceutil/trace.go:171","msg":"trace[144157283] transaction","detail":"{read_only:false; response_revision:1593; number_of_response:1; }","duration":"134.71864ms","start":"2026-05-07T02:06:43.072088Z","end":"2026-05-07T02:06:43.206806Z","steps":["trace[144157283] 'process raft request' (duration: 89.586871ms)","trace[144157283] 'compare' (duration: 44.997746ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:06:47.838484Z","caller":"traceutil/trace.go:171","msg":"trace[1042973054] linearizableReadLoop","detail":"{readStateIndex:1686; appliedIndex:1685; }","duration":"163.103387ms","start":"2026-05-07T02:06:47.675354Z","end":"2026-05-07T02:06:47.838458Z","steps":["trace[1042973054] 'read index received' (duration: 162.953533ms)","trace[1042973054] 'applied index is now lower than readState.Index' (duration: 149.084µs)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:06:47.838658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.280921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-07T02:06:47.838716Z","caller":"traceutil/trace.go:171","msg":"trace[217560635] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1618; }","duration":"163.355573ms","start":"2026-05-07T02:06:47.675346Z","end":"2026-05-07T02:06:47.838701Z","steps":["trace[217560635] 'agreement among raft nodes before linearized reading' (duration: 163.218349ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:06:47.838548Z","caller":"traceutil/trace.go:171","msg":"trace[403481752] transaction","detail":"{read_only:false; response_revision:1618; number_of_response:1; }","duration":"191.178005ms","start":"2026-05-07T02:06:47.647347Z","end":"2026-05-07T02:06:47.838525Z","steps":["trace[403481752] 'process raft request' (duration: 191.002331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:56.16603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.453158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T02:06:56.166158Z","caller":"traceutil/trace.go:171","msg":"trace[1688870308] linearizableReadLoop","detail":"{readStateIndex:1720; appliedIndex:1718; }","duration":"649.097313ms","start":"2026-05-07T02:06:55.51704Z","end":"2026-05-07T02:06:56.166137Z","steps":["trace[1688870308] 'read index received' (duration: 85.97769ms)","trace[1688870308] 'applied index is now lower than readState.Index' (duration: 563.118263ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:06:56.166409Z","caller":"traceutil/trace.go:171","msg":"trace[974777318] transaction","detail":"{read_only:false; response_revision:1651; number_of_response:1; }","duration":"649.993655ms","start":"2026-05-07T02:06:55.516391Z","end":"2026-05-07T02:06:56.166385Z","steps":["trace[974777318] 'process raft request' (duration: 228.095356ms)","trace[974777318] 'compare' (duration: 421.326725ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:06:56.166549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:55.516356Z","time spent":"650.110568ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:06:56.166683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.248294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-07T02:06:56.166661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.595256ms","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-07T02:06:56.166732Z","caller":"traceutil/trace.go:171","msg":"trace[583272597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1651; }","duration":"601.352797ms","start":"2026-05-07T02:06:55.565366Z","end":"2026-05-07T02:06:56.166719Z","steps":["trace[583272597] 'agreement among raft nodes before linearized reading' (duration: 601.143702ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:06:56.16675Z","caller":"traceutil/trace.go:171","msg":"trace[2013251687] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1651; }","duration":"649.73892ms","start":"2026-05-07T02:06:55.516992Z","end":"2026-05-07T02:06:56.166731Z","steps":["trace[2013251687] 'agreement among raft nodes before linearized reading' (duration: 649.466053ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:56.166774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:55.565339Z","time spent":"601.425918ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-07T02:06:56.166786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:55.516979Z","time spent":"649.801791ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-07T02:06:56.166811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.225746ms","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-07T02:06:56.166847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.879412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:06:56.166854Z","caller":"traceutil/trace.go:171","msg":"trace[1911966366] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1651; }","duration":"237.267997ms","start":"2026-05-07T02:06:55.929574Z","end":"2026-05-07T02:06:56.166842Z","steps":["trace[1911966366] 'agreement among raft nodes before linearized reading' (duration: 237.122084ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:06:56.166883Z","caller":"traceutil/trace.go:171","msg":"trace[1775779929] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1651; }","duration":"586.947273ms","start":"2026-05-07T02:06:55.579927Z","end":"2026-05-07T02:06:56.166874Z","steps":["trace[1775779929] 'agreement among raft nodes before linearized reading' (duration: 586.898702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:56.166904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:55.579908Z","time spent":"586.992005ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-07T02:06:56.166979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.006978ms","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-07T02:06:56.1671Z","caller":"traceutil/trace.go:171","msg":"trace[460967292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1651; }","duration":"433.341726ms","start":"2026-05-07T02:06:55.733737Z","end":"2026-05-07T02:06:56.167079Z","steps":["trace[460967292] 'agreement among raft nodes before linearized reading' (duration: 432.783052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:56.167179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:55.733703Z","time spent":"433.461559ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-07T02:06:56.691678Z","caller":"traceutil/trace.go:171","msg":"trace[1026358616] transaction","detail":"{read_only:false; response_revision:1652; number_of_response:1; }","duration":"519.524703ms","start":"2026-05-07T02:06:56.172122Z","end":"2026-05-07T02:06:56.691647Z","steps":["trace[1026358616] 'process raft request' (duration: 519.316468ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:56.691842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:56.172102Z","time spent":"519.654917ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:06:56.951403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.160584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T02:06:56.951528Z","caller":"traceutil/trace.go:171","msg":"trace[22317900] transaction","detail":"{read_only:false; response_revision:1653; number_of_response:1; }","duration":"778.92447ms","start":"2026-05-07T02:06:56.172583Z","end":"2026-05-07T02:06:56.951508Z","steps":["trace[22317900] 'process raft request' (duration: 673.614663ms)","trace[22317900] 'compare' (duration: 104.998039ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:06:56.951575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:56.172577Z","time spent":"778.976991ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:06:56.952073Z","caller":"traceutil/trace.go:171","msg":"trace[1846828015] transaction","detail":"{read_only:false; response_revision:1655; number_of_response:1; }","duration":"778.50308ms","start":"2026-05-07T02:06:56.173558Z","end":"2026-05-07T02:06:56.952061Z","steps":["trace[1846828015] 'process raft request' (duration: 778.334095ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:56.952129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:56.173521Z","time spent":"778.581132ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":516,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:06:56.952243Z","caller":"traceutil/trace.go:171","msg":"trace[2027174024] transaction","detail":"{read_only:false; response_revision:1654; number_of_response:1; }","duration":"779.114316ms","start":"2026-05-07T02:06:56.173122Z","end":"2026-05-07T02:06:56.952236Z","steps":["trace[2027174024] 'process raft request' (duration: 778.360386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:06:56.952284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:56.173116Z","time spent":"779.148527ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-07T02:06:56.952349Z","caller":"traceutil/trace.go:171","msg":"trace[821389356] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"572.342366ms","start":"2026-05-07T02:06:56.379899Z","end":"2026-05-07T02:06:56.952241Z","steps":["trace[821389356] 'process raft request' (duration: 572.0915ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:06:56.952351Z","caller":"traceutil/trace.go:171","msg":"trace[782620635] linearizableReadLoop","detail":"{readStateIndex:1725; appliedIndex:1720; }","duration":"370.999784ms","start":"2026-05-07T02:06:56.581318Z","end":"2026-05-07T02:06:56.952317Z","steps":["trace[782620635] 'read index received' (duration: 110.166121ms)","trace[782620635] 'applied index is now lower than readState.Index' (duration: 260.829063ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:06:56.952567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:56.379864Z","time spent":"572.585063ms","remote":"127.0.0.1:42764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-07T02:06:56.993252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.909427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:06:56.993357Z","caller":"traceutil/trace.go:171","msg":"trace[1655114321] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1656; }","duration":"412.076131ms","start":"2026-05-07T02:06:56.581258Z","end":"2026-05-07T02:06:56.993334Z","steps":["trace[1655114321] 'agreement among raft nodes before linearized reading' (duration: 371.164409ms)","trace[1655114321] 'range keys from in-memory index tree' (duration: 40.761008ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:06:56.993407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-07T02:06:56.581235Z","time spent":"412.159933ms","remote":"127.0.0.1:42506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-07T02:07:09.571977Z","caller":"traceutil/trace.go:171","msg":"trace[27763271] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"127.620451ms","start":"2026-05-07T02:07:09.444319Z","end":"2026-05-07T02:07:09.57194Z","steps":["trace[27763271] 'process raft request' (duration: 127.104108ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:07:10.526877Z","caller":"traceutil/trace.go:171","msg":"trace[1122763844] transaction","detail":"{read_only:false; response_revision:1717; number_of_response:1; }","duration":"146.112057ms","start":"2026-05-07T02:07:10.380735Z","end":"2026-05-07T02:07:10.526847Z","steps":["trace[1122763844] 'process raft request' (duration: 98.390473ms)","trace[1122763844] 'compare' (duration: 47.600471ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:07:13.873084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.546799ms","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-07T02:07:13.873229Z","caller":"traceutil/trace.go:171","msg":"trace[1788850795] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1732; }","duration":"104.801075ms","start":"2026-05-07T02:07:13.768384Z","end":"2026-05-07T02:07:13.873186Z","steps":["trace[1788850795] 'range keys from in-memory index tree' (duration: 102.366084ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:07:28.573949Z","caller":"traceutil/trace.go:171","msg":"trace[516095607] transaction","detail":"{read_only:false; response_revision:1799; number_of_response:1; }","duration":"103.471702ms","start":"2026-05-07T02:07:28.470453Z","end":"2026-05-07T02:07:28.573924Z","steps":["trace[516095607] 'process raft request' (duration: 103.335417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:07:29.593748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.50914ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-07T02:07:29.593917Z","caller":"traceutil/trace.go:171","msg":"trace[894762765] linearizableReadLoop","detail":"{readStateIndex:1880; appliedIndex:1878; }","duration":"181.88108ms","start":"2026-05-07T02:07:29.41202Z","end":"2026-05-07T02:07:29.593901Z","steps":["trace[894762765] 'read index received' (duration: 67.497143ms)","trace[894762765] 'applied index is now lower than readState.Index' (duration: 114.382947ms)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:07:29.594023Z","caller":"traceutil/trace.go:171","msg":"trace[450372070] transaction","detail":"{read_only:false; response_revision:1804; number_of_response:1; }","duration":"186.320553ms","start":"2026-05-07T02:07:29.407691Z","end":"2026-05-07T02:07:29.594012Z","steps":["trace[450372070] 'process raft request' (duration: 186.158639ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:07:29.594207Z","caller":"traceutil/trace.go:171","msg":"trace[56160576] transaction","detail":"{read_only:false; response_revision:1803; number_of_response:1; }","duration":"186.903988ms","start":"2026-05-07T02:07:29.407217Z","end":"2026-05-07T02:07:29.594121Z","steps":["trace[56160576] 'process raft request' (duration: 71.970027ms)","trace[56160576] 'compare' (duration: 114.342415ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:07:29.594368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.342572ms","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-07T02:07:29.594412Z","caller":"traceutil/trace.go:171","msg":"trace[864134052] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1804; }","duration":"182.405244ms","start":"2026-05-07T02:07:29.411994Z","end":"2026-05-07T02:07:29.594399Z","steps":["trace[864134052] 'agreement among raft nodes before linearized reading' (duration: 182.27817ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:08:03.491032Z","caller":"traceutil/trace.go:171","msg":"trace[358070722] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"136.362152ms","start":"2026-05-07T02:08:03.354644Z","end":"2026-05-07T02:08:03.491007Z","steps":["trace[358070722] 'process raft request' (duration: 136.209438ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:08:03.794648Z","caller":"traceutil/trace.go:171","msg":"trace[411412846] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"127.475777ms","start":"2026-05-07T02:08:03.66715Z","end":"2026-05-07T02:08:03.794625Z","steps":["trace[411412846] 'process raft request' (duration: 86.112713ms)","trace[411412846] 'compare' (duration: 41.165409ms)"],"step_count":2} {"level":"warn","ts":"2026-05-07T02:08:59.178819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.37644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-07T02:08:59.179506Z","caller":"traceutil/trace.go:171","msg":"trace[1060799285] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2235; }","duration":"125.185491ms","start":"2026-05-07T02:08:59.0543Z","end":"2026-05-07T02:08:59.179486Z","steps":["trace[1060799285] 'range keys from in-memory index tree' (duration: 124.254417ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:09:09.137948Z","caller":"traceutil/trace.go:171","msg":"trace[1706510451] linearizableReadLoop","detail":"{readStateIndex:2387; appliedIndex:2386; }","duration":"158.35487ms","start":"2026-05-07T02:09:08.979565Z","end":"2026-05-07T02:09:09.13792Z","steps":["trace[1706510451] 'read index received' (duration: 158.214386ms)","trace[1706510451] 'applied index is now lower than readState.Index' (duration: 138.974µs)"],"step_count":2} {"level":"info","ts":"2026-05-07T02:09:09.138021Z","caller":"traceutil/trace.go:171","msg":"trace[1759411507] transaction","detail":"{read_only:false; response_revision:2290; number_of_response:1; }","duration":"167.607805ms","start":"2026-05-07T02:09:08.970367Z","end":"2026-05-07T02:09:09.137974Z","steps":["trace[1759411507] 'process raft request' (duration: 167.375058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-07T02:09:09.138185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.578317ms","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-07T02:09:09.138227Z","caller":"traceutil/trace.go:171","msg":"trace[1173636320] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2290; }","duration":"158.70502ms","start":"2026-05-07T02:09:08.979511Z","end":"2026-05-07T02:09:09.138216Z","steps":["trace[1173636320] 'agreement among raft nodes before linearized reading' (duration: 158.514935ms)"],"step_count":1} {"level":"info","ts":"2026-05-07T02:09:09.940294Z","caller":"traceutil/trace.go:171","msg":"trace[1724695265] transaction","detail":"{read_only:false; response_revision:2292; number_of_response:1; }","duration":"294.227452ms","start":"2026-05-07T02:09:09.646041Z","end":"2026-05-07T02:09:09.940268Z","steps":["trace[1724695265] 'process raft request' (duration: 294.024066ms)"],"step_count":1}