{"level":"warn","ts":"2026-09-18T00:01:03.147472Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T00:01:03.147568Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.205: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://162.253.55.205:2380","--initial-cluster=instance=https://162.253.55.205:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.205:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.205: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-09-18T00:01:03.147654Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-18T00:01:03.147666Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.205:2380"]} {"level":"info","ts":"2026-09-18T00:01:03.147691Z","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-09-18T00:01:03.14836Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.205:2379"]} {"level":"info","ts":"2026-09-18T00:01:03.148442Z","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://162.253.55.205:2380"],"listen-peer-urls":["https://162.253.55.205:2380"],"advertise-client-urls":["https://162.253.55.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.205: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-09-18T00:01:03.154015Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.343657ms"} {"level":"info","ts":"2026-09-18T00:01:03.163913Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f1f73ead33f822e","cluster-id":"eaba2551c55d0fa5"} {"level":"info","ts":"2026-09-18T00:01:03.163972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e switched to configuration voters=()"} {"level":"info","ts":"2026-09-18T00:01:03.163996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e became follower at term 0"} {"level":"info","ts":"2026-09-18T00:01:03.164898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f1f73ead33f822e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-18T00:01:03.164911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e became follower at term 1"} {"level":"info","ts":"2026-09-18T00:01:03.16495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e switched to configuration voters=(6854324610284880430)"} {"level":"warn","ts":"2026-09-18T00:01:03.174481Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-18T00:01:03.175834Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-18T00:01:03.177167Z","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-09-18T00:01:03.180126Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f1f73ead33f822e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-18T00:01:03.180293Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f1f73ead33f822e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-18T00:01:03.180469Z","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-09-18T00:01:03.180616Z","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-09-18T00:01:03.180717Z","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-09-18T00:01:03.181574Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e switched to configuration voters=(6854324610284880430)"} {"level":"info","ts":"2026-09-18T00:01:03.182108Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"eaba2551c55d0fa5","local-member-id":"5f1f73ead33f822e","added-peer-id":"5f1f73ead33f822e","added-peer-peer-urls":["https://162.253.55.205:2380"]} {"level":"info","ts":"2026-09-18T00:01:03.183797Z","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-09-18T00:01:03.183868Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.205:2380"} {"level":"info","ts":"2026-09-18T00:01:03.184062Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f1f73ead33f822e","initial-advertise-peer-urls":["https://162.253.55.205:2380"],"listen-peer-urls":["https://162.253.55.205:2380"],"advertise-client-urls":["https://162.253.55.205:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.205:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-18T00:01:03.184116Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-18T00:01:03.183895Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.205:2380"} {"level":"info","ts":"2026-09-18T00:01:04.065879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e is starting a new election at term 1"} {"level":"info","ts":"2026-09-18T00:01:04.06593Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e became pre-candidate at term 1"} {"level":"info","ts":"2026-09-18T00:01:04.06596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e received MsgPreVoteResp from 5f1f73ead33f822e at term 1"} {"level":"info","ts":"2026-09-18T00:01:04.065974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e became candidate at term 2"} {"level":"info","ts":"2026-09-18T00:01:04.065979Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e received MsgVoteResp from 5f1f73ead33f822e at term 2"} {"level":"info","ts":"2026-09-18T00:01:04.065988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f1f73ead33f822e became leader at term 2"} {"level":"info","ts":"2026-09-18T00:01:04.065996Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f1f73ead33f822e elected leader 5f1f73ead33f822e at term 2"} {"level":"info","ts":"2026-09-18T00:01:04.067424Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T00:01:04.068183Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f1f73ead33f822e","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.205:2379]}","request-path":"/0/members/5f1f73ead33f822e/attributes","cluster-id":"eaba2551c55d0fa5","publish-timeout":"7s"} {"level":"info","ts":"2026-09-18T00:01:04.068366Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T00:01:04.068411Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-18T00:01:04.068603Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-18T00:01:04.068758Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-18T00:01:04.06882Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"eaba2551c55d0fa5","local-member-id":"5f1f73ead33f822e","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T00:01:04.068918Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T00:01:04.068952Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-18T00:01:04.073358Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-18T00:01:04.07387Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.205:2379"} {"level":"info","ts":"2026-09-18T00:01:19.499177Z","caller":"traceutil/trace.go:171","msg":"trace[810783950] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"181.071942ms","start":"2026-09-18T00:01:19.318074Z","end":"2026-09-18T00:01:19.499146Z","steps":["trace[810783950] 'read index received' (duration: 180.864198ms)","trace[810783950] 'applied index is now lower than readState.Index' (duration: 206.594µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:01:19.499269Z","caller":"traceutil/trace.go:171","msg":"trace[1577744388] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"248.390403ms","start":"2026-09-18T00:01:19.25086Z","end":"2026-09-18T00:01:19.49925Z","steps":["trace[1577744388] 'process raft request' (duration: 248.073878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:01:19.499376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.468863ms","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-09-18T00:01:19.499467Z","caller":"traceutil/trace.go:171","msg":"trace[1966953202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:407; }","duration":"137.565825ms","start":"2026-09-18T00:01:19.361885Z","end":"2026-09-18T00:01:19.499451Z","steps":["trace[1966953202] 'agreement among raft nodes before linearized reading' (duration: 137.423472ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:01:19.499376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.229354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T00:01:19.499491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.728766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-18T00:01:19.499543Z","caller":"traceutil/trace.go:171","msg":"trace[1284869704] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:407; }","duration":"133.790008ms","start":"2026-09-18T00:01:19.365721Z","end":"2026-09-18T00:01:19.499511Z","steps":["trace[1284869704] 'agreement among raft nodes before linearized reading' (duration: 133.712817ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:01:19.499556Z","caller":"traceutil/trace.go:171","msg":"trace[2056593938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"181.484069ms","start":"2026-09-18T00:01:19.318025Z","end":"2026-09-18T00:01:19.499509Z","steps":["trace[2056593938] 'agreement among raft nodes before linearized reading' (duration: 181.241015ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:01:19.815267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.052472ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T00:01:19.815401Z","caller":"traceutil/trace.go:171","msg":"trace[1545818604] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"310.633694ms","start":"2026-09-18T00:01:19.504747Z","end":"2026-09-18T00:01:19.81538Z","steps":["trace[1545818604] 'read index received' (duration: 193.552464ms)","trace[1545818604] 'applied index is now lower than readState.Index' (duration: 117.07938ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:01:19.815557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.800678ms","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-09-18T00:01:19.815603Z","caller":"traceutil/trace.go:171","msg":"trace[756210716] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:408; }","duration":"310.875469ms","start":"2026-09-18T00:01:19.504715Z","end":"2026-09-18T00:01:19.81559Z","steps":["trace[756210716] 'agreement among raft nodes before linearized reading' (duration: 310.764677ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:01:19.815636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:01:19.504698Z","time spent":"310.93009ms","remote":"127.0.0.1:42150","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-09-18T00:01:19.815713Z","caller":"traceutil/trace.go:171","msg":"trace[1510633877] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"310.951491ms","start":"2026-09-18T00:01:19.504723Z","end":"2026-09-18T00:01:19.815675Z","steps":["trace[1510633877] 'process raft request' (duration: 193.595535ms)","trace[1510633877] 'compare' (duration: 115.890089ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:01:19.81584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:01:19.504694Z","time spent":"311.087262ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:01:19.819987Z","caller":"traceutil/trace.go:171","msg":"trace[1783156346] transaction","detail":"{read_only:false; response_revision:409; number_of_response:1; }","duration":"315.134565ms","start":"2026-09-18T00:01:19.504837Z","end":"2026-09-18T00:01:19.819972Z","steps":["trace[1783156346] 'process raft request' (duration: 315.027883ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:01:19.820096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:01:19.504799Z","time spent":"315.242627ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:01:54.425022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.780042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:01:54.425178Z","caller":"traceutil/trace.go:171","msg":"trace[98591768] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"106.970457ms","start":"2026-09-18T00:01:54.318163Z","end":"2026-09-18T00:01:54.425134Z","steps":["trace[98591768] 'range keys from in-memory index tree' (duration: 106.65685ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:00.83185Z","caller":"traceutil/trace.go:171","msg":"trace[1363462610] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"278.987495ms","start":"2026-09-18T00:02:00.552832Z","end":"2026-09-18T00:02:00.83182Z","steps":["trace[1363462610] 'process raft request' (duration: 278.38924ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:01.803776Z","caller":"traceutil/trace.go:171","msg":"trace[1743464863] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"458.227259ms","start":"2026-09-18T00:02:01.345524Z","end":"2026-09-18T00:02:01.803751Z","steps":["trace[1743464863] 'read index received' (duration: 458.056225ms)","trace[1743464863] 'applied index is now lower than readState.Index' (duration: 170.194µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:01.804037Z","caller":"traceutil/trace.go:171","msg":"trace[273846780] transaction","detail":"{read_only:false; response_revision:663; number_of_response:1; }","duration":"898.144945ms","start":"2026-09-18T00:02:00.905881Z","end":"2026-09-18T00:02:01.804026Z","steps":["trace[273846780] 'process raft request' (duration: 897.759426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:01.80412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:00.905855Z","time spent":"898.213256ms","remote":"127.0.0.1:42392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T00:02:01.804117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.604178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:02:01.804178Z","caller":"traceutil/trace.go:171","msg":"trace[1799177908] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:663; }","duration":"458.73903ms","start":"2026-09-18T00:02:01.345421Z","end":"2026-09-18T00:02:01.80416Z","steps":["trace[1799177908] 'agreement among raft nodes before linearized reading' (duration: 458.617757ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:01.80422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:01.345397Z","time spent":"458.812642ms","remote":"127.0.0.1:41870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T00:02:01.804666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.72134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wvxvm\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-18T00:02:01.804793Z","caller":"traceutil/trace.go:171","msg":"trace[796783665] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wvxvm; range_end:; response_count:1; response_revision:663; }","duration":"447.872574ms","start":"2026-09-18T00:02:01.356899Z","end":"2026-09-18T00:02:01.804772Z","steps":["trace[796783665] 'agreement among raft nodes before linearized reading' (duration: 447.083275ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:01.804877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:01.356853Z","time spent":"448.010047ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-wvxvm\" "} {"level":"warn","ts":"2026-09-18T00:02:02.533181Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9380611759436382846,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T00:02:03.034058Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9380611759436382846,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T00:02:03.160871Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.336952382s","expected-duration":"1s"} {"level":"warn","ts":"2026-09-18T00:02:03.647024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.827712626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:02:03.647109Z","caller":"traceutil/trace.go:171","msg":"trace[1742546805] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:663; }","duration":"1.827843309s","start":"2026-09-18T00:02:01.819243Z","end":"2026-09-18T00:02:03.647086Z","steps":["trace[1742546805] 'range keys from in-memory index tree' (duration: 1.827605613s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:03.647145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:01.819224Z","time spent":"1.827912121s","remote":"127.0.0.1:42110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-09-18T00:02:03.647344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.980971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T00:02:03.647653Z","caller":"traceutil/trace.go:171","msg":"trace[1965320985] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"1.823897726s","start":"2026-09-18T00:02:01.823722Z","end":"2026-09-18T00:02:03.64762Z","steps":["trace[1965320985] 'process raft request' (duration: 1.337467874s)","trace[1965320985] 'compare' (duration: 485.780346ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:02:03.647832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:01.823683Z","time spent":"1.82403975s","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:02:03.716772Z","caller":"traceutil/trace.go:171","msg":"trace[1138996216] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:686; }","duration":"1.684427191s","start":"2026-09-18T00:02:02.032308Z","end":"2026-09-18T00:02:03.716735Z","steps":["trace[1138996216] 'read index received' (duration: 1.128894628s)","trace[1138996216] 'applied index is now lower than readState.Index' (duration: 555.530773ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:02:03.717014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.398665341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:02:03.717054Z","caller":"traceutil/trace.go:171","msg":"trace[497504767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:664; }","duration":"1.398762443s","start":"2026-09-18T00:02:02.318281Z","end":"2026-09-18T00:02:03.717043Z","steps":["trace[497504767] 'agreement among raft nodes before linearized reading' (duration: 1.398685552s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:03.717085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:02.31823Z","time spent":"1.398848245s","remote":"127.0.0.1:41884","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T00:02:03.717131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.684796469s","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-09-18T00:02:03.717216Z","caller":"traceutil/trace.go:171","msg":"trace[279631183] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:664; }","duration":"1.684934753s","start":"2026-09-18T00:02:02.03226Z","end":"2026-09-18T00:02:03.717195Z","steps":["trace[279631183] 'agreement among raft nodes before linearized reading' (duration: 1.684612635s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:03.717289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:02.032242Z","time spent":"1.685016416s","remote":"127.0.0.1:42150","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-09-18T00:02:03.717458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.448640831s","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-09-18T00:02:03.717498Z","caller":"traceutil/trace.go:171","msg":"trace[386454093] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:664; }","duration":"1.448752894s","start":"2026-09-18T00:02:02.268735Z","end":"2026-09-18T00:02:03.717488Z","steps":["trace[386454093] 'agreement among raft nodes before linearized reading' (duration: 1.44858822s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:03.717523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:02.268637Z","time spent":"1.448880176s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-18T00:02:03.717622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.669910677s","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-09-18T00:02:03.717635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.652678077s","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-09-18T00:02:03.717668Z","caller":"traceutil/trace.go:171","msg":"trace[631907569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:664; }","duration":"1.652710928s","start":"2026-09-18T00:02:02.064949Z","end":"2026-09-18T00:02:03.71766Z","steps":["trace[631907569] 'agreement among raft nodes before linearized reading' (duration: 1.65239564s)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:03.717668Z","caller":"traceutil/trace.go:171","msg":"trace[222189613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:664; }","duration":"1.669984238s","start":"2026-09-18T00:02:02.047672Z","end":"2026-09-18T00:02:03.717657Z","steps":["trace[222189613] 'agreement among raft nodes before linearized reading' (duration: 1.6692553s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:03.71769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:02.06488Z","time spent":"1.652804469s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-18T00:02:03.717705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:02.047659Z","time spent":"1.67003683s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-09-18T00:02:06.008704Z","caller":"traceutil/trace.go:171","msg":"trace[245491742] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"276.60257ms","start":"2026-09-18T00:02:05.732065Z","end":"2026-09-18T00:02:06.008668Z","steps":["trace[245491742] 'process raft request' (duration: 276.410976ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:06.236263Z","caller":"traceutil/trace.go:171","msg":"trace[1196326481] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"504.164771ms","start":"2026-09-18T00:02:05.732068Z","end":"2026-09-18T00:02:06.236233Z","steps":["trace[1196326481] 'process raft request' (duration: 448.042045ms)","trace[1196326481] 'compare' (duration: 55.947111ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:02:06.236432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:05.732042Z","time spent":"504.301614ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:02:06.236635Z","caller":"traceutil/trace.go:171","msg":"trace[1797039016] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"504.53604ms","start":"2026-09-18T00:02:05.732081Z","end":"2026-09-18T00:02:06.236617Z","steps":["trace[1797039016] 'process raft request' (duration: 504.0975ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:06.23673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:05.732067Z","time spent":"504.612642ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:02:06.236745Z","caller":"traceutil/trace.go:171","msg":"trace[1133509352] linearizableReadLoop","detail":"{readStateIndex:733; appliedIndex:730; }","duration":"500.498024ms","start":"2026-09-18T00:02:05.73623Z","end":"2026-09-18T00:02:06.236728Z","steps":["trace[1133509352] 'read index received' (duration: 272.348569ms)","trace[1133509352] 'applied index is now lower than readState.Index' (duration: 228.148465ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:06.236753Z","caller":"traceutil/trace.go:171","msg":"trace[551248541] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"469.551858ms","start":"2026-09-18T00:02:05.767177Z","end":"2026-09-18T00:02:06.236729Z","steps":["trace[551248541] 'process raft request' (duration: 469.247971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:06.236851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:05.767159Z","time spent":"469.64587ms","remote":"127.0.0.1:41940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8270,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-18T00:02:06.236896Z","caller":"traceutil/trace.go:171","msg":"trace[1967108368] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"270.611847ms","start":"2026-09-18T00:02:05.96625Z","end":"2026-09-18T00:02:06.236862Z","steps":["trace[1967108368] 'process raft request' (duration: 270.415583ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:06.236926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.683018ms","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-09-18T00:02:06.236971Z","caller":"traceutil/trace.go:171","msg":"trace[1382104401] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:711; }","duration":"500.752429ms","start":"2026-09-18T00:02:05.736205Z","end":"2026-09-18T00:02:06.236957Z","steps":["trace[1382104401] 'agreement among raft nodes before linearized reading' (duration: 500.583415ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:06.237015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:05.736195Z","time spent":"500.80888ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-18T00:02:06.390326Z","caller":"traceutil/trace.go:171","msg":"trace[2114692309] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"222.038077ms","start":"2026-09-18T00:02:06.168258Z","end":"2026-09-18T00:02:06.390296Z","steps":["trace[2114692309] 'process raft request' (duration: 191.14081ms)","trace[2114692309] 'compare' (duration: 30.758044ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:06.405298Z","caller":"traceutil/trace.go:171","msg":"trace[1474304863] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"163.494511ms","start":"2026-09-18T00:02:06.241754Z","end":"2026-09-18T00:02:06.405248Z","steps":["trace[1474304863] 'process raft request' (duration: 162.938548ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:06.405354Z","caller":"traceutil/trace.go:171","msg":"trace[1871034209] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"159.963566ms","start":"2026-09-18T00:02:06.24536Z","end":"2026-09-18T00:02:06.405324Z","steps":["trace[1871034209] 'process raft request' (duration: 159.540907ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:06.846711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.037106ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T00:02:06.846962Z","caller":"traceutil/trace.go:171","msg":"trace[584441572] transaction","detail":"{read_only:false; response_revision:716; number_of_response:1; }","duration":"433.432117ms","start":"2026-09-18T00:02:06.413499Z","end":"2026-09-18T00:02:06.846931Z","steps":["trace[584441572] 'process raft request' (duration: 269.087864ms)","trace[584441572] 'compare' (duration: 163.891371ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:02:06.847052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:06.41346Z","time spent":"433.550079ms","remote":"127.0.0.1:41940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4156,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:02:06.867955Z","caller":"traceutil/trace.go:171","msg":"trace[287443344] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:739; }","duration":"302.236226ms","start":"2026-09-18T00:02:06.565699Z","end":"2026-09-18T00:02:06.867935Z","steps":["trace[287443344] 'read index received' (duration: 116.6744ms)","trace[287443344] 'applied index is now lower than readState.Index' (duration: 185.560816ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:06.868045Z","caller":"traceutil/trace.go:171","msg":"trace[600067072] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"436.640974ms","start":"2026-09-18T00:02:06.431393Z","end":"2026-09-18T00:02:06.868034Z","steps":["trace[600067072] 'process raft request' (duration: 436.47993ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:06.868115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:06.431377Z","time spent":"436.688235ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17775,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:02:06.868111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.41008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:02:06.868163Z","caller":"traceutil/trace.go:171","msg":"trace[1919479669] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:718; }","duration":"302.465151ms","start":"2026-09-18T00:02:06.565681Z","end":"2026-09-18T00:02:06.868147Z","steps":["trace[1919479669] 'agreement among raft nodes before linearized reading' (duration: 302.385569ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:06.868209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:06.565632Z","time spent":"302.566103ms","remote":"127.0.0.1:41940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-09-18T00:02:06.868267Z","caller":"traceutil/trace.go:171","msg":"trace[1915661479] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"452.754813ms","start":"2026-09-18T00:02:06.415431Z","end":"2026-09-18T00:02:06.868186Z","steps":["trace[1915661479] 'process raft request' (duration: 452.310022ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:06.8684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:06.415407Z","time spent":"452.929987ms","remote":"127.0.0.1:41940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:02:07.235545Z","caller":"traceutil/trace.go:171","msg":"trace[1298801766] transaction","detail":"{read_only:false; response_revision:719; number_of_response:1; }","duration":"380.19308ms","start":"2026-09-18T00:02:06.855327Z","end":"2026-09-18T00:02:07.23552Z","steps":["trace[1298801766] 'process raft request' (duration: 380.039377ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:07.235628Z","caller":"traceutil/trace.go:171","msg":"trace[1670236369] linearizableReadLoop","detail":"{readStateIndex:743; appliedIndex:743; }","duration":"367.583455ms","start":"2026-09-18T00:02:06.868007Z","end":"2026-09-18T00:02:07.235591Z","steps":["trace[1670236369] 'read index received' (duration: 367.569374ms)","trace[1670236369] 'applied index is now lower than readState.Index' (duration: 12.691µs)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:02:07.235698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.892953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:02:07.235758Z","caller":"traceutil/trace.go:171","msg":"trace[1428109619] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:719; }","duration":"470.994945ms","start":"2026-09-18T00:02:06.764744Z","end":"2026-09-18T00:02:07.235739Z","steps":["trace[1428109619] 'agreement among raft nodes before linearized reading' (duration: 470.883493ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:07.235775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.17236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-09-18T00:02:07.235808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:06.764722Z","time spent":"471.073958ms","remote":"127.0.0.1:41940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"info","ts":"2026-09-18T00:02:07.235817Z","caller":"traceutil/trace.go:171","msg":"trace[583167359] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:719; }","duration":"639.280184ms","start":"2026-09-18T00:02:06.596523Z","end":"2026-09-18T00:02:07.235803Z","steps":["trace[583167359] 'agreement among raft nodes before linearized reading' (duration: 639.189851ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:07.235866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:06.596477Z","time spent":"639.372956ms","remote":"127.0.0.1:42326","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-09-18T00:02:07.235799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:06.855294Z","time spent":"380.368215ms","remote":"127.0.0.1:41940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:02:07.311144Z","caller":"traceutil/trace.go:171","msg":"trace[1881995558] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"416.638058ms","start":"2026-09-18T00:02:06.894484Z","end":"2026-09-18T00:02:07.311122Z","steps":["trace[1881995558] 'process raft request' (duration: 416.465464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:07.3113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:06.894432Z","time spent":"416.775001ms","remote":"127.0.0.1:41940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4165,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:02:07.311624Z","caller":"traceutil/trace.go:171","msg":"trace[824586641] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"338.91938ms","start":"2026-09-18T00:02:06.972643Z","end":"2026-09-18T00:02:07.311562Z","steps":["trace[824586641] 'process raft request' (duration: 338.443707ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:07.311731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.417651ms","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-09-18T00:02:07.311786Z","caller":"traceutil/trace.go:171","msg":"trace[1252402007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:721; }","duration":"134.503103ms","start":"2026-09-18T00:02:07.177269Z","end":"2026-09-18T00:02:07.311772Z","steps":["trace[1252402007] 'agreement among raft nodes before linearized reading' (duration: 134.329209ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:07.311896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:06.972619Z","time spent":"339.136355ms","remote":"127.0.0.1:41940","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T00:02:07.311641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.641128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-09-18T00:02:07.312418Z","caller":"traceutil/trace.go:171","msg":"trace[592881754] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:721; }","duration":"147.444217ms","start":"2026-09-18T00:02:07.164941Z","end":"2026-09-18T00:02:07.312385Z","steps":["trace[592881754] 'agreement among raft nodes before linearized reading' (duration: 146.302409ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:10.381927Z","caller":"traceutil/trace.go:171","msg":"trace[961910776] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"124.653828ms","start":"2026-09-18T00:02:10.257252Z","end":"2026-09-18T00:02:10.381906Z","steps":["trace[961910776] 'process raft request' (duration: 124.481883ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:10.76897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.931213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T00:02:10.769178Z","caller":"traceutil/trace.go:171","msg":"trace[996651511] linearizableReadLoop","detail":"{readStateIndex:782; appliedIndex:779; }","duration":"495.016589ms","start":"2026-09-18T00:02:10.274141Z","end":"2026-09-18T00:02:10.769157Z","steps":["trace[996651511] 'read index received' (duration: 107.618929ms)","trace[996651511] 'applied index is now lower than readState.Index' (duration: 387.39662ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:10.769271Z","caller":"traceutil/trace.go:171","msg":"trace[448981503] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"511.919443ms","start":"2026-09-18T00:02:10.257336Z","end":"2026-09-18T00:02:10.769256Z","steps":["trace[448981503] 'process raft request' (duration: 353.596161ms)","trace[448981503] 'compare' (duration: 157.79422ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:02:10.769348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.257305Z","time spent":"511.999695ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:02:10.769606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.404998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:02:10.769794Z","caller":"traceutil/trace.go:171","msg":"trace[655752468] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:756; }","duration":"495.671426ms","start":"2026-09-18T00:02:10.274101Z","end":"2026-09-18T00:02:10.769773Z","steps":["trace[655752468] 'agreement among raft nodes before linearized reading' (duration: 495.401658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:10.769841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.274047Z","time spent":"495.779759ms","remote":"127.0.0.1:37068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 "} {"level":"info","ts":"2026-09-18T00:02:10.769431Z","caller":"traceutil/trace.go:171","msg":"trace[1901296410] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"501.350764ms","start":"2026-09-18T00:02:10.268059Z","end":"2026-09-18T00:02:10.76941Z","steps":["trace[1901296410] 'process raft request' (duration: 501.019296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:10.770942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.268034Z","time spent":"502.82705ms","remote":"127.0.0.1:42060","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4791,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:02:10.771925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.693474ms","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:5"} {"level":"info","ts":"2026-09-18T00:02:10.771994Z","caller":"traceutil/trace.go:171","msg":"trace[684965545] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:758; }","duration":"497.790167ms","start":"2026-09-18T00:02:10.274189Z","end":"2026-09-18T00:02:10.77198Z","steps":["trace[684965545] 'agreement among raft nodes before linearized reading' (duration: 497.678514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:10.771989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.322816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T00:02:10.772027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.274183Z","time spent":"497.834478ms","remote":"127.0.0.1:37068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"info","ts":"2026-09-18T00:02:10.772094Z","caller":"traceutil/trace.go:171","msg":"trace[113327923] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"350.29778ms","start":"2026-09-18T00:02:10.421652Z","end":"2026-09-18T00:02:10.77195Z","steps":["trace[113327923] 'process raft request' (duration: 350.150597ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:10.772333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.271648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T00:02:10.772365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.966351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wvxvm\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-09-18T00:02:10.772398Z","caller":"traceutil/trace.go:171","msg":"trace[1035952696] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:758; }","duration":"467.33762ms","start":"2026-09-18T00:02:10.305043Z","end":"2026-09-18T00:02:10.772381Z","steps":["trace[1035952696] 'agreement among raft nodes before linearized reading' (duration: 467.249178ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:10.772415Z","caller":"traceutil/trace.go:171","msg":"trace[1204303507] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wvxvm; range_end:; response_count:1; response_revision:758; }","duration":"352.062933ms","start":"2026-09-18T00:02:10.420339Z","end":"2026-09-18T00:02:10.772402Z","steps":["trace[1204303507] 'agreement among raft nodes before linearized reading' (duration: 351.812457ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:10.772481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.305029Z","time spent":"467.407171ms","remote":"127.0.0.1:37074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":113,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-09-18T00:02:10.772692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.421632Z","time spent":"350.72675ms","remote":"127.0.0.1:41958","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":761,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-18T00:02:10.772964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.036772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-18T00:02:10.773025Z","caller":"traceutil/trace.go:171","msg":"trace[2024252321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:758; }","duration":"354.151415ms","start":"2026-09-18T00:02:10.418858Z","end":"2026-09-18T00:02:10.77301Z","steps":["trace[2024252321] 'agreement among raft nodes before linearized reading' (duration: 353.916799ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:10.772709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"467.395372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T00:02:10.773068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.418822Z","time spent":"354.231897ms","remote":"127.0.0.1:42150","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-09-18T00:02:10.773157Z","caller":"traceutil/trace.go:171","msg":"trace[372452995] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:758; }","duration":"467.872093ms","start":"2026-09-18T00:02:10.305271Z","end":"2026-09-18T00:02:10.773143Z","steps":["trace[372452995] 'agreement among raft nodes before linearized reading' (duration: 467.393971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:10.773229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.305251Z","time spent":"467.957395ms","remote":"127.0.0.1:37074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-18T00:02:10.772218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.581967ms","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":"warn","ts":"2026-09-18T00:02:10.772261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.643578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-18T00:02:10.772292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.662583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:02:10.773288Z","caller":"traceutil/trace.go:171","msg":"trace[378803674] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:758; }","duration":"467.675339ms","start":"2026-09-18T00:02:10.305604Z","end":"2026-09-18T00:02:10.77328Z","steps":["trace[378803674] 'agreement among raft nodes before linearized reading' (duration: 466.670824ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:10.773317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.305596Z","time spent":"467.71224ms","remote":"127.0.0.1:37074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 "} {"level":"info","ts":"2026-09-18T00:02:10.773473Z","caller":"traceutil/trace.go:171","msg":"trace[786497465] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:758; }","duration":"437.827947ms","start":"2026-09-18T00:02:10.335585Z","end":"2026-09-18T00:02:10.773413Z","steps":["trace[786497465] 'agreement among raft nodes before linearized reading' (duration: 436.656818ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:10.773507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.335568Z","time spent":"437.929289ms","remote":"127.0.0.1:37086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-09-18T00:02:10.772324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"497.826888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:02:10.773683Z","caller":"traceutil/trace.go:171","msg":"trace[1355927983] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:758; }","duration":"499.208141ms","start":"2026-09-18T00:02:10.274465Z","end":"2026-09-18T00:02:10.773673Z","steps":["trace[1355927983] 'agreement among raft nodes before linearized reading' (duration: 497.839118ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:10.773715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.27443Z","time spent":"499.274103ms","remote":"127.0.0.1:37068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 "} {"level":"warn","ts":"2026-09-18T00:02:10.772495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.420316Z","time spent":"352.165765ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18360,"request content":"key:\"/registry/pods/kube-system/cilium-wvxvm\" "} {"level":"info","ts":"2026-09-18T00:02:10.772059Z","caller":"traceutil/trace.go:171","msg":"trace[1156460353] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:758; }","duration":"435.418157ms","start":"2026-09-18T00:02:10.336619Z","end":"2026-09-18T00:02:10.772038Z","steps":["trace[1156460353] 'agreement among raft nodes before linearized reading' (duration: 435.278644ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:10.773965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.336608Z","time spent":"437.340035ms","remote":"127.0.0.1:37086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 "} {"level":"info","ts":"2026-09-18T00:02:10.773459Z","caller":"traceutil/trace.go:171","msg":"trace[2109014355] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:758; }","duration":"437.775446ms","start":"2026-09-18T00:02:10.335598Z","end":"2026-09-18T00:02:10.773373Z","steps":["trace[2109014355] 'agreement among raft nodes before linearized reading' (duration: 436.583887ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:10.774211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.335586Z","time spent":"438.450533ms","remote":"127.0.0.1:37086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-09-18T00:02:10.772909Z","caller":"traceutil/trace.go:171","msg":"trace[568717007] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"375.803296ms","start":"2026-09-18T00:02:10.397092Z","end":"2026-09-18T00:02:10.772895Z","steps":["trace[568717007] 'process raft request' (duration: 374.604826ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:10.77458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.397054Z","time spent":"377.461657ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:02:11.177244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.55926ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T00:02:11.177481Z","caller":"traceutil/trace.go:171","msg":"trace[839495028] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"399.360659ms","start":"2026-09-18T00:02:10.778039Z","end":"2026-09-18T00:02:11.177399Z","steps":["trace[839495028] 'process raft request' (duration: 161.561962ms)","trace[839495028] 'compare' (duration: 237.454857ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:02:11.177602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.778018Z","time spent":"399.540123ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:02:11.181622Z","caller":"traceutil/trace.go:171","msg":"trace[113957385] linearizableReadLoop","detail":"{readStateIndex:786; appliedIndex:785; }","duration":"203.852165ms","start":"2026-09-18T00:02:10.977756Z","end":"2026-09-18T00:02:11.181608Z","steps":["trace[113957385] 'read index received' (duration: 203.677921ms)","trace[113957385] 'applied index is now lower than readState.Index' (duration: 173.474µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:11.181702Z","caller":"traceutil/trace.go:171","msg":"trace[1039748201] transaction","detail":"{read_only:false; response_revision:760; number_of_response:1; }","duration":"388.41334ms","start":"2026-09-18T00:02:10.793245Z","end":"2026-09-18T00:02:11.181659Z","steps":["trace[1039748201] 'process raft request' (duration: 388.246036ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:11.181733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.972378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:02:11.181768Z","caller":"traceutil/trace.go:171","msg":"trace[2080478804] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:760; }","duration":"204.04896ms","start":"2026-09-18T00:02:10.97771Z","end":"2026-09-18T00:02:11.181759Z","steps":["trace[2080478804] 'agreement among raft nodes before linearized reading' (duration: 203.978648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:11.181815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:10.793222Z","time spent":"388.524903ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18501,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:02:11.181844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.046659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:02:11.181888Z","caller":"traceutil/trace.go:171","msg":"trace[1321948545] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:760; }","duration":"204.139151ms","start":"2026-09-18T00:02:10.977736Z","end":"2026-09-18T00:02:11.181875Z","steps":["trace[1321948545] 'agreement among raft nodes before linearized reading' (duration: 204.06205ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:11.18202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.212928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:02:11.182211Z","caller":"traceutil/trace.go:171","msg":"trace[1388205908] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:760; }","duration":"203.431694ms","start":"2026-09-18T00:02:10.978758Z","end":"2026-09-18T00:02:11.182189Z","steps":["trace[1388205908] 'agreement among raft nodes before linearized reading' (duration: 203.206649ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:11.530987Z","caller":"traceutil/trace.go:171","msg":"trace[300690301] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"182.195143ms","start":"2026-09-18T00:02:11.348754Z","end":"2026-09-18T00:02:11.530949Z","steps":["trace[300690301] 'process raft request' (duration: 181.719272ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:21.787933Z","caller":"traceutil/trace.go:171","msg":"trace[1962608343] linearizableReadLoop","detail":"{readStateIndex:857; appliedIndex:856; }","duration":"164.174747ms","start":"2026-09-18T00:02:21.623719Z","end":"2026-09-18T00:02:21.787894Z","steps":["trace[1962608343] 'read index received' (duration: 163.773157ms)","trace[1962608343] 'applied index is now lower than readState.Index' (duration: 400.1µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:21.78799Z","caller":"traceutil/trace.go:171","msg":"trace[944364443] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"203.095675ms","start":"2026-09-18T00:02:21.58487Z","end":"2026-09-18T00:02:21.787965Z","steps":["trace[944364443] 'process raft request' (duration: 202.806028ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:21.788122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.371632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:02:21.78816Z","caller":"traceutil/trace.go:171","msg":"trace[1186497509] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:828; }","duration":"164.495515ms","start":"2026-09-18T00:02:21.623652Z","end":"2026-09-18T00:02:21.788148Z","steps":["trace[1186497509] 'agreement among raft nodes before linearized reading' (duration: 164.392042ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:02:24.937039Z","caller":"traceutil/trace.go:171","msg":"trace[1227187092] transaction","detail":"{read_only:false; response_revision:844; number_of_response:1; }","duration":"477.680566ms","start":"2026-09-18T00:02:24.459322Z","end":"2026-09-18T00:02:24.937002Z","steps":["trace[1227187092] 'process raft request' (duration: 477.43664ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:24.937434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:24.459293Z","time spent":"477.87012ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:02:25.05091Z","caller":"traceutil/trace.go:171","msg":"trace[2097836031] linearizableReadLoop","detail":"{readStateIndex:875; appliedIndex:873; }","duration":"209.132616ms","start":"2026-09-18T00:02:24.84172Z","end":"2026-09-18T00:02:25.050852Z","steps":["trace[2097836031] 'read index received' (duration: 95.076904ms)","trace[2097836031] 'applied index is now lower than readState.Index' (duration: 114.054202ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:25.050952Z","caller":"traceutil/trace.go:171","msg":"trace[1559507843] transaction","detail":"{read_only:false; response_revision:845; number_of_response:1; }","duration":"548.167053ms","start":"2026-09-18T00:02:24.502751Z","end":"2026-09-18T00:02:25.050918Z","steps":["trace[1559507843] 'process raft request' (duration: 547.925377ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:25.051144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.402053ms","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-09-18T00:02:25.051213Z","caller":"traceutil/trace.go:171","msg":"trace[1284780554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:845; }","duration":"209.524697ms","start":"2026-09-18T00:02:24.841669Z","end":"2026-09-18T00:02:25.051194Z","steps":["trace[1284780554] 'agreement among raft nodes before linearized reading' (duration: 209.319841ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:25.051384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:02:24.502722Z","time spent":"548.4565ms","remote":"127.0.0.1:42110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:02:29.238461Z","caller":"traceutil/trace.go:171","msg":"trace[69842111] transaction","detail":"{read_only:false; response_revision:893; number_of_response:1; }","duration":"147.196656ms","start":"2026-09-18T00:02:29.091214Z","end":"2026-09-18T00:02:29.23841Z","steps":["trace[69842111] 'process raft request' (duration: 134.529007ms)","trace[69842111] 'compare' (duration: 12.407692ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:02:52.779757Z","caller":"traceutil/trace.go:171","msg":"trace[1680991299] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"110.304715ms","start":"2026-09-18T00:02:52.66943Z","end":"2026-09-18T00:02:52.779735Z","steps":["trace[1680991299] 'process raft request' (duration: 110.188291ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:02:52.938135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.627381ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-18T00:03:11.112881Z","caller":"traceutil/trace.go:171","msg":"trace[2129355894] transaction","detail":"{read_only:false; response_revision:1359; number_of_response:1; }","duration":"141.450871ms","start":"2026-09-18T00:03:10.971396Z","end":"2026-09-18T00:03:11.112846Z","steps":["trace[2129355894] 'process raft request' (duration: 120.383253ms)","trace[2129355894] 'compare' (duration: 20.886773ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:03:11.397955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.332878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T00:03:11.398066Z","caller":"traceutil/trace.go:171","msg":"trace[1264707026] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1404; }","duration":"218.895439ms","start":"2026-09-18T00:03:11.17915Z","end":"2026-09-18T00:03:11.398046Z","steps":["trace[1264707026] 'read index received' (duration: 72.363846ms)","trace[1264707026] 'applied index is now lower than readState.Index' (duration: 146.529953ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:03:11.398381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.221708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/ingress-nginx-admission\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:03:11.398449Z","caller":"traceutil/trace.go:171","msg":"trace[2127767827] range","detail":"{range_begin:/registry/clusterroles/ingress-nginx-admission; range_end:; response_count:0; response_revision:1361; }","duration":"219.3223ms","start":"2026-09-18T00:03:11.179108Z","end":"2026-09-18T00:03:11.39843Z","steps":["trace[2127767827] 'agreement among raft nodes before linearized reading' (duration: 219.223298ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:11.398906Z","caller":"traceutil/trace.go:171","msg":"trace[924032829] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"266.943079ms","start":"2026-09-18T00:03:11.131909Z","end":"2026-09-18T00:03:11.398852Z","steps":["trace[924032829] 'process raft request' (duration: 119.602442ms)","trace[924032829] 'compare' (duration: 146.197145ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:03:11.5895Z","caller":"traceutil/trace.go:171","msg":"trace[1640152176] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"136.673669ms","start":"2026-09-18T00:03:11.452777Z","end":"2026-09-18T00:03:11.589451Z","steps":["trace[1640152176] 'process raft request' (duration: 57.103511ms)","trace[1640152176] 'compare' (duration: 79.31051ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:03:11.808563Z","caller":"traceutil/trace.go:171","msg":"trace[724475701] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"113.584315ms","start":"2026-09-18T00:03:11.694955Z","end":"2026-09-18T00:03:11.808539Z","steps":["trace[724475701] 'process raft request' (duration: 113.41203ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:14.779351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.472191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:03:14.779557Z","caller":"traceutil/trace.go:171","msg":"trace[961765233] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1397; }","duration":"109.706547ms","start":"2026-09-18T00:03:14.669805Z","end":"2026-09-18T00:03:14.779511Z","steps":["trace[961765233] 'count revisions from in-memory index tree' (duration: 109.360147ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:20.714234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.970799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/ingress-nginx-admission\" ","response":"range_response_count:1 size:1659"} {"level":"info","ts":"2026-09-18T00:03:20.714327Z","caller":"traceutil/trace.go:171","msg":"trace[731973488] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/ingress-nginx-admission; range_end:; response_count:1; response_revision:1516; }","duration":"199.085822ms","start":"2026-09-18T00:03:20.515219Z","end":"2026-09-18T00:03:20.714305Z","steps":["trace[731973488] 'range keys from in-memory index tree' (duration: 198.817865ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:31.741124Z","caller":"traceutil/trace.go:171","msg":"trace[1809477886] transaction","detail":"{read_only:false; response_revision:1675; number_of_response:1; }","duration":"112.637511ms","start":"2026-09-18T00:03:31.628451Z","end":"2026-09-18T00:03:31.741088Z","steps":["trace[1809477886] 'process raft request' (duration: 30.465276ms)","trace[1809477886] 'compare' (duration: 81.948239ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:03:31.741169Z","caller":"traceutil/trace.go:171","msg":"trace[1268874158] transaction","detail":"{read_only:false; response_revision:1676; number_of_response:1; }","duration":"112.099487ms","start":"2026-09-18T00:03:31.629045Z","end":"2026-09-18T00:03:31.741144Z","steps":["trace[1268874158] 'process raft request' (duration: 111.994834ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:31.741315Z","caller":"traceutil/trace.go:171","msg":"trace[276788133] transaction","detail":"{read_only:false; response_revision:1677; number_of_response:1; }","duration":"111.981413ms","start":"2026-09-18T00:03:31.629325Z","end":"2026-09-18T00:03:31.741306Z","steps":["trace[276788133] 'process raft request' (duration: 111.754547ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:31.741564Z","caller":"traceutil/trace.go:171","msg":"trace[1226588635] transaction","detail":"{read_only:false; response_revision:1678; number_of_response:1; }","duration":"111.647234ms","start":"2026-09-18T00:03:31.629901Z","end":"2026-09-18T00:03:31.741548Z","steps":["trace[1226588635] 'process raft request' (duration: 111.209961ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:31.904472Z","caller":"traceutil/trace.go:171","msg":"trace[1960526424] transaction","detail":"{read_only:false; response_revision:1684; number_of_response:1; }","duration":"111.556622ms","start":"2026-09-18T00:03:31.792888Z","end":"2026-09-18T00:03:31.904444Z","steps":["trace[1960526424] 'process raft request' (duration: 107.27478ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:31.90452Z","caller":"traceutil/trace.go:171","msg":"trace[445521633] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"111.522201ms","start":"2026-09-18T00:03:31.792957Z","end":"2026-09-18T00:03:31.904479Z","steps":["trace[445521633] 'process raft request' (duration: 111.375516ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:31.904633Z","caller":"traceutil/trace.go:171","msg":"trace[1218826125] transaction","detail":"{read_only:false; response_revision:1686; number_of_response:1; }","duration":"104.965444ms","start":"2026-09-18T00:03:31.799631Z","end":"2026-09-18T00:03:31.904597Z","steps":["trace[1218826125] 'process raft request' (duration: 104.742498ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:31.904811Z","caller":"traceutil/trace.go:171","msg":"trace[1359444152] transaction","detail":"{read_only:false; response_revision:1687; number_of_response:1; }","duration":"101.032101ms","start":"2026-09-18T00:03:31.803765Z","end":"2026-09-18T00:03:31.904797Z","steps":["trace[1359444152] 'process raft request' (duration: 100.756194ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:36.739065Z","caller":"traceutil/trace.go:171","msg":"trace[1857781780] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1778; }","duration":"103.539025ms","start":"2026-09-18T00:03:36.635496Z","end":"2026-09-18T00:03:36.739035Z","steps":["trace[1857781780] 'process raft request' (duration: 103.217546ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:36.739101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.917617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterbackups.pxc.percona.com\" ","response":"range_response_count:1 size:6805"} {"level":"info","ts":"2026-09-18T00:03:36.739166Z","caller":"traceutil/trace.go:171","msg":"trace[1622997023] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterbackups.pxc.percona.com; range_end:; response_count:1; response_revision:1778; }","duration":"144.036181ms","start":"2026-09-18T00:03:36.595115Z","end":"2026-09-18T00:03:36.739151Z","steps":["trace[1622997023] 'agreement among raft nodes before linearized reading' (duration: 143.890566ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:36.763417Z","caller":"traceutil/trace.go:171","msg":"trace[2009226558] transaction","detail":"{read_only:false; response_revision:1779; number_of_response:1; }","duration":"114.026684ms","start":"2026-09-18T00:03:36.64936Z","end":"2026-09-18T00:03:36.763387Z","steps":["trace[2009226558] 'process raft request' (duration: 113.762837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:36.764695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.631317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterrestores.pxc.percona.com\" ","response":"range_response_count:1 size:9379"} {"level":"info","ts":"2026-09-18T00:03:36.764881Z","caller":"traceutil/trace.go:171","msg":"trace[1984064041] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/perconaxtradbclusterrestores.pxc.percona.com; range_end:; response_count:1; response_revision:1779; }","duration":"117.852643ms","start":"2026-09-18T00:03:36.647009Z","end":"2026-09-18T00:03:36.764861Z","steps":["trace[1984064041] 'agreement among raft nodes before linearized reading' (duration: 116.495734ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:38.517492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.710736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:03:38.517571Z","caller":"traceutil/trace.go:171","msg":"trace[509324550] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:1808; }","duration":"102.856499ms","start":"2026-09-18T00:03:38.414694Z","end":"2026-09-18T00:03:38.517551Z","steps":["trace[509324550] 'agreement among raft nodes before linearized reading' (duration: 102.749766ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:38.517935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.936682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:03:38.518033Z","caller":"traceutil/trace.go:171","msg":"trace[685607632] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:1808; }","duration":"103.095096ms","start":"2026-09-18T00:03:38.41492Z","end":"2026-09-18T00:03:38.518015Z","steps":["trace[685607632] 'agreement among raft nodes before linearized reading' (duration: 102.970433ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:38.518089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.495909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:03:38.5183Z","caller":"traceutil/trace.go:171","msg":"trace[595245336] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:1808; }","duration":"109.669625ms","start":"2026-09-18T00:03:38.408553Z","end":"2026-09-18T00:03:38.518223Z","steps":["trace[595245336] 'agreement among raft nodes before linearized reading' (duration: 109.455328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:38.520104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.542449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:03:38.520175Z","caller":"traceutil/trace.go:171","msg":"trace[895928469] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:1808; }","duration":"111.626371ms","start":"2026-09-18T00:03:38.408531Z","end":"2026-09-18T00:03:38.520157Z","steps":["trace[895928469] 'agreement among raft nodes before linearized reading' (duration: 111.513918ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:38.522057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.881882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:03:38.522144Z","caller":"traceutil/trace.go:171","msg":"trace[1148339050] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:1808; }","duration":"106.791792ms","start":"2026-09-18T00:03:38.415317Z","end":"2026-09-18T00:03:38.522109Z","steps":["trace[1148339050] 'agreement among raft nodes before linearized reading' (duration: 101.735658ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:49.238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.42495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T00:03:49.238104Z","caller":"traceutil/trace.go:171","msg":"trace[201844673] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1951; }","duration":"143.590056ms","start":"2026-09-18T00:03:49.09449Z","end":"2026-09-18T00:03:49.23808Z","steps":["trace[201844673] 'count revisions from in-memory index tree' (duration: 143.311548ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:49.238108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.820145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f819ad0b-0979-4591-8e04-4d5c1ab31650\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-09-18T00:03:49.238165Z","caller":"traceutil/trace.go:171","msg":"trace[1291196704] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f819ad0b-0979-4591-8e04-4d5c1ab31650; range_end:; response_count:1; response_revision:1951; }","duration":"107.924008ms","start":"2026-09-18T00:03:49.130224Z","end":"2026-09-18T00:03:49.238148Z","steps":["trace[1291196704] 'range keys from in-memory index tree' (duration: 107.6357ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:49.995082Z","caller":"traceutil/trace.go:171","msg":"trace[1104031392] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"548.149335ms","start":"2026-09-18T00:03:49.446909Z","end":"2026-09-18T00:03:49.995058Z","steps":["trace[1104031392] 'process raft request' (duration: 547.918089ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:49.995216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:49.446808Z","time spent":"548.343231ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:03:50.024946Z","caller":"traceutil/trace.go:171","msg":"trace[1172717948] linearizableReadLoop","detail":"{readStateIndex:2027; appliedIndex:2025; }","duration":"297.59432ms","start":"2026-09-18T00:03:49.727324Z","end":"2026-09-18T00:03:50.024918Z","steps":["trace[1172717948] 'read index received' (duration: 267.499613ms)","trace[1172717948] 'applied index is now lower than readState.Index' (duration: 30.093087ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:03:50.025318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.968552ms","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-09-18T00:03:50.025382Z","caller":"traceutil/trace.go:171","msg":"trace[1232934655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1953; }","duration":"298.047344ms","start":"2026-09-18T00:03:49.727317Z","end":"2026-09-18T00:03:50.025364Z","steps":["trace[1232934655] 'agreement among raft nodes before linearized reading' (duration: 297.832758ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:50.025753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.66005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-18T00:03:50.025827Z","caller":"traceutil/trace.go:171","msg":"trace[1718088885] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1953; }","duration":"132.775753ms","start":"2026-09-18T00:03:49.893029Z","end":"2026-09-18T00:03:50.025805Z","steps":["trace[1718088885] 'agreement among raft nodes before linearized reading' (duration: 132.589258ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:50.02596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.822866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-18T00:03:50.026088Z","caller":"traceutil/trace.go:171","msg":"trace[1827355159] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1953; }","duration":"174.028102ms","start":"2026-09-18T00:03:49.852038Z","end":"2026-09-18T00:03:50.026066Z","steps":["trace[1827355159] 'agreement among raft nodes before linearized reading' (duration: 173.641681ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:50.026124Z","caller":"traceutil/trace.go:171","msg":"trace[863780507] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"355.084227ms","start":"2026-09-18T00:03:49.671024Z","end":"2026-09-18T00:03:50.026108Z","steps":["trace[863780507] 'process raft request' (duration: 353.612624ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:50.026238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:49.670998Z","time spent":"355.177538ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:03:50.026659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.283739ms","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-09-18T00:03:50.026725Z","caller":"traceutil/trace.go:171","msg":"trace[395100417] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1953; }","duration":"299.394692ms","start":"2026-09-18T00:03:49.727313Z","end":"2026-09-18T00:03:50.026708Z","steps":["trace[395100417] 'agreement among raft nodes before linearized reading' (duration: 298.25974ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:50.166308Z","caller":"traceutil/trace.go:171","msg":"trace[1978724368] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"135.832584ms","start":"2026-09-18T00:03:50.030449Z","end":"2026-09-18T00:03:50.166282Z","steps":["trace[1978724368] 'process raft request' (duration: 51.360429ms)","trace[1978724368] 'compare' (duration: 84.352971ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:03:50.742155Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9380611759436385422,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-09-18T00:03:51.145514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.314292ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T00:03:51.145665Z","caller":"traceutil/trace.go:171","msg":"trace[320007470] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"1.114469867s","start":"2026-09-18T00:03:50.031178Z","end":"2026-09-18T00:03:51.145647Z","steps":["trace[320007470] 'process raft request' (duration: 903.939248ms)","trace[320007470] 'compare' (duration: 209.777567ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:03:51.145723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:50.031158Z","time spent":"1.114536018s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:03:51.146371Z","caller":"traceutil/trace.go:171","msg":"trace[1147771798] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"1.115109965s","start":"2026-09-18T00:03:50.031231Z","end":"2026-09-18T00:03:51.146341Z","steps":["trace[1147771798] 'process raft request' (duration: 1.114364363s)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:51.146428Z","caller":"traceutil/trace.go:171","msg":"trace[1052127974] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"1.114846328s","start":"2026-09-18T00:03:50.031561Z","end":"2026-09-18T00:03:51.146407Z","steps":["trace[1052127974] 'process raft request' (duration: 1.114637682s)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:51.146441Z","caller":"traceutil/trace.go:171","msg":"trace[296445878] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"1.114199828s","start":"2026-09-18T00:03:50.032225Z","end":"2026-09-18T00:03:51.146425Z","steps":["trace[296445878] 'process raft request' (duration: 1.114065545s)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:51.146524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:50.03221Z","time spent":"1.114268571s","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:03:51.146522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:50.0312Z","time spent":"1.115240489s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:03:51.146567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:50.031545Z","time spent":"1.114930121s","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:03:51.156328Z","caller":"traceutil/trace.go:171","msg":"trace[452677341] linearizableReadLoop","detail":"{readStateIndex:2035; appliedIndex:2028; }","duration":"914.511965ms","start":"2026-09-18T00:03:50.241798Z","end":"2026-09-18T00:03:51.15631Z","steps":["trace[452677341] 'read index received' (duration: 693.334689ms)","trace[452677341] 'applied index is now lower than readState.Index' (duration: 221.176205ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:03:51.156593Z","caller":"traceutil/trace.go:171","msg":"trace[380866121] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"930.947109ms","start":"2026-09-18T00:03:50.225633Z","end":"2026-09-18T00:03:51.156581Z","steps":["trace[380866121] 'process raft request' (duration: 930.432045ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:51.156665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:50.225611Z","time spent":"931.00821ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:03:51.156845Z","caller":"traceutil/trace.go:171","msg":"trace[1458902585] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"866.008076ms","start":"2026-09-18T00:03:50.290825Z","end":"2026-09-18T00:03:51.156833Z","steps":["trace[1458902585] 'process raft request' (duration: 865.373778ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:51.156926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:50.290798Z","time spent":"866.096639ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:03:51.157208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"794.668461ms","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-09-18T00:03:51.157365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.634477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f819ad0b-0979-4591-8e04-4d5c1ab31650\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-09-18T00:03:51.157294Z","caller":"traceutil/trace.go:171","msg":"trace[1338681099] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1961; }","duration":"794.808185ms","start":"2026-09-18T00:03:50.362464Z","end":"2026-09-18T00:03:51.157273Z","steps":["trace[1338681099] 'agreement among raft nodes before linearized reading' (duration: 794.560138ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:03:51.157413Z","caller":"traceutil/trace.go:171","msg":"trace[1511621427] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f819ad0b-0979-4591-8e04-4d5c1ab31650; range_end:; response_count:1; response_revision:1961; }","duration":"915.705459ms","start":"2026-09-18T00:03:50.241693Z","end":"2026-09-18T00:03:51.157398Z","steps":["trace[1511621427] 'agreement among raft nodes before linearized reading' (duration: 915.633727ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:51.157442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:50.241681Z","time spent":"915.753321ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3770,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f819ad0b-0979-4591-8e04-4d5c1ab31650\" "} {"level":"info","ts":"2026-09-18T00:03:51.157567Z","caller":"traceutil/trace.go:171","msg":"trace[1462648908] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"735.957461ms","start":"2026-09-18T00:03:50.4216Z","end":"2026-09-18T00:03:51.157557Z","steps":["trace[1462648908] 'process raft request' (duration: 734.667253ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:51.157626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:50.421571Z","time spent":"736.023972ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:03:51.157555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:03:50.362444Z","time spent":"794.992651ms","remote":"127.0.0.1:42150","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-09-18T00:03:51.157827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.751285ms","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-09-18T00:03:51.15793Z","caller":"traceutil/trace.go:171","msg":"trace[2120925369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1961; }","duration":"278.91855ms","start":"2026-09-18T00:03:50.878997Z","end":"2026-09-18T00:03:51.157916Z","steps":["trace[2120925369] 'agreement among raft nodes before linearized reading' (duration: 278.657372ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:03:51.157802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.76942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-18T00:03:51.158076Z","caller":"traceutil/trace.go:171","msg":"trace[1150030410] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1961; }","duration":"124.092569ms","start":"2026-09-18T00:03:51.033963Z","end":"2026-09-18T00:03:51.158055Z","steps":["trace[1150030410] 'agreement among raft nodes before linearized reading' (duration: 123.626626ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:04:03.443475Z","caller":"traceutil/trace.go:171","msg":"trace[1538803535] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"190.128777ms","start":"2026-09-18T00:04:03.253309Z","end":"2026-09-18T00:04:03.443438Z","steps":["trace[1538803535] 'process raft request' (duration: 189.923901ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:04:03.443767Z","caller":"traceutil/trace.go:171","msg":"trace[2040771765] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"190.368135ms","start":"2026-09-18T00:04:03.253385Z","end":"2026-09-18T00:04:03.443753Z","steps":["trace[2040771765] 'process raft request' (duration: 190.001824ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:04:03.444192Z","caller":"traceutil/trace.go:171","msg":"trace[1146374707] linearizableReadLoop","detail":"{readStateIndex:2150; appliedIndex:2147; }","duration":"167.888253ms","start":"2026-09-18T00:04:03.276276Z","end":"2026-09-18T00:04:03.444165Z","steps":["trace[1146374707] 'read index received' (duration: 166.931925ms)","trace[1146374707] 'applied index is now lower than readState.Index' (duration: 954.728µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:04:03.444311Z","caller":"traceutil/trace.go:171","msg":"trace[1173690428] transaction","detail":"{read_only:false; response_revision:2072; number_of_response:1; }","duration":"190.720655ms","start":"2026-09-18T00:04:03.253578Z","end":"2026-09-18T00:04:03.444298Z","steps":["trace[1173690428] 'process raft request' (duration: 190.123278ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:03.444495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.285254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:04:03.444601Z","caller":"traceutil/trace.go:171","msg":"trace[70616471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2072; }","duration":"127.425208ms","start":"2026-09-18T00:04:03.317155Z","end":"2026-09-18T00:04:03.444581Z","steps":["trace[70616471] 'agreement among raft nodes before linearized reading' (duration: 127.246073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:03.444698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.419918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-18T00:04:03.44477Z","caller":"traceutil/trace.go:171","msg":"trace[1382645146] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2072; }","duration":"168.48143ms","start":"2026-09-18T00:04:03.276258Z","end":"2026-09-18T00:04:03.44474Z","steps":["trace[1382645146] 'agreement among raft nodes before linearized reading' (duration: 168.271404ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:04:07.454219Z","caller":"traceutil/trace.go:171","msg":"trace[1929039515] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"207.978309ms","start":"2026-09-18T00:04:07.246218Z","end":"2026-09-18T00:04:07.454196Z","steps":["trace[1929039515] 'process raft request' (duration: 207.842885ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:04:07.832609Z","caller":"traceutil/trace.go:171","msg":"trace[1373827590] linearizableReadLoop","detail":"{readStateIndex:2169; appliedIndex:2166; }","duration":"547.860021ms","start":"2026-09-18T00:04:07.284733Z","end":"2026-09-18T00:04:07.832593Z","steps":["trace[1373827590] 'read index received' (duration: 169.267352ms)","trace[1373827590] 'applied index is now lower than readState.Index' (duration: 378.591939ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:04:07.832657Z","caller":"traceutil/trace.go:171","msg":"trace[2072870503] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"571.427636ms","start":"2026-09-18T00:04:07.261209Z","end":"2026-09-18T00:04:07.832636Z","steps":["trace[2072870503] 'process raft request' (duration: 553.031951ms)","trace[2072870503] 'compare' (duration: 18.109397ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:04:07.832673Z","caller":"traceutil/trace.go:171","msg":"trace[146587328] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"570.010705ms","start":"2026-09-18T00:04:07.262635Z","end":"2026-09-18T00:04:07.832646Z","steps":["trace[146587328] 'process raft request' (duration: 569.925843ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:07.832721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.072518ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:04:07.83274Z","caller":"traceutil/trace.go:171","msg":"trace[136705531] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2091; }","duration":"515.14492ms","start":"2026-09-18T00:04:07.317589Z","end":"2026-09-18T00:04:07.832734Z","steps":["trace[136705531] 'agreement among raft nodes before linearized reading' (duration: 515.081748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:07.832757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:07.317566Z","time spent":"515.185481ms","remote":"127.0.0.1:41870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-09-18T00:04:07.832763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:07.261189Z","time spent":"571.515829ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:04:07.832792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:07.26261Z","time spent":"570.118128ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:04:07.832811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.067176ms","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-09-18T00:04:07.832859Z","caller":"traceutil/trace.go:171","msg":"trace[441866471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2091; }","duration":"548.139419ms","start":"2026-09-18T00:04:07.284706Z","end":"2026-09-18T00:04:07.832845Z","steps":["trace[441866471] 'agreement among raft nodes before linearized reading' (duration: 547.969504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:07.832888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.726328ms","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-09-18T00:04:07.832894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:07.284693Z","time spent":"548.19446ms","remote":"127.0.0.1:42150","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-09-18T00:04:07.832901Z","caller":"traceutil/trace.go:171","msg":"trace[841533008] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2091; }","duration":"372.759959ms","start":"2026-09-18T00:04:07.460137Z","end":"2026-09-18T00:04:07.832897Z","steps":["trace[841533008] 'agreement among raft nodes before linearized reading' (duration: 372.718408ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:07.832914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:07.460126Z","time spent":"372.78521ms","remote":"127.0.0.1:42150","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-09-18T00:04:07.832918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.550454ms","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-09-18T00:04:07.832943Z","caller":"traceutil/trace.go:171","msg":"trace[975775342] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2091; }","duration":"372.593734ms","start":"2026-09-18T00:04:07.460341Z","end":"2026-09-18T00:04:07.832934Z","steps":["trace[975775342] 'agreement among raft nodes before linearized reading' (duration: 372.511412ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:07.832985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.990389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.205\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-09-18T00:04:07.833011Z","caller":"traceutil/trace.go:171","msg":"trace[577911148] range","detail":"{range_begin:/registry/masterleases/162.253.55.205; range_end:; response_count:1; response_revision:2091; }","duration":"467.068721ms","start":"2026-09-18T00:04:07.365935Z","end":"2026-09-18T00:04:07.833004Z","steps":["trace[577911148] 'agreement among raft nodes before linearized reading' (duration: 466.951568ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:07.833035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:07.365917Z","time spent":"467.113202ms","remote":"127.0.0.1:41916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/162.253.55.205\" "} {"level":"warn","ts":"2026-09-18T00:04:07.833065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.206831ms","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-09-18T00:04:07.833081Z","caller":"traceutil/trace.go:171","msg":"trace[877251159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2091; }","duration":"374.258742ms","start":"2026-09-18T00:04:07.458818Z","end":"2026-09-18T00:04:07.833077Z","steps":["trace[877251159] 'agreement among raft nodes before linearized reading' (duration: 374.217711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:07.833093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:07.458799Z","time spent":"374.291085ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-18T00:04:07.83311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.45076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-09-18T00:04:07.832989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:07.460331Z","time spent":"372.651546ms","remote":"127.0.0.1:42150","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-09-18T00:04:07.833139Z","caller":"traceutil/trace.go:171","msg":"trace[1017525375] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2091; }","duration":"361.528064ms","start":"2026-09-18T00:04:07.471599Z","end":"2026-09-18T00:04:07.833127Z","steps":["trace[1017525375] 'agreement among raft nodes before linearized reading' (duration: 361.4132ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:07.833153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.221622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11989"} {"level":"warn","ts":"2026-09-18T00:04:07.833162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:07.471587Z","time spent":"361.569955ms","remote":"127.0.0.1:59160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-09-18T00:04:07.833183Z","caller":"traceutil/trace.go:171","msg":"trace[634419171] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2091; }","duration":"364.290934ms","start":"2026-09-18T00:04:07.468883Z","end":"2026-09-18T00:04:07.833174Z","steps":["trace[634419171] 'agreement among raft nodes before linearized reading' (duration: 364.198102ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:07.833207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:07.468863Z","time spent":"364.337875ms","remote":"127.0.0.1:42062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12012,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-18T00:04:30.453361Z","caller":"traceutil/trace.go:171","msg":"trace[985148888] transaction","detail":"{read_only:false; response_revision:2220; number_of_response:1; }","duration":"222.948901ms","start":"2026-09-18T00:04:30.230384Z","end":"2026-09-18T00:04:30.453333Z","steps":["trace[985148888] 'process raft request' (duration: 222.762245ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:04:31.204079Z","caller":"traceutil/trace.go:171","msg":"trace[898777047] transaction","detail":"{read_only:false; response_revision:2222; number_of_response:1; }","duration":"173.605995ms","start":"2026-09-18T00:04:31.030445Z","end":"2026-09-18T00:04:31.204051Z","steps":["trace[898777047] 'process raft request' (duration: 173.476981ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:04:31.701617Z","caller":"traceutil/trace.go:171","msg":"trace[1424263683] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"121.460855ms","start":"2026-09-18T00:04:31.580134Z","end":"2026-09-18T00:04:31.701594Z","steps":["trace[1424263683] 'process raft request' (duration: 121.319041ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:32.210824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.908795ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T00:04:32.210948Z","caller":"traceutil/trace.go:171","msg":"trace[1148678515] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"285.048657ms","start":"2026-09-18T00:04:31.925878Z","end":"2026-09-18T00:04:32.210927Z","steps":["trace[1148678515] 'process raft request' (duration: 79.934446ms)","trace[1148678515] 'compare' (duration: 204.661098ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:04:32.496298Z","caller":"traceutil/trace.go:171","msg":"trace[520589854] linearizableReadLoop","detail":"{readStateIndex:2309; appliedIndex:2307; }","duration":"505.297234ms","start":"2026-09-18T00:04:31.990976Z","end":"2026-09-18T00:04:32.496274Z","steps":["trace[520589854] 'read index received' (duration: 14.834755ms)","trace[520589854] 'applied index is now lower than readState.Index' (duration: 490.461649ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:04:32.496352Z","caller":"traceutil/trace.go:171","msg":"trace[761849305] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"569.84979ms","start":"2026-09-18T00:04:31.926483Z","end":"2026-09-18T00:04:32.496332Z","steps":["trace[761849305] 'process raft request' (duration: 569.611513ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:32.496429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.447979ms","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-09-18T00:04:32.496454Z","caller":"traceutil/trace.go:171","msg":"trace[1733361827] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2225; }","duration":"505.47679ms","start":"2026-09-18T00:04:31.990971Z","end":"2026-09-18T00:04:32.496448Z","steps":["trace[1733361827] 'agreement among raft nodes before linearized reading' (duration: 505.395108ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:32.49648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:31.990915Z","time spent":"505.560832ms","remote":"127.0.0.1:42150","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-09-18T00:04:32.496718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:31.926463Z","time spent":"569.931631ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:04:32.8499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.513851ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-09-18T00:04:32.851393Z","caller":"traceutil/trace.go:171","msg":"trace[1333863013] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"392.872104ms","start":"2026-09-18T00:04:32.458499Z","end":"2026-09-18T00:04:32.851371Z","steps":["trace[1333863013] 'process raft request' (duration: 391.515294ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:32.851523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:32.45847Z","time spent":"392.987527ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-18T00:04:32.851411Z","caller":"traceutil/trace.go:171","msg":"trace[497856700] linearizableReadLoop","detail":"{readStateIndex:2311; appliedIndex:2309; }","duration":"355.011772ms","start":"2026-09-18T00:04:32.496375Z","end":"2026-09-18T00:04:32.851387Z","steps":["trace[497856700] 'read index received' (duration: 2.969777ms)","trace[497856700] 'applied index is now lower than readState.Index' (duration: 352.040935ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:04:32.958415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"942.762497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-18T00:04:32.958508Z","caller":"traceutil/trace.go:171","msg":"trace[754857899] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2226; }","duration":"942.88262ms","start":"2026-09-18T00:04:32.015603Z","end":"2026-09-18T00:04:32.958485Z","steps":["trace[754857899] 'agreement among raft nodes before linearized reading' (duration: 835.873949ms)","trace[754857899] 'range keys from in-memory index tree' (duration: 106.746014ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:04:32.958548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:32.015593Z","time spent":"942.944233ms","remote":"127.0.0.1:59160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-09-18T00:04:32.95864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"948.212547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-18T00:04:32.958714Z","caller":"traceutil/trace.go:171","msg":"trace[1210378658] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2226; }","duration":"948.32317ms","start":"2026-09-18T00:04:32.010369Z","end":"2026-09-18T00:04:32.958692Z","steps":["trace[1210378658] 'agreement among raft nodes before linearized reading' (duration: 841.116973ms)","trace[1210378658] 'range keys from in-memory index tree' (duration: 107.012412ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:04:32.958762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:32.010351Z","time spent":"948.401924ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-09-18T00:04:32.959054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"659.769639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18d6417169d42b48\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-09-18T00:04:32.959225Z","caller":"traceutil/trace.go:171","msg":"trace[1289102206] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18d6417169d42b48; range_end:; response_count:1; response_revision:2226; }","duration":"659.958636ms","start":"2026-09-18T00:04:32.299242Z","end":"2026-09-18T00:04:32.959201Z","steps":["trace[1289102206] 'agreement among raft nodes before linearized reading' (duration: 552.223293ms)","trace[1289102206] 'range keys from in-memory index tree' (duration: 107.453115ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:04:32.959324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:32.299228Z","time spent":"660.08021ms","remote":"127.0.0.1:41958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":869,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18d6417169d42b48\" "} {"level":"warn","ts":"2026-09-18T00:04:32.959352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.910098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-18T00:04:32.959589Z","caller":"traceutil/trace.go:171","msg":"trace[1363875017] linearizableReadLoop","detail":"{readStateIndex:2312; appliedIndex:2311; }","duration":"107.850087ms","start":"2026-09-18T00:04:32.851728Z","end":"2026-09-18T00:04:32.959578Z","steps":["trace[1363875017] 'read index received' (duration: 52.232µs)","trace[1363875017] 'applied index is now lower than readState.Index' (duration: 107.796934ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:04:32.959661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.994412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:04:32.9597Z","caller":"traceutil/trace.go:171","msg":"trace[973713523] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2227; }","duration":"232.053443ms","start":"2026-09-18T00:04:32.727621Z","end":"2026-09-18T00:04:32.959675Z","steps":["trace[973713523] 'agreement among raft nodes before linearized reading' (duration: 231.987221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:32.959914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.655965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-09-18T00:04:32.95994Z","caller":"traceutil/trace.go:171","msg":"trace[49129003] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2226; }","duration":"474.704667ms","start":"2026-09-18T00:04:32.485226Z","end":"2026-09-18T00:04:32.959931Z","steps":["trace[49129003] 'agreement among raft nodes before linearized reading' (duration: 366.230712ms)","trace[49129003] 'range keys from in-memory index tree' (duration: 108.395372ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:04:32.959965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:32.48521Z","time spent":"474.748347ms","remote":"127.0.0.1:42150","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-09-18T00:04:32.960201Z","caller":"traceutil/trace.go:171","msg":"trace[965840004] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"458.940324ms","start":"2026-09-18T00:04:32.501248Z","end":"2026-09-18T00:04:32.960188Z","steps":["trace[965840004] 'process raft request' (duration: 350.119909ms)","trace[965840004] 'compare' (duration: 107.463035ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:04:32.960255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:32.501222Z","time spent":"459.002966ms","remote":"127.0.0.1:42150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-18T00:04:32.960433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.023586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-18T00:04:32.960461Z","caller":"traceutil/trace.go:171","msg":"trace[1339733909] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:2227; }","duration":"226.077147ms","start":"2026-09-18T00:04:32.734374Z","end":"2026-09-18T00:04:32.960451Z","steps":["trace[1339733909] 'agreement among raft nodes before linearized reading' (duration: 225.983815ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:04:32.960844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"955.400018ms","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-09-18T00:04:32.960873Z","caller":"traceutil/trace.go:171","msg":"trace[78099216] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2226; }","duration":"955.47412ms","start":"2026-09-18T00:04:32.005389Z","end":"2026-09-18T00:04:32.960863Z","steps":["trace[78099216] 'agreement among raft nodes before linearized reading' (duration: 846.10631ms)","trace[78099216] 'range keys from in-memory index tree' (duration: 109.289638ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:04:32.960897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:32.005363Z","time spent":"955.526982ms","remote":"127.0.0.1:42150","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-09-18T00:04:32.961433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.511938ms","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-09-18T00:04:32.9615Z","caller":"traceutil/trace.go:171","msg":"trace[1322154781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2226; }","duration":"960.624582ms","start":"2026-09-18T00:04:32.00086Z","end":"2026-09-18T00:04:32.961485Z","steps":["trace[1322154781] 'agreement among raft nodes before linearized reading' (duration: 850.582722ms)","trace[1322154781] 'range keys from in-memory index tree' (duration: 108.118514ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:04:32.961535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-18T00:04:32.000838Z","time spent":"960.688664ms","remote":"127.0.0.1:42150","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-09-18T00:06:32.264744Z","caller":"traceutil/trace.go:171","msg":"trace[1438964093] transaction","detail":"{read_only:false; response_revision:2971; number_of_response:1; }","duration":"111.816241ms","start":"2026-09-18T00:06:32.152903Z","end":"2026-09-18T00:06:32.264719Z","steps":["trace[1438964093] 'process raft request' (duration: 111.629477ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:06:32.4347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.71935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:06:32.434784Z","caller":"traceutil/trace.go:171","msg":"trace[680598052] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2971; }","duration":"114.820352ms","start":"2026-09-18T00:06:32.319944Z","end":"2026-09-18T00:06:32.434765Z","steps":["trace[680598052] 'range keys from in-memory index tree' (duration: 114.640748ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:10:55.125471Z","caller":"traceutil/trace.go:171","msg":"trace[756372059] linearizableReadLoop","detail":"{readStateIndex:5108; appliedIndex:5107; }","duration":"116.415041ms","start":"2026-09-18T00:10:55.009024Z","end":"2026-09-18T00:10:55.125439Z","steps":["trace[756372059] 'read index received' (duration: 112.957318ms)","trace[756372059] 'applied index is now lower than readState.Index' (duration: 3.456113ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:10:55.12557Z","caller":"traceutil/trace.go:171","msg":"trace[1428548237] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"127.148641ms","start":"2026-09-18T00:10:54.998381Z","end":"2026-09-18T00:10:55.125529Z","steps":["trace[1428548237] 'process raft request' (duration: 123.544254ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:10:55.125793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.749641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-09-18T00:10:55.125845Z","caller":"traceutil/trace.go:171","msg":"trace[1357178452] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4918; }","duration":"116.855083ms","start":"2026-09-18T00:10:55.008975Z","end":"2026-09-18T00:10:55.12583Z","steps":["trace[1357178452] 'agreement among raft nodes before linearized reading' (duration: 116.652688ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:10:55.126048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.608381ms","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-09-18T00:10:55.126112Z","caller":"traceutil/trace.go:171","msg":"trace[2134445593] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4918; }","duration":"104.724784ms","start":"2026-09-18T00:10:55.021371Z","end":"2026-09-18T00:10:55.126095Z","steps":["trace[2134445593] 'agreement among raft nodes before linearized reading' (duration: 104.534419ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:11:04.106828Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2819} {"level":"info","ts":"2026-09-18T00:11:04.181567Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2819,"took":"73.572093ms","hash":2013806588,"current-db-size-bytes":51232768,"current-db-size":"51 MB","current-db-size-in-use-bytes":26415104,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-18T00:11:04.181638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2013806588,"revision":2819,"compact-revision":-1} {"level":"info","ts":"2026-09-18T00:11:06.090157Z","caller":"traceutil/trace.go:171","msg":"trace[176365875] linearizableReadLoop","detail":"{readStateIndex:5315; appliedIndex:5314; }","duration":"103.965352ms","start":"2026-09-18T00:11:05.986161Z","end":"2026-09-18T00:11:06.090126Z","steps":["trace[176365875] 'read index received' (duration: 103.744615ms)","trace[176365875] 'applied index is now lower than readState.Index' (duration: 217.177µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:11:06.090364Z","caller":"traceutil/trace.go:171","msg":"trace[140888066] transaction","detail":"{read_only:false; response_revision:5116; number_of_response:1; }","duration":"107.562639ms","start":"2026-09-18T00:11:05.982784Z","end":"2026-09-18T00:11:06.090347Z","steps":["trace[140888066] 'process raft request' (duration: 107.179318ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:11:06.09047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.213359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:11:06.090545Z","caller":"traceutil/trace.go:171","msg":"trace[528247108] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:5116; }","duration":"104.417494ms","start":"2026-09-18T00:11:05.986115Z","end":"2026-09-18T00:11:06.090532Z","steps":["trace[528247108] 'agreement among raft nodes before linearized reading' (duration: 104.232419ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:11:06.286115Z","caller":"traceutil/trace.go:171","msg":"trace[724163944] linearizableReadLoop","detail":"{readStateIndex:5316; appliedIndex:5315; }","duration":"188.337649ms","start":"2026-09-18T00:11:06.097746Z","end":"2026-09-18T00:11:06.286084Z","steps":["trace[724163944] 'read index received' (duration: 173.858055ms)","trace[724163944] 'applied index is now lower than readState.Index' (duration: 14.478314ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:11:06.286155Z","caller":"traceutil/trace.go:171","msg":"trace[25661620] transaction","detail":"{read_only:false; response_revision:5117; number_of_response:1; }","duration":"189.607594ms","start":"2026-09-18T00:11:06.096505Z","end":"2026-09-18T00:11:06.286113Z","steps":["trace[25661620] 'process raft request' (duration: 175.055027ms)","trace[25661620] 'compare' (duration: 14.072243ms)"],"step_count":2} {"level":"warn","ts":"2026-09-18T00:11:06.28635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.582777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-c57febfa-8210-461c-8484-9c50c669c836\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-09-18T00:11:06.28652Z","caller":"traceutil/trace.go:171","msg":"trace[1127252676] range","detail":"{range_begin:/registry/persistentvolumes/pvc-c57febfa-8210-461c-8484-9c50c669c836; range_end:; response_count:1; response_revision:5117; }","duration":"188.779202ms","start":"2026-09-18T00:11:06.097727Z","end":"2026-09-18T00:11:06.286506Z","steps":["trace[1127252676] 'agreement among raft nodes before linearized reading' (duration: 188.465203ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:11:06.322942Z","caller":"traceutil/trace.go:171","msg":"trace[1273644463] transaction","detail":"{read_only:false; response_revision:5118; number_of_response:1; }","duration":"214.416529ms","start":"2026-09-18T00:11:06.108504Z","end":"2026-09-18T00:11:06.322921Z","steps":["trace[1273644463] 'process raft request' (duration: 214.251845ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:11:09.496109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.268258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:11:09.496212Z","caller":"traceutil/trace.go:171","msg":"trace[1545055844] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5212; }","duration":"178.411843ms","start":"2026-09-18T00:11:09.317777Z","end":"2026-09-18T00:11:09.496189Z","steps":["trace[1545055844] 'range keys from in-memory index tree' (duration: 178.132175ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:11:09.496408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.165365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1fee01fe-2117-4309-b675-a391c18b6ffe\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-18T00:11:09.496525Z","caller":"traceutil/trace.go:171","msg":"trace[1286049007] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1fee01fe-2117-4309-b675-a391c18b6ffe; range_end:; response_count:1; response_revision:5212; }","duration":"175.301698ms","start":"2026-09-18T00:11:09.3212Z","end":"2026-09-18T00:11:09.496501Z","steps":["trace[1286049007] 'range keys from in-memory index tree' (duration: 175.026391ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:11:09.496594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.232178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-09-18T00:11:09.496609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.402404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-09-18T00:11:09.496629Z","caller":"traceutil/trace.go:171","msg":"trace[1979689559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5212; }","duration":"200.290209ms","start":"2026-09-18T00:11:09.296329Z","end":"2026-09-18T00:11:09.496619Z","steps":["trace[1979689559] 'range keys from in-memory index tree' (duration: 200.121035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:11:09.49652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.567324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-09-18T00:11:09.496813Z","caller":"traceutil/trace.go:171","msg":"trace[2043618145] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5212; }","duration":"201.883914ms","start":"2026-09-18T00:11:09.294917Z","end":"2026-09-18T00:11:09.496801Z","steps":["trace[2043618145] 'range keys from in-memory index tree' (duration: 201.40105ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:11:09.497007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.459359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-18T00:11:09.49704Z","caller":"traceutil/trace.go:171","msg":"trace[76605633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5212; }","duration":"202.536361ms","start":"2026-09-18T00:11:09.294493Z","end":"2026-09-18T00:11:09.49703Z","steps":["trace[76605633] 'range keys from in-memory index tree' (duration: 202.353956ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:11:09.497165Z","caller":"traceutil/trace.go:171","msg":"trace[193791932] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"151.285833ms","start":"2026-09-18T00:11:09.345859Z","end":"2026-09-18T00:11:09.497145Z","steps":["trace[193791932] 'process raft request' (duration: 80.671588ms)","trace[193791932] 'compare' (duration: 70.202874ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:11:09.496644Z","caller":"traceutil/trace.go:171","msg":"trace[405437289] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5212; }","duration":"203.432215ms","start":"2026-09-18T00:11:09.2932Z","end":"2026-09-18T00:11:09.496632Z","steps":["trace[405437289] 'range keys from in-memory index tree' (duration: 203.298261ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:11:11.503211Z","caller":"traceutil/trace.go:171","msg":"trace[828002647] transaction","detail":"{read_only:false; response_revision:5225; number_of_response:1; }","duration":"157.913746ms","start":"2026-09-18T00:11:11.345274Z","end":"2026-09-18T00:11:11.503188Z","steps":["trace[828002647] 'process raft request' (duration: 157.768353ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:14:29.901262Z","caller":"traceutil/trace.go:171","msg":"trace[1906494936] transaction","detail":"{read_only:false; response_revision:6649; number_of_response:1; }","duration":"115.193931ms","start":"2026-09-18T00:14:29.786042Z","end":"2026-09-18T00:14:29.901236Z","steps":["trace[1906494936] 'process raft request' (duration: 98.502069ms)","trace[1906494936] 'compare' (duration: 16.57018ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:14:32.337498Z","caller":"traceutil/trace.go:171","msg":"trace[947788384] transaction","detail":"{read_only:false; response_revision:6683; number_of_response:1; }","duration":"113.411067ms","start":"2026-09-18T00:14:32.224058Z","end":"2026-09-18T00:14:32.337469Z","steps":["trace[947788384] 'process raft request' (duration: 104.665158ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:14:32.338041Z","caller":"traceutil/trace.go:171","msg":"trace[757530120] transaction","detail":"{read_only:false; number_of_response:1; response_revision:6683; }","duration":"102.161329ms","start":"2026-09-18T00:14:32.235854Z","end":"2026-09-18T00:14:32.338016Z","steps":["trace[757530120] 'process raft request' (duration: 101.432166ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:14:40.856599Z","caller":"traceutil/trace.go:171","msg":"trace[925593551] linearizableReadLoop","detail":"{readStateIndex:6983; appliedIndex:6982; }","duration":"158.290144ms","start":"2026-09-18T00:14:40.698287Z","end":"2026-09-18T00:14:40.856577Z","steps":["trace[925593551] 'read index received' (duration: 158.13905ms)","trace[925593551] 'applied index is now lower than readState.Index' (duration: 149.924µs)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:14:40.856724Z","caller":"traceutil/trace.go:171","msg":"trace[1660807370] transaction","detail":"{read_only:false; response_revision:6732; number_of_response:1; }","duration":"223.001223ms","start":"2026-09-18T00:14:40.633697Z","end":"2026-09-18T00:14:40.856698Z","steps":["trace[1660807370] 'process raft request' (duration: 222.768628ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:14:40.856831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.530928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-09-18T00:14:40.856869Z","caller":"traceutil/trace.go:171","msg":"trace[1797903004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6732; }","duration":"158.627691ms","start":"2026-09-18T00:14:40.698231Z","end":"2026-09-18T00:14:40.856858Z","steps":["trace[1797903004] 'agreement among raft nodes before linearized reading' (duration: 158.430428ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:14:44.413261Z","caller":"traceutil/trace.go:171","msg":"trace[335031854] linearizableReadLoop","detail":"{readStateIndex:7015; appliedIndex:7014; }","duration":"111.760164ms","start":"2026-09-18T00:14:44.301473Z","end":"2026-09-18T00:14:44.413234Z","steps":["trace[335031854] 'read index received' (duration: 106.219155ms)","trace[335031854] 'applied index is now lower than readState.Index' (duration: 5.540159ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:14:44.413569Z","caller":"traceutil/trace.go:171","msg":"trace[1832926944] transaction","detail":"{read_only:false; response_revision:6762; number_of_response:1; }","duration":"123.147659ms","start":"2026-09-18T00:14:44.290409Z","end":"2026-09-18T00:14:44.413556Z","steps":["trace[1832926944] 'process raft request' (duration: 117.306464ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:14:44.414034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.524229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated\" ","response":"range_response_count:1 size:1343"} {"level":"info","ts":"2026-09-18T00:14:44.414129Z","caller":"traceutil/trace.go:171","msg":"trace[1395378927] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-generated; range_end:; response_count:1; response_revision:6762; }","duration":"112.653922ms","start":"2026-09-18T00:14:44.301451Z","end":"2026-09-18T00:14:44.414105Z","steps":["trace[1395378927] 'agreement among raft nodes before linearized reading' (duration: 112.231514ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:14:44.443344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.904863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-18T00:14:44.443419Z","caller":"traceutil/trace.go:171","msg":"trace[950515945] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6763; }","duration":"124.998745ms","start":"2026-09-18T00:14:44.318397Z","end":"2026-09-18T00:14:44.443396Z","steps":["trace[950515945] 'agreement among raft nodes before linearized reading' (duration: 124.909523ms)"],"step_count":1} {"level":"warn","ts":"2026-09-18T00:14:44.443752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.383016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-09-18T00:14:44.443792Z","caller":"traceutil/trace.go:171","msg":"trace[1462325726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6763; }","duration":"113.446768ms","start":"2026-09-18T00:14:44.330335Z","end":"2026-09-18T00:14:44.443782Z","steps":["trace[1462325726] 'agreement among raft nodes before linearized reading' (duration: 112.931107ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:15:58.264647Z","caller":"traceutil/trace.go:171","msg":"trace[322585660] transaction","detail":"{read_only:false; response_revision:7319; number_of_response:1; }","duration":"111.638337ms","start":"2026-09-18T00:15:58.15298Z","end":"2026-09-18T00:15:58.264618Z","steps":["trace[322585660] 'process raft request' (duration: 111.401682ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:15:58.264937Z","caller":"traceutil/trace.go:171","msg":"trace[1486537285] transaction","detail":"{read_only:false; response_revision:7318; number_of_response:1; }","duration":"114.491218ms","start":"2026-09-18T00:15:58.15043Z","end":"2026-09-18T00:15:58.264921Z","steps":["trace[1486537285] 'process raft request' (duration: 96.96516ms)","trace[1486537285] 'compare' (duration: 16.598308ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:15:58.2654Z","caller":"traceutil/trace.go:171","msg":"trace[1329492713] transaction","detail":"{read_only:false; response_revision:7320; number_of_response:1; }","duration":"111.728948ms","start":"2026-09-18T00:15:58.153656Z","end":"2026-09-18T00:15:58.265385Z","steps":["trace[1329492713] 'process raft request' (duration: 110.84573ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:15:58.266123Z","caller":"traceutil/trace.go:171","msg":"trace[3795034] transaction","detail":"{read_only:false; response_revision:7321; number_of_response:1; }","duration":"108.469588ms","start":"2026-09-18T00:15:58.157638Z","end":"2026-09-18T00:15:58.266108Z","steps":["trace[3795034] 'process raft request' (duration: 106.923435ms)"],"step_count":1} {"level":"info","ts":"2026-09-18T00:15:58.389888Z","caller":"traceutil/trace.go:171","msg":"trace[834964161] transaction","detail":"{read_only:false; response_revision:7323; number_of_response:1; }","duration":"100.886514ms","start":"2026-09-18T00:15:58.288986Z","end":"2026-09-18T00:15:58.389872Z","steps":["trace[834964161] 'process raft request' (duration: 81.959346ms)","trace[834964161] 'compare' (duration: 18.395827ms)"],"step_count":2} {"level":"info","ts":"2026-09-18T00:16:04.114474Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5095} {"level":"info","ts":"2026-09-18T00:16:04.203921Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5095,"took":"88.310665ms","hash":1538971675,"current-db-size-bytes":51232768,"current-db-size":"51 MB","current-db-size-in-use-bytes":19644416,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-09-18T00:16:04.204Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1538971675,"revision":5095,"compact-revision":2819}