{"level":"warn","ts":"2026-03-11T01:42:14.653556Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-11T01:42:14.653646Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70: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.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70: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-03-11T01:42:14.653716Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-11T01:42:14.653727Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-03-11T01:42:14.653746Z","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-03-11T01:42:14.654353Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-03-11T01:42:14.654433Z","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":16,"max-cpu-available":16,"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.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70: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-03-11T01:42:14.657776Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.051679ms"} {"level":"info","ts":"2026-03-11T01:42:14.668886Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-03-11T01:42:14.668985Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-03-11T01:42:14.669008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-03-11T01:42:14.669027Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-11T01:42:14.669045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-03-11T01:42:14.669096Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-03-11T01:42:14.673731Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-11T01:42:14.676191Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-11T01:42:14.678539Z","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-03-11T01:42:14.680551Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-11T01:42:14.680749Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-11T01:42:14.680898Z","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-03-11T01:42:14.681014Z","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-03-11T01:42:14.681033Z","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-03-11T01:42:14.683731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-03-11T01:42:14.684013Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-03-11T01:42:14.689668Z","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-03-11T01:42:14.689764Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-03-11T01:42:14.68984Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-03-11T01:42:14.690216Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-11T01:42:14.690345Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-11T01:42:15.570157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-03-11T01:42:15.57024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-03-11T01:42:15.570271Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-03-11T01:42:15.570295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-03-11T01:42:15.570308Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-03-11T01:42:15.570348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-03-11T01:42:15.570366Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-03-11T01:42:15.571455Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-03-11T01:42:15.571505Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-11T01:42:15.571529Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-11T01:42:15.571761Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-11T01:42:15.571723Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T01:42:15.571852Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-11T01:42:15.57316Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T01:42:15.573306Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T01:42:15.573349Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-11T01:42:15.575055Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-03-11T01:42:15.576013Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-11T01:42:21.758411Z","caller":"traceutil/trace.go:171","msg":"trace[1385995185] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"117.751182ms","start":"2026-03-11T01:42:21.640637Z","end":"2026-03-11T01:42:21.758388Z","steps":["trace[1385995185] 'process raft request' (duration: 117.597998ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:42:21.758431Z","caller":"traceutil/trace.go:171","msg":"trace[1853110120] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"117.31833ms","start":"2026-03-11T01:42:21.641091Z","end":"2026-03-11T01:42:21.758409Z","steps":["trace[1853110120] 'process raft request' (duration: 117.233428ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:42:21.758568Z","caller":"traceutil/trace.go:171","msg":"trace[454456773] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"116.762896ms","start":"2026-03-11T01:42:21.64177Z","end":"2026-03-11T01:42:21.758533Z","steps":["trace[454456773] 'process raft request' (duration: 116.580162ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:42:21.758756Z","caller":"traceutil/trace.go:171","msg":"trace[1753296149] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"106.885604ms","start":"2026-03-11T01:42:21.651854Z","end":"2026-03-11T01:42:21.75874Z","steps":["trace[1753296149] 'process raft request' (duration: 106.77227ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:42:21.758849Z","caller":"traceutil/trace.go:171","msg":"trace[771840565] transaction","detail":"{read_only:false; number_of_response:0; response_revision:304; }","duration":"116.429209ms","start":"2026-03-11T01:42:21.642415Z","end":"2026-03-11T01:42:21.758844Z","steps":["trace[771840565] 'process raft request' (duration: 116.190212ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:42:21.758903Z","caller":"traceutil/trace.go:171","msg":"trace[1545454278] transaction","detail":"{read_only:false; response_revision:306; number_of_response:1; }","duration":"101.588407ms","start":"2026-03-11T01:42:21.657305Z","end":"2026-03-11T01:42:21.758893Z","steps":["trace[1545454278] 'process raft request' (duration: 101.394602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:29.159306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.288963ms","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-03-11T01:42:29.159387Z","caller":"traceutil/trace.go:171","msg":"trace[1127635258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:403; }","duration":"273.473788ms","start":"2026-03-11T01:42:28.8859Z","end":"2026-03-11T01:42:29.159374Z","steps":["trace[1127635258] 'range keys from in-memory index tree' (duration: 273.103499ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:34.620057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.955072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:42:34.620162Z","caller":"traceutil/trace.go:171","msg":"trace[945601392] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:448; }","duration":"117.120227ms","start":"2026-03-11T01:42:34.503015Z","end":"2026-03-11T01:42:34.620135Z","steps":["trace[945601392] 'range keys from in-memory index tree' (duration: 116.841749ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:34.62016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.7425ms","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-03-11T01:42:34.620288Z","caller":"traceutil/trace.go:171","msg":"trace[605972351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:448; }","duration":"173.905604ms","start":"2026-03-11T01:42:34.446353Z","end":"2026-03-11T01:42:34.620258Z","steps":["trace[605972351] 'range keys from in-memory index tree' (duration: 173.485352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:45.638165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.920158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:42:45.638263Z","caller":"traceutil/trace.go:171","msg":"trace[436199894] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:507; }","duration":"136.064883ms","start":"2026-03-11T01:42:45.502178Z","end":"2026-03-11T01:42:45.638243Z","steps":["trace[436199894] 'range keys from in-memory index tree' (duration: 135.852617ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:45.638612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.592883ms","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-03-11T01:42:45.638669Z","caller":"traceutil/trace.go:171","msg":"trace[1813815668] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:507; }","duration":"222.678615ms","start":"2026-03-11T01:42:45.415978Z","end":"2026-03-11T01:42:45.638656Z","steps":["trace[1813815668] 'range keys from in-memory index tree' (duration: 222.490981ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:42:45.940797Z","caller":"traceutil/trace.go:171","msg":"trace[93688096] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"219.085233ms","start":"2026-03-11T01:42:45.72169Z","end":"2026-03-11T01:42:45.940775Z","steps":["trace[93688096] 'process raft request' (duration: 218.96373ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:42:47.799271Z","caller":"traceutil/trace.go:171","msg":"trace[1894773345] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"118.844319ms","start":"2026-03-11T01:42:47.680398Z","end":"2026-03-11T01:42:47.799242Z","steps":["trace[1894773345] 'process raft request' (duration: 80.825774ms)","trace[1894773345] 'compare' (duration: 37.909873ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:42:52.818782Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133286605885867,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-11T01:42:52.915508Z","caller":"traceutil/trace.go:171","msg":"trace[1225554650] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"597.053745ms","start":"2026-03-11T01:42:52.31843Z","end":"2026-03-11T01:42:52.915483Z","steps":["trace[1225554650] 'read index received' (duration: 596.785548ms)","trace[1225554650] 'applied index is now lower than readState.Index' (duration: 265.237µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:42:52.915616Z","caller":"traceutil/trace.go:171","msg":"trace[1336836632] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"630.15561ms","start":"2026-03-11T01:42:52.285451Z","end":"2026-03-11T01:42:52.915607Z","steps":["trace[1336836632] 'process raft request' (duration: 629.867153ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:52.915966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.519888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-11T01:42:52.916029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.053486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-lzdtg\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-03-11T01:42:52.916057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.878839ms","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-03-11T01:42:52.916115Z","caller":"traceutil/trace.go:171","msg":"trace[1613896562] range","detail":"{range_begin:/registry/pods/kube-system/cilium-lzdtg; range_end:; response_count:1; response_revision:550; }","duration":"171.171809ms","start":"2026-03-11T01:42:52.744924Z","end":"2026-03-11T01:42:52.916095Z","steps":["trace[1613896562] 'agreement among raft nodes before linearized reading' (duration: 170.961324ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:52.916103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.800292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:42:52.916196Z","caller":"traceutil/trace.go:171","msg":"trace[1121264163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:550; }","duration":"417.1512ms","start":"2026-03-11T01:42:52.499037Z","end":"2026-03-11T01:42:52.916188Z","steps":["trace[1121264163] 'agreement among raft nodes before linearized reading' (duration: 416.797731ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:42:52.91606Z","caller":"traceutil/trace.go:171","msg":"trace[2088540631] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:550; }","duration":"597.640171ms","start":"2026-03-11T01:42:52.318376Z","end":"2026-03-11T01:42:52.916016Z","steps":["trace[2088540631] 'agreement among raft nodes before linearized reading' (duration: 597.532878ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:52.916236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:52.285426Z","time spent":"630.215591ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:42:52.916347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:52.499019Z","time spent":"417.205872ms","remote":"127.0.0.1:42844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-11T01:42:52.916403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:52.318353Z","time spent":"597.926597ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"info","ts":"2026-03-11T01:42:52.916254Z","caller":"traceutil/trace.go:171","msg":"trace[1962756829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:550; }","duration":"186.035484ms","start":"2026-03-11T01:42:52.730114Z","end":"2026-03-11T01:42:52.916149Z","steps":["trace[1962756829] 'agreement among raft nodes before linearized reading' (duration: 185.758397ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:53.235994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.696998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:42:53.23618Z","caller":"traceutil/trace.go:171","msg":"trace[474111430] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"315.630799ms","start":"2026-03-11T01:42:52.920521Z","end":"2026-03-11T01:42:53.236152Z","steps":["trace[474111430] 'process raft request' (duration: 130.556721ms)","trace[474111430] 'compare' (duration: 184.597676ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:42:53.236232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:52.920503Z","time spent":"315.703961ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:42:53.630256Z","caller":"traceutil/trace.go:171","msg":"trace[358248054] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:572; }","duration":"368.700418ms","start":"2026-03-11T01:42:53.261531Z","end":"2026-03-11T01:42:53.630231Z","steps":["trace[358248054] 'read index received' (duration: 357.084979ms)","trace[358248054] 'applied index is now lower than readState.Index' (duration: 11.614299ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:42:53.630417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.874322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:42:53.630404Z","caller":"traceutil/trace.go:171","msg":"trace[1618272286] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"702.084635ms","start":"2026-03-11T01:42:52.928283Z","end":"2026-03-11T01:42:53.630368Z","steps":["trace[1618272286] 'process raft request' (duration: 690.285121ms)","trace[1618272286] 'compare' (duration: 11.438225ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:42:53.630484Z","caller":"traceutil/trace.go:171","msg":"trace[579313295] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:552; }","duration":"368.980887ms","start":"2026-03-11T01:42:53.261488Z","end":"2026-03-11T01:42:53.630468Z","steps":["trace[579313295] 'agreement among raft nodes before linearized reading' (duration: 368.879923ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:53.630523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:53.261471Z","time spent":"369.043808ms","remote":"127.0.0.1:42926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":27,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"warn","ts":"2026-03-11T01:42:53.630582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.920403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:42:53.630672Z","caller":"traceutil/trace.go:171","msg":"trace[1068374526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"128.077266ms","start":"2026-03-11T01:42:53.502575Z","end":"2026-03-11T01:42:53.630652Z","steps":["trace[1068374526] 'agreement among raft nodes before linearized reading' (duration: 127.903272ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:53.630608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:52.928261Z","time spent":"702.254401ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:42:53.630778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.19681ms","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-03-11T01:42:53.631489Z","caller":"traceutil/trace.go:171","msg":"trace[171736875] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:552; }","duration":"269.96652ms","start":"2026-03-11T01:42:53.361499Z","end":"2026-03-11T01:42:53.631466Z","steps":["trace[171736875] 'agreement among raft nodes before linearized reading' (duration: 268.924333ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:54.239643Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133286605885877,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-11T01:42:54.740292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133286605885877,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-11T01:42:54.874499Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.154121728s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-11T01:42:55.976337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10156898s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-11T01:42:55.97642Z","caller":"traceutil/trace.go:171","msg":"trace[15487019] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"2.237631622s","start":"2026-03-11T01:42:53.738774Z","end":"2026-03-11T01:42:55.976406Z","steps":["trace[15487019] 'read index received' (duration: 1.135879568s)","trace[15487019] 'applied index is now lower than readState.Index' (duration: 1.101750904s)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:42:55.976521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.237747875s","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-03-11T01:42:55.976541Z","caller":"traceutil/trace.go:171","msg":"trace[1387899903] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"2.237823237s","start":"2026-03-11T01:42:53.738712Z","end":"2026-03-11T01:42:55.976535Z","steps":["trace[1387899903] 'agreement among raft nodes before linearized reading' (duration: 2.237761584s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:55.976564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:53.738646Z","time spent":"2.23791234s","remote":"127.0.0.1:43086","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-03-11T01:42:55.976648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.353158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:42:55.976685Z","caller":"traceutil/trace.go:171","msg":"trace[1580394769] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"474.413058ms","start":"2026-03-11T01:42:55.502262Z","end":"2026-03-11T01:42:55.976675Z","steps":["trace[1580394769] 'agreement among raft nodes before linearized reading' (duration: 474.334346ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:55.976701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:55.502249Z","time spent":"474.44807ms","remote":"127.0.0.1:42834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-11T01:42:55.976688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056299122s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-11T01:42:55.976771Z","caller":"traceutil/trace.go:171","msg":"trace[791010121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"1.056427685s","start":"2026-03-11T01:42:54.920324Z","end":"2026-03-11T01:42:55.976752Z","steps":["trace[791010121] 'agreement among raft nodes before linearized reading' (duration: 1.056243681s)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:55.976811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:54.920288Z","time spent":"1.056516497s","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-11T01:42:55.976835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"735.989731ms","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-03-11T01:42:55.976904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.561729ms","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-03-11T01:42:55.976966Z","caller":"traceutil/trace.go:171","msg":"trace[734871548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"736.165856ms","start":"2026-03-11T01:42:55.240773Z","end":"2026-03-11T01:42:55.976939Z","steps":["trace[734871548] 'agreement among raft nodes before linearized reading' (duration: 735.822807ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:42:55.976992Z","caller":"traceutil/trace.go:171","msg":"trace[31577132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"262.692012ms","start":"2026-03-11T01:42:55.714277Z","end":"2026-03-11T01:42:55.976969Z","steps":["trace[31577132] 'agreement among raft nodes before linearized reading' (duration: 262.405025ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:42:55.977038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:42:55.240753Z","time spent":"736.269869ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-11T01:42:57.120507Z","caller":"traceutil/trace.go:171","msg":"trace[1864452017] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"225.821001ms","start":"2026-03-11T01:42:56.894656Z","end":"2026-03-11T01:42:57.120477Z","steps":["trace[1864452017] 'process raft request' (duration: 138.732372ms)","trace[1864452017] 'compare' (duration: 86.938544ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:43:01.094635Z","caller":"traceutil/trace.go:171","msg":"trace[189638781] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"134.140412ms","start":"2026-03-11T01:43:00.960471Z","end":"2026-03-11T01:43:01.094611Z","steps":["trace[189638781] 'process raft request' (duration: 94.326514ms)","trace[189638781] 'compare' (duration: 39.365656ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:43:18.211309Z","caller":"traceutil/trace.go:171","msg":"trace[868522611] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"112.004762ms","start":"2026-03-11T01:43:18.099285Z","end":"2026-03-11T01:43:18.211289Z","steps":["trace[868522611] 'process raft request' (duration: 108.83069ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:18.211595Z","caller":"traceutil/trace.go:171","msg":"trace[617932863] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"111.862378ms","start":"2026-03-11T01:43:18.099702Z","end":"2026-03-11T01:43:18.211564Z","steps":["trace[617932863] 'process raft request' (duration: 111.55089ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:18.211613Z","caller":"traceutil/trace.go:171","msg":"trace[75997515] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"111.262803ms","start":"2026-03-11T01:43:18.100316Z","end":"2026-03-11T01:43:18.211579Z","steps":["trace[75997515] 'process raft request' (duration: 111.149009ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:20.375379Z","caller":"traceutil/trace.go:171","msg":"trace[2049828210] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"325.554246ms","start":"2026-03-11T01:43:20.049798Z","end":"2026-03-11T01:43:20.375352Z","steps":["trace[2049828210] 'read index received' (duration: 297.882312ms)","trace[2049828210] 'applied index is now lower than readState.Index' (duration: 27.671144ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:43:20.37546Z","caller":"traceutil/trace.go:171","msg":"trace[880675730] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"402.105692ms","start":"2026-03-11T01:43:19.97332Z","end":"2026-03-11T01:43:20.375425Z","steps":["trace[880675730] 'process raft request' (duration: 374.310415ms)","trace[880675730] 'compare' (duration: 27.589472ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:43:20.37558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.742091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T01:43:20.375626Z","caller":"traceutil/trace.go:171","msg":"trace[1501264852] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:934; }","duration":"325.858144ms","start":"2026-03-11T01:43:20.049757Z","end":"2026-03-11T01:43:20.375615Z","steps":["trace[1501264852] 'agreement among raft nodes before linearized reading' (duration: 325.739591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:20.37564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:43:19.973303Z","time spent":"402.229545ms","remote":"127.0.0.1:43254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:43:20.375666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:43:20.049715Z","time spent":"325.940616ms","remote":"127.0.0.1:43254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":6,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-03-11T01:43:20.375913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.186066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-11T01:43:20.375931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.278129ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-11T01:43:20.375965Z","caller":"traceutil/trace.go:171","msg":"trace[68910658] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"160.231527ms","start":"2026-03-11T01:43:20.215718Z","end":"2026-03-11T01:43:20.37595Z","steps":["trace[68910658] 'agreement among raft nodes before linearized reading' (duration: 160.083724ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:20.37598Z","caller":"traceutil/trace.go:171","msg":"trace[706575961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"160.369531ms","start":"2026-03-11T01:43:20.215598Z","end":"2026-03-11T01:43:20.375967Z","steps":["trace[706575961] 'agreement among raft nodes before linearized reading' (duration: 160.238998ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:20.375992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.707805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-11T01:43:20.376161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.78602ms","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-03-11T01:43:20.376222Z","caller":"traceutil/trace.go:171","msg":"trace[905102475] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:934; }","duration":"160.870954ms","start":"2026-03-11T01:43:20.215332Z","end":"2026-03-11T01:43:20.376203Z","steps":["trace[905102475] 'agreement among raft nodes before linearized reading' (duration: 160.733959ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:20.376224Z","caller":"traceutil/trace.go:171","msg":"trace[35322618] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:934; }","duration":"154.847838ms","start":"2026-03-11T01:43:20.221233Z","end":"2026-03-11T01:43:20.37608Z","steps":["trace[35322618] 'agreement among raft nodes before linearized reading' (duration: 154.596421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:20.376023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.076645ms","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-03-11T01:43:20.376341Z","caller":"traceutil/trace.go:171","msg":"trace[161669331] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:934; }","duration":"279.444905ms","start":"2026-03-11T01:43:20.096884Z","end":"2026-03-11T01:43:20.376329Z","steps":["trace[161669331] 'agreement among raft nodes before linearized reading' (duration: 278.919331ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:20.621714Z","caller":"traceutil/trace.go:171","msg":"trace[1860435199] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"241.565087ms","start":"2026-03-11T01:43:20.380119Z","end":"2026-03-11T01:43:20.621684Z","steps":["trace[1860435199] 'process raft request' (duration: 216.757397ms)","trace[1860435199] 'compare' (duration: 24.526223ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:43:20.62527Z","caller":"traceutil/trace.go:171","msg":"trace[758982869] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:963; }","duration":"121.849796ms","start":"2026-03-11T01:43:20.503392Z","end":"2026-03-11T01:43:20.625242Z","steps":["trace[758982869] 'read index received' (duration: 93.457794ms)","trace[758982869] 'applied index is now lower than readState.Index' (duration: 28.390832ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:43:20.625301Z","caller":"traceutil/trace.go:171","msg":"trace[107853353] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"243.668271ms","start":"2026-03-11T01:43:20.381602Z","end":"2026-03-11T01:43:20.625271Z","steps":["trace[107853353] 'process raft request' (duration: 243.563699ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:20.625377Z","caller":"traceutil/trace.go:171","msg":"trace[52916736] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"243.746603ms","start":"2026-03-11T01:43:20.381598Z","end":"2026-03-11T01:43:20.625345Z","steps":["trace[52916736] 'process raft request' (duration: 243.606699ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:43:20.625462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.040332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:43:20.625514Z","caller":"traceutil/trace.go:171","msg":"trace[869082355] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:938; }","duration":"122.155705ms","start":"2026-03-11T01:43:20.503341Z","end":"2026-03-11T01:43:20.625497Z","steps":["trace[869082355] 'agreement among raft nodes before linearized reading' (duration: 122.031171ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:20.625923Z","caller":"traceutil/trace.go:171","msg":"trace[1129895697] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"244.040901ms","start":"2026-03-11T01:43:20.381588Z","end":"2026-03-11T01:43:20.625629Z","steps":["trace[1129895697] 'process raft request' (duration: 243.446445ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:21.149208Z","caller":"traceutil/trace.go:171","msg":"trace[328479151] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"161.954742ms","start":"2026-03-11T01:43:20.987226Z","end":"2026-03-11T01:43:21.14918Z","steps":["trace[328479151] 'process raft request' (duration: 161.778397ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:23.040611Z","caller":"traceutil/trace.go:171","msg":"trace[371526335] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"121.743983ms","start":"2026-03-11T01:43:22.91884Z","end":"2026-03-11T01:43:23.040584Z","steps":["trace[371526335] 'process raft request' (duration: 121.501477ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:43:29.072365Z","caller":"traceutil/trace.go:171","msg":"trace[1692197155] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"127.295467ms","start":"2026-03-11T01:43:28.945049Z","end":"2026-03-11T01:43:29.072344Z","steps":["trace[1692197155] 'process raft request' (duration: 127.145174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:11.49541Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133286605887908,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-11T01:44:11.68549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.326297ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:44:11.685695Z","caller":"traceutil/trace.go:171","msg":"trace[51125647] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1606; }","duration":"117.503741ms","start":"2026-03-11T01:44:11.568128Z","end":"2026-03-11T01:44:11.685631Z","steps":["trace[51125647] 'range keys from in-memory index tree' (duration: 117.308336ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:11.724543Z","caller":"traceutil/trace.go:171","msg":"trace[299149922] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"773.518569ms","start":"2026-03-11T01:44:10.950995Z","end":"2026-03-11T01:44:11.724514Z","steps":["trace[299149922] 'process raft request' (duration: 773.339455ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:11.724765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:10.950972Z","time spent":"773.657593ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:44:11.747989Z","caller":"traceutil/trace.go:171","msg":"trace[181548485] linearizableReadLoop","detail":"{readStateIndex:1672; appliedIndex:1670; }","duration":"752.967284ms","start":"2026-03-11T01:44:10.994997Z","end":"2026-03-11T01:44:11.747965Z","steps":["trace[181548485] 'read index received' (duration: 729.310447ms)","trace[181548485] 'applied index is now lower than readState.Index' (duration: 23.655877ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:44:11.748116Z","caller":"traceutil/trace.go:171","msg":"trace[74053870] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"795.973705ms","start":"2026-03-11T01:44:10.9521Z","end":"2026-03-11T01:44:11.748074Z","steps":["trace[74053870] 'process raft request' (duration: 795.74374ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:11.748261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.923189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-11T01:44:11.748307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.530602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T01:44:11.748344Z","caller":"traceutil/trace.go:171","msg":"trace[1343938683] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1608; }","duration":"320.623046ms","start":"2026-03-11T01:44:11.427708Z","end":"2026-03-11T01:44:11.748331Z","steps":["trace[1343938683] 'agreement among raft nodes before linearized reading' (duration: 320.523173ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:11.748348Z","caller":"traceutil/trace.go:171","msg":"trace[457993111] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1608; }","duration":"123.086274ms","start":"2026-03-11T01:44:11.625239Z","end":"2026-03-11T01:44:11.748325Z","steps":["trace[457993111] 'agreement among raft nodes before linearized reading' (duration: 122.913409ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:11.748329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.610303ms","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-03-11T01:44:11.748386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.139882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-11T01:44:11.748501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:10.952073Z","time spent":"796.108739ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:44:11.748501Z","caller":"traceutil/trace.go:171","msg":"trace[88746888] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1608; }","duration":"245.324746ms","start":"2026-03-11T01:44:11.503156Z","end":"2026-03-11T01:44:11.748481Z","steps":["trace[88746888] 'agreement among raft nodes before linearized reading' (duration: 245.131201ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:11.748433Z","caller":"traceutil/trace.go:171","msg":"trace[14302804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1608; }","duration":"750.748408ms","start":"2026-03-11T01:44:10.997661Z","end":"2026-03-11T01:44:11.748409Z","steps":["trace[14302804] 'agreement among raft nodes before linearized reading' (duration: 750.48358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:11.748375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:11.42768Z","time spent":"320.686997ms","remote":"127.0.0.1:42974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":29,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-03-11T01:44:11.748807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.815786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10667"} {"level":"info","ts":"2026-03-11T01:44:11.749028Z","caller":"traceutil/trace.go:171","msg":"trace[269435181] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1608; }","duration":"580.095213ms","start":"2026-03-11T01:44:11.168908Z","end":"2026-03-11T01:44:11.749003Z","steps":["trace[269435181] 'agreement among raft nodes before linearized reading' (duration: 579.482948ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:11.749119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:11.16888Z","time spent":"580.225437ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10689,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-03-11T01:44:11.748813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:10.997647Z","time spent":"751.134657ms","remote":"127.0.0.1:43086","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-03-11T01:44:11.749669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.670787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-11T01:44:11.749781Z","caller":"traceutil/trace.go:171","msg":"trace[1164950436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1608; }","duration":"754.852761ms","start":"2026-03-11T01:44:10.994914Z","end":"2026-03-11T01:44:11.749767Z","steps":["trace[1164950436] 'agreement among raft nodes before linearized reading' (duration: 753.178369ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:11.749961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:10.994888Z","time spent":"755.059028ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-11T01:44:12.443541Z","caller":"traceutil/trace.go:171","msg":"trace[702940738] transaction","detail":"{read_only:false; response_revision:1614; number_of_response:1; }","duration":"582.694299ms","start":"2026-03-11T01:44:11.860828Z","end":"2026-03-11T01:44:12.443523Z","steps":["trace[702940738] 'process raft request' (duration: 582.559356ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:12.443698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:11.860804Z","time spent":"582.817104ms","remote":"127.0.0.1:43096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:44:12.443537Z","caller":"traceutil/trace.go:171","msg":"trace[1205810910] transaction","detail":"{read_only:false; response_revision:1613; number_of_response:1; }","duration":"583.044618ms","start":"2026-03-11T01:44:11.860461Z","end":"2026-03-11T01:44:12.443506Z","steps":["trace[1205810910] 'process raft request' (duration: 544.624724ms)","trace[1205810910] 'compare' (duration: 38.048785ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:44:12.443856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:11.860431Z","time spent":"583.318556ms","remote":"127.0.0.1:42988","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:44:12.444054Z","caller":"traceutil/trace.go:171","msg":"trace[2127782694] transaction","detail":"{read_only:false; response_revision:1616; number_of_response:1; }","duration":"577.111527ms","start":"2026-03-11T01:44:11.86693Z","end":"2026-03-11T01:44:12.444041Z","steps":["trace[2127782694] 'process raft request' (duration: 576.716407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:12.444124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:11.866894Z","time spent":"577.194049ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3728,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:44:12.444131Z","caller":"traceutil/trace.go:171","msg":"trace[992060123] transaction","detail":"{read_only:false; response_revision:1615; number_of_response:1; }","duration":"581.970631ms","start":"2026-03-11T01:44:11.86203Z","end":"2026-03-11T01:44:12.444001Z","steps":["trace[992060123] 'process raft request' (duration: 581.452477ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:12.444254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:11.862015Z","time spent":"582.188867ms","remote":"127.0.0.1:43096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:44:12.444247Z","caller":"traceutil/trace.go:171","msg":"trace[471076109] linearizableReadLoop","detail":"{readStateIndex:1679; appliedIndex:1675; }","duration":"580.510094ms","start":"2026-03-11T01:44:11.863717Z","end":"2026-03-11T01:44:12.444227Z","steps":["trace[471076109] 'read index received' (duration: 16.433072ms)","trace[471076109] 'applied index is now lower than readState.Index' (duration: 564.075172ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:44:12.44435Z","caller":"traceutil/trace.go:171","msg":"trace[1985049644] transaction","detail":"{read_only:false; response_revision:1618; number_of_response:1; }","duration":"174.196564ms","start":"2026-03-11T01:44:12.270136Z","end":"2026-03-11T01:44:12.444333Z","steps":["trace[1985049644] 'process raft request' (duration: 174.099971ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:12.444463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.745801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-11T01:44:12.444491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.114807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8e95a845-4eb9-4cf8-b361-f3938a2bc7c5\" ","response":"range_response_count:1 size:3743"} {"level":"info","ts":"2026-03-11T01:44:12.444511Z","caller":"traceutil/trace.go:171","msg":"trace[539809555] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1618; }","duration":"580.832013ms","start":"2026-03-11T01:44:11.863666Z","end":"2026-03-11T01:44:12.444498Z","steps":["trace[539809555] 'agreement among raft nodes before linearized reading' (duration: 580.668059ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:12.444531Z","caller":"traceutil/trace.go:171","msg":"trace[1765782037] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8e95a845-4eb9-4cf8-b361-f3938a2bc7c5; range_end:; response_count:1; response_revision:1618; }","duration":"577.192459ms","start":"2026-03-11T01:44:11.867327Z","end":"2026-03-11T01:44:12.444519Z","steps":["trace[1765782037] 'agreement among raft nodes before linearized reading' (duration: 577.046946ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:12.444567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:11.867312Z","time spent":"577.244671ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3765,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8e95a845-4eb9-4cf8-b361-f3938a2bc7c5\" "} {"level":"warn","ts":"2026-03-11T01:44:12.444558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:11.863653Z","time spent":"580.894504ms","remote":"127.0.0.1:43086","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-03-11T01:44:12.444946Z","caller":"traceutil/trace.go:171","msg":"trace[16036092] transaction","detail":"{read_only:false; response_revision:1617; number_of_response:1; }","duration":"259.089178ms","start":"2026-03-11T01:44:12.185842Z","end":"2026-03-11T01:44:12.444931Z","steps":["trace[16036092] 'process raft request' (duration: 258.220666ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:18.575778Z","caller":"traceutil/trace.go:171","msg":"trace[1232930643] transaction","detail":"{read_only:false; response_revision:1677; number_of_response:1; }","duration":"114.111154ms","start":"2026-03-11T01:44:18.461613Z","end":"2026-03-11T01:44:18.575725Z","steps":["trace[1232930643] 'process raft request' (duration: 113.911388ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:18.677396Z","caller":"traceutil/trace.go:171","msg":"trace[925322918] linearizableReadLoop","detail":"{readStateIndex:1743; appliedIndex:1741; }","duration":"209.453616ms","start":"2026-03-11T01:44:18.467923Z","end":"2026-03-11T01:44:18.677377Z","steps":["trace[925322918] 'read index received' (duration: 107.647547ms)","trace[925322918] 'applied index is now lower than readState.Index' (duration: 101.805459ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:44:18.677462Z","caller":"traceutil/trace.go:171","msg":"trace[1577236432] transaction","detail":"{read_only:false; response_revision:1679; number_of_response:1; }","duration":"178.663768ms","start":"2026-03-11T01:44:18.498769Z","end":"2026-03-11T01:44:18.677433Z","steps":["trace[1577236432] 'process raft request' (duration: 178.572046ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:18.677514Z","caller":"traceutil/trace.go:171","msg":"trace[1112064395] transaction","detail":"{read_only:false; response_revision:1678; number_of_response:1; }","duration":"214.357772ms","start":"2026-03-11T01:44:18.463092Z","end":"2026-03-11T01:44:18.67745Z","steps":["trace[1112064395] 'process raft request' (duration: 187.910774ms)","trace[1112064395] 'compare' (duration: 26.252293ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:44:18.677609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.803213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:44:18.677703Z","caller":"traceutil/trace.go:171","msg":"trace[1190078905] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1679; }","duration":"173.948357ms","start":"2026-03-11T01:44:18.503731Z","end":"2026-03-11T01:44:18.677679Z","steps":["trace[1190078905] 'agreement among raft nodes before linearized reading' (duration: 173.791023ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:18.678022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.077992ms","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-03-11T01:44:18.678129Z","caller":"traceutil/trace.go:171","msg":"trace[1869632227] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1679; }","duration":"210.232487ms","start":"2026-03-11T01:44:18.467881Z","end":"2026-03-11T01:44:18.678114Z","steps":["trace[1869632227] 'agreement among raft nodes before linearized reading' (duration: 209.663232ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:20.008501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.117933ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:44:20.008608Z","caller":"traceutil/trace.go:171","msg":"trace[1941817672] transaction","detail":"{read_only:false; response_revision:1685; number_of_response:1; }","duration":"120.337584ms","start":"2026-03-11T01:44:19.888253Z","end":"2026-03-11T01:44:20.008591Z","steps":["trace[1941817672] 'compare' (duration: 111.970979ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:20.013817Z","caller":"traceutil/trace.go:171","msg":"trace[1823856942] transaction","detail":"{read_only:false; response_revision:1686; number_of_response:1; }","duration":"124.492861ms","start":"2026-03-11T01:44:19.889299Z","end":"2026-03-11T01:44:20.013792Z","steps":["trace[1823856942] 'process raft request' (duration: 124.341866ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:25.135347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133286605888134,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-11T01:44:25.242356Z","caller":"traceutil/trace.go:171","msg":"trace[891387255] transaction","detail":"{read_only:false; response_revision:1712; number_of_response:1; }","duration":"649.323298ms","start":"2026-03-11T01:44:24.59301Z","end":"2026-03-11T01:44:25.242333Z","steps":["trace[891387255] 'process raft request' (duration: 649.148063ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:25.242532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:24.592963Z","time spent":"649.459562ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:44:25.262837Z","caller":"traceutil/trace.go:171","msg":"trace[1690099189] linearizableReadLoop","detail":"{readStateIndex:1782; appliedIndex:1779; }","duration":"628.516954ms","start":"2026-03-11T01:44:24.634287Z","end":"2026-03-11T01:44:25.262804Z","steps":["trace[1690099189] 'read index received' (duration: 607.875266ms)","trace[1690099189] 'applied index is now lower than readState.Index' (duration: 20.640708ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:44:25.26294Z","caller":"traceutil/trace.go:171","msg":"trace[1278870407] transaction","detail":"{read_only:false; response_revision:1714; number_of_response:1; }","duration":"565.983822ms","start":"2026-03-11T01:44:24.69693Z","end":"2026-03-11T01:44:25.262914Z","steps":["trace[1278870407] 'process raft request' (duration: 565.842689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:25.263072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.524672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11988"} {"level":"info","ts":"2026-03-11T01:44:25.2631Z","caller":"traceutil/trace.go:171","msg":"trace[1253612636] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1714; }","duration":"622.591113ms","start":"2026-03-11T01:44:24.6405Z","end":"2026-03-11T01:44:25.263091Z","steps":["trace[1253612636] 'agreement among raft nodes before linearized reading' (duration: 622.436939ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:25.26312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:24.640484Z","time spent":"622.632324ms","remote":"127.0.0.1:43008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12010,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-03-11T01:44:25.263123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"628.766951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T01:44:25.263276Z","caller":"traceutil/trace.go:171","msg":"trace[1680818461] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1714; }","duration":"629.010667ms","start":"2026-03-11T01:44:24.634238Z","end":"2026-03-11T01:44:25.263248Z","steps":["trace[1680818461] 'agreement among raft nodes before linearized reading' (duration: 628.704309ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:25.263281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.014548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-03-11T01:44:25.263292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.493729ms","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-03-11T01:44:25.263543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:24.634214Z","time spent":"629.313525ms","remote":"127.0.0.1:55806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":12,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"info","ts":"2026-03-11T01:44:25.263556Z","caller":"traceutil/trace.go:171","msg":"trace[1616103001] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1714; }","duration":"558.295025ms","start":"2026-03-11T01:44:24.705239Z","end":"2026-03-11T01:44:25.263534Z","steps":["trace[1616103001] 'agreement among raft nodes before linearized reading' (duration: 557.847484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:25.26356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.04697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-11T01:44:25.263606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:24.705209Z","time spent":"558.387127ms","remote":"127.0.0.1:43086","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-03-11T01:44:25.263601Z","caller":"traceutil/trace.go:171","msg":"trace[953030855] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1714; }","duration":"355.858468ms","start":"2026-03-11T01:44:24.907716Z","end":"2026-03-11T01:44:25.263574Z","steps":["trace[953030855] 'agreement among raft nodes before linearized reading' (duration: 355.305265ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:25.263667Z","caller":"traceutil/trace.go:171","msg":"trace[1326802977] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1714; }","duration":"567.214994ms","start":"2026-03-11T01:44:24.696428Z","end":"2026-03-11T01:44:25.263643Z","steps":["trace[1326802977] 'agreement among raft nodes before linearized reading' (duration: 566.752443ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:25.263782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:24.907694Z","time spent":"356.030063ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-03-11T01:44:25.263818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:24.696407Z","time spent":"567.399489ms","remote":"127.0.0.1:42930","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":8,"response size":29,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-03-11T01:44:25.263113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:24.696908Z","time spent":"566.084834ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:44:25.263973Z","caller":"traceutil/trace.go:171","msg":"trace[1825576447] transaction","detail":"{read_only:false; response_revision:1713; number_of_response:1; }","duration":"567.08679ms","start":"2026-03-11T01:44:24.696862Z","end":"2026-03-11T01:44:25.263949Z","steps":["trace[1825576447] 'process raft request' (duration: 565.752736ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:25.26412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:44:24.696832Z","time spent":"567.212683ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:44:35.420833Z","caller":"traceutil/trace.go:171","msg":"trace[281309338] linearizableReadLoop","detail":"{readStateIndex:1848; appliedIndex:1847; }","duration":"106.216792ms","start":"2026-03-11T01:44:35.314595Z","end":"2026-03-11T01:44:35.420812Z","steps":["trace[281309338] 'read index received' (duration: 106.063527ms)","trace[281309338] 'applied index is now lower than readState.Index' (duration: 152.265µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:44:35.420871Z","caller":"traceutil/trace.go:171","msg":"trace[1294169716] transaction","detail":"{read_only:false; response_revision:1778; number_of_response:1; }","duration":"116.983247ms","start":"2026-03-11T01:44:35.303872Z","end":"2026-03-11T01:44:35.420855Z","steps":["trace[1294169716] 'process raft request' (duration: 116.795913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:44:35.421031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.426077ms","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-03-11T01:44:35.421061Z","caller":"traceutil/trace.go:171","msg":"trace[644748474] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1778; }","duration":"106.499199ms","start":"2026-03-11T01:44:35.314554Z","end":"2026-03-11T01:44:35.421053Z","steps":["trace[644748474] 'agreement among raft nodes before linearized reading' (duration: 106.378836ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:51.292393Z","caller":"traceutil/trace.go:171","msg":"trace[497320220] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"108.133301ms","start":"2026-03-11T01:44:51.184234Z","end":"2026-03-11T01:44:51.292367Z","steps":["trace[497320220] 'process raft request' (duration: 107.991957ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:44:56.27428Z","caller":"traceutil/trace.go:171","msg":"trace[1527775527] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"124.948072ms","start":"2026-03-11T01:44:56.149295Z","end":"2026-03-11T01:44:56.274243Z","steps":["trace[1527775527] 'process raft request' (duration: 110.750568ms)","trace[1527775527] 'compare' (duration: 14.05622ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:44:56.690616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.805589ms","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-03-11T01:44:56.690702Z","caller":"traceutil/trace.go:171","msg":"trace[502175679] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1884; }","duration":"103.949273ms","start":"2026-03-11T01:44:56.586732Z","end":"2026-03-11T01:44:56.690682Z","steps":["trace[502175679] 'range keys from in-memory index tree' (duration: 103.577664ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:02.467487Z","caller":"traceutil/trace.go:171","msg":"trace[52425527] linearizableReadLoop","detail":"{readStateIndex:1986; appliedIndex:1985; }","duration":"257.224491ms","start":"2026-03-11T01:45:02.210235Z","end":"2026-03-11T01:45:02.467459Z","steps":["trace[52425527] 'read index received' (duration: 257.082437ms)","trace[52425527] 'applied index is now lower than readState.Index' (duration: 140.654µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:45:02.46771Z","caller":"traceutil/trace.go:171","msg":"trace[642919356] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"296.179978ms","start":"2026-03-11T01:45:02.171489Z","end":"2026-03-11T01:45:02.467669Z","steps":["trace[642919356] 'process raft request' (duration: 295.818268ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:02.467804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.542569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-11T01:45:02.467941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.447747ms","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-03-11T01:45:02.468053Z","caller":"traceutil/trace.go:171","msg":"trace[1846861103] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1911; }","duration":"257.820726ms","start":"2026-03-11T01:45:02.210176Z","end":"2026-03-11T01:45:02.467996Z","steps":["trace[1846861103] 'agreement among raft nodes before linearized reading' (duration: 257.484708ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:02.468126Z","caller":"traceutil/trace.go:171","msg":"trace[927733803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1911; }","duration":"105.706235ms","start":"2026-03-11T01:45:02.362398Z","end":"2026-03-11T01:45:02.468104Z","steps":["trace[927733803] 'agreement among raft nodes before linearized reading' (duration: 105.461558ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:02.46816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.897053ms","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-03-11T01:45:02.468303Z","caller":"traceutil/trace.go:171","msg":"trace[1482755303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1911; }","duration":"173.060577ms","start":"2026-03-11T01:45:02.295194Z","end":"2026-03-11T01:45:02.468255Z","steps":["trace[1482755303] 'agreement among raft nodes before linearized reading' (duration: 172.569534ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:03.835978Z","caller":"traceutil/trace.go:171","msg":"trace[1072071266] transaction","detail":"{read_only:false; response_revision:1915; number_of_response:1; }","duration":"438.316314ms","start":"2026-03-11T01:45:03.397637Z","end":"2026-03-11T01:45:03.835953Z","steps":["trace[1072071266] 'process raft request' (duration: 438.14537ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:03.83614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:03.397609Z","time spent":"438.446647ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:45:04.360445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.762557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:45:04.360699Z","caller":"traceutil/trace.go:171","msg":"trace[1019977856] linearizableReadLoop","detail":"{readStateIndex:1992; appliedIndex:1989; }","duration":"858.144415ms","start":"2026-03-11T01:45:03.502536Z","end":"2026-03-11T01:45:04.36068Z","steps":["trace[1019977856] 'read index received' (duration: 333.300817ms)","trace[1019977856] 'applied index is now lower than readState.Index' (duration: 524.842718ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:45:04.360696Z","caller":"traceutil/trace.go:171","msg":"trace[124325648] transaction","detail":"{read_only:false; response_revision:1916; number_of_response:1; }","duration":"960.930066ms","start":"2026-03-11T01:45:03.399725Z","end":"2026-03-11T01:45:04.360655Z","steps":["trace[124325648] 'process raft request' (duration: 569.85556ms)","trace[124325648] 'compare' (duration: 390.428429ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:45:04.360737Z","caller":"traceutil/trace.go:171","msg":"trace[487604250] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"956.920663ms","start":"2026-03-11T01:45:03.403785Z","end":"2026-03-11T01:45:04.360706Z","steps":["trace[487604250] 'process raft request' (duration: 956.77774ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:04.360851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.32231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:45:04.360892Z","caller":"traceutil/trace.go:171","msg":"trace[516356971] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1917; }","duration":"858.406422ms","start":"2026-03-11T01:45:03.502475Z","end":"2026-03-11T01:45:04.360882Z","steps":["trace[516356971] 'agreement among raft nodes before linearized reading' (duration: 858.253459ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:04.36089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:03.403763Z","time spent":"957.045846ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:45:04.360901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:03.399707Z","time spent":"961.073809ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:45:04.360926Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:03.502426Z","time spent":"858.491054ms","remote":"127.0.0.1:42834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-11T01:45:04.536701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.007502ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-03-11T01:45:04.53744Z","caller":"traceutil/trace.go:171","msg":"trace[235249893] linearizableReadLoop","detail":"{readStateIndex:1994; appliedIndex:1992; }","duration":"176.674429ms","start":"2026-03-11T01:45:04.360739Z","end":"2026-03-11T01:45:04.537414Z","steps":["trace[235249893] 'read index received' (duration: 44.631µs)","trace[235249893] 'applied index is now lower than readState.Index' (duration: 176.628318ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:45:04.537619Z","caller":"traceutil/trace.go:171","msg":"trace[1551480157] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"612.977903ms","start":"2026-03-11T01:45:03.924609Z","end":"2026-03-11T01:45:04.537587Z","steps":["trace[1551480157] 'process raft request' (duration: 612.394698ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:04.537657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.693277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.189ba6131304ecf7\" ","response":"range_response_count:1 size:846"} {"level":"warn","ts":"2026-03-11T01:45:04.537701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.760279ms","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-03-11T01:45:04.537713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.020318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:45:04.537738Z","caller":"traceutil/trace.go:171","msg":"trace[1814241599] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.189ba6131304ecf7; range_end:; response_count:1; response_revision:1918; }","duration":"385.828191ms","start":"2026-03-11T01:45:04.151885Z","end":"2026-03-11T01:45:04.537714Z","steps":["trace[1814241599] 'agreement among raft nodes before linearized reading' (duration: 385.539913ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:04.537791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:03.924583Z","time spent":"613.127297ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:45:04.537788Z","caller":"traceutil/trace.go:171","msg":"trace[205386131] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1918; }","duration":"795.891802ms","start":"2026-03-11T01:45:03.741874Z","end":"2026-03-11T01:45:04.537766Z","steps":["trace[205386131] 'agreement among raft nodes before linearized reading' (duration: 795.606004ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:04.537798Z","caller":"traceutil/trace.go:171","msg":"trace[1933504209] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1918; }","duration":"816.158091ms","start":"2026-03-11T01:45:03.721619Z","end":"2026-03-11T01:45:04.537777Z","steps":["trace[1933504209] 'agreement among raft nodes before linearized reading' (duration: 815.996987ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:04.537804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:04.15186Z","time spent":"385.927233ms","remote":"127.0.0.1:42900","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":868,"request content":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.189ba6131304ecf7\" "} {"level":"warn","ts":"2026-03-11T01:45:04.537852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:03.741861Z","time spent":"795.980745ms","remote":"127.0.0.1:59224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-03-11T01:45:04.537854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:03.721595Z","time spent":"816.248304ms","remote":"127.0.0.1:54728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-03-11T01:45:04.538035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.433008ms","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-03-11T01:45:04.538141Z","caller":"traceutil/trace.go:171","msg":"trace[1687106314] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1918; }","duration":"970.585381ms","start":"2026-03-11T01:45:03.56753Z","end":"2026-03-11T01:45:04.538116Z","steps":["trace[1687106314] 'agreement among raft nodes before linearized reading' (duration: 970.256723ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:04.538208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:03.567515Z","time spent":"970.682844ms","remote":"127.0.0.1:43086","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-03-11T01:45:04.539069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.699171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:45:04.539207Z","caller":"traceutil/trace.go:171","msg":"trace[1033996886] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1918; }","duration":"176.965698ms","start":"2026-03-11T01:45:04.362208Z","end":"2026-03-11T01:45:04.539174Z","steps":["trace[1033996886] 'agreement among raft nodes before linearized reading' (duration: 175.832458ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:45:54.837368Z","caller":"traceutil/trace.go:171","msg":"trace[1555044034] transaction","detail":"{read_only:false; response_revision:2334; number_of_response:1; }","duration":"303.229028ms","start":"2026-03-11T01:45:54.534108Z","end":"2026-03-11T01:45:54.837337Z","steps":["trace[1555044034] 'process raft request' (duration: 303.019642ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:45:54.837758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:45:54.534066Z","time spent":"303.587618ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-11T01:45:54.838104Z","caller":"traceutil/trace.go:171","msg":"trace[1733696807] transaction","detail":"{read_only:false; response_revision:2335; number_of_response:1; }","duration":"296.336003ms","start":"2026-03-11T01:45:54.54174Z","end":"2026-03-11T01:45:54.838076Z","steps":["trace[1733696807] 'process raft request' (duration: 296.187188ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:29.176861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.942529ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:46:29.176991Z","caller":"traceutil/trace.go:171","msg":"trace[2039929154] transaction","detail":"{read_only:false; response_revision:2523; number_of_response:1; }","duration":"282.702053ms","start":"2026-03-11T01:46:28.894264Z","end":"2026-03-11T01:46:29.176966Z","steps":["trace[2039929154] 'process raft request' (duration: 49.560578ms)","trace[2039929154] 'compare' (duration: 232.840826ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:46:29.560911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.974692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:46:29.561293Z","caller":"traceutil/trace.go:171","msg":"trace[1658429468] linearizableReadLoop","detail":"{readStateIndex:2626; appliedIndex:2624; }","duration":"492.848714ms","start":"2026-03-11T01:46:29.068422Z","end":"2026-03-11T01:46:29.56127Z","steps":["trace[1658429468] 'read index received' (duration: 276.42625ms)","trace[1658429468] 'applied index is now lower than readState.Index' (duration: 216.421114ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:46:29.561425Z","caller":"traceutil/trace.go:171","msg":"trace[183379586] transaction","detail":"{read_only:false; response_revision:2524; number_of_response:1; }","duration":"585.827584ms","start":"2026-03-11T01:46:28.975581Z","end":"2026-03-11T01:46:29.561409Z","steps":["trace[183379586] 'process raft request' (duration: 369.256376ms)","trace[183379586] 'compare' (duration: 215.826899ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:46:29.561517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:28.97555Z","time spent":"585.918526ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:46:29.561555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.131022ms","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-03-11T01:46:29.561567Z","caller":"traceutil/trace.go:171","msg":"trace[880144089] transaction","detail":"{read_only:false; response_revision:2525; number_of_response:1; }","duration":"566.440923ms","start":"2026-03-11T01:46:28.995102Z","end":"2026-03-11T01:46:29.561543Z","steps":["trace[880144089] 'process raft request' (duration: 565.972021ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:29.561587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.952718ms","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-03-11T01:46:29.561626Z","caller":"traceutil/trace.go:171","msg":"trace[153103591] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2525; }","duration":"493.246325ms","start":"2026-03-11T01:46:29.068364Z","end":"2026-03-11T01:46:29.56161Z","steps":["trace[153103591] 'agreement among raft nodes before linearized reading' (duration: 493.04085ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:29.561676Z","caller":"traceutil/trace.go:171","msg":"trace[257976097] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2525; }","duration":"488.086712ms","start":"2026-03-11T01:46:29.073569Z","end":"2026-03-11T01:46:29.561656Z","steps":["trace[257976097] 'agreement among raft nodes before linearized reading' (duration: 487.878497ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:29.561678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:29.068339Z","time spent":"493.326896ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":499,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-11T01:46:29.561682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:28.995078Z","time spent":"566.545356ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:46:29.561725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:29.073548Z","time spent":"488.165004ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-11T01:46:29.561881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.937037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-03-11T01:46:29.561917Z","caller":"traceutil/trace.go:171","msg":"trace[1054862663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2525; }","duration":"481.01516ms","start":"2026-03-11T01:46:29.080893Z","end":"2026-03-11T01:46:29.561908Z","steps":["trace[1054862663] 'agreement among raft nodes before linearized reading' (duration: 480.929977ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:29.561943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:29.080853Z","time spent":"481.083742ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-11T01:46:29.561936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.164144ms","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-03-11T01:46:29.56199Z","caller":"traceutil/trace.go:171","msg":"trace[1135438151] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2525; }","duration":"481.240785ms","start":"2026-03-11T01:46:29.080733Z","end":"2026-03-11T01:46:29.561974Z","steps":["trace[1135438151] 'agreement among raft nodes before linearized reading' (duration: 481.108491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:29.562043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:29.08072Z","time spent":"481.311868ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-11T01:46:29.561873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"480.075415ms","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-03-11T01:46:29.562159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.498241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/valkey-node-0.189ba6280e9f8fd0\" ","response":"range_response_count:1 size:765"} {"level":"info","ts":"2026-03-11T01:46:29.562225Z","caller":"traceutil/trace.go:171","msg":"trace[1487138212] range","detail":"{range_begin:/registry/events/openstack/valkey-node-0.189ba6280e9f8fd0; range_end:; response_count:1; response_revision:2525; }","duration":"279.604493ms","start":"2026-03-11T01:46:29.282606Z","end":"2026-03-11T01:46:29.56221Z","steps":["trace[1487138212] 'agreement among raft nodes before linearized reading' (duration: 279.46465ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:29.56217Z","caller":"traceutil/trace.go:171","msg":"trace[1061229730] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2525; }","duration":"480.403853ms","start":"2026-03-11T01:46:29.081754Z","end":"2026-03-11T01:46:29.562157Z","steps":["trace[1061229730] 'agreement among raft nodes before linearized reading' (duration: 480.032763ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:29.562321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:46:29.081741Z","time spent":"480.563107ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-11T01:46:39.786219Z","caller":"traceutil/trace.go:171","msg":"trace[390519904] transaction","detail":"{read_only:false; response_revision:2579; number_of_response:1; }","duration":"156.164139ms","start":"2026-03-11T01:46:39.630021Z","end":"2026-03-11T01:46:39.786185Z","steps":["trace[390519904] 'process raft request' (duration: 90.392712ms)","trace[390519904] 'compare' (duration: 65.52422ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:46:39.78636Z","caller":"traceutil/trace.go:171","msg":"trace[1639570495] linearizableReadLoop","detail":"{readStateIndex:2683; appliedIndex:2681; }","duration":"150.483583ms","start":"2026-03-11T01:46:39.635852Z","end":"2026-03-11T01:46:39.786335Z","steps":["trace[1639570495] 'read index received' (duration: 59.159996ms)","trace[1639570495] 'applied index is now lower than readState.Index' (duration: 91.321817ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:46:39.786432Z","caller":"traceutil/trace.go:171","msg":"trace[2133039742] transaction","detail":"{read_only:false; response_revision:2580; number_of_response:1; }","duration":"156.343223ms","start":"2026-03-11T01:46:39.630053Z","end":"2026-03-11T01:46:39.786396Z","steps":["trace[2133039742] 'process raft request' (duration: 156.052756ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:46:39.786859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.940979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-03-11T01:46:39.787074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.204931ms","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-03-11T01:46:39.787162Z","caller":"traceutil/trace.go:171","msg":"trace[1978357851] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2580; }","duration":"151.327774ms","start":"2026-03-11T01:46:39.635815Z","end":"2026-03-11T01:46:39.787143Z","steps":["trace[1978357851] 'agreement among raft nodes before linearized reading' (duration: 150.617115ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:46:39.787306Z","caller":"traceutil/trace.go:171","msg":"trace[806682652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2580; }","duration":"150.258917ms","start":"2026-03-11T01:46:39.636867Z","end":"2026-03-11T01:46:39.787126Z","steps":["trace[806682652] 'agreement among raft nodes before linearized reading' (duration: 149.810195ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:49:00.233213Z","caller":"traceutil/trace.go:171","msg":"trace[2030563086] transaction","detail":"{read_only:false; response_revision:3445; number_of_response:1; }","duration":"118.473363ms","start":"2026-03-11T01:49:00.114698Z","end":"2026-03-11T01:49:00.233172Z","steps":["trace[2030563086] 'process raft request' (duration: 116.261816ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:50:14.023246Z","caller":"traceutil/trace.go:171","msg":"trace[1682337825] transaction","detail":"{read_only:false; response_revision:4297; number_of_response:1; }","duration":"172.965912ms","start":"2026-03-11T01:50:13.850237Z","end":"2026-03-11T01:50:14.023203Z","steps":["trace[1682337825] 'process raft request' (duration: 135.95464ms)","trace[1682337825] 'compare' (duration: 36.772996ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:50:14.023476Z","caller":"traceutil/trace.go:171","msg":"trace[215339658] linearizableReadLoop","detail":"{readStateIndex:4474; appliedIndex:4473; }","duration":"167.109471ms","start":"2026-03-11T01:50:13.856355Z","end":"2026-03-11T01:50:14.023464Z","steps":["trace[215339658] 'read index received' (duration: 129.726969ms)","trace[215339658] 'applied index is now lower than readState.Index' (duration: 37.380522ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:50:14.042332Z","caller":"traceutil/trace.go:171","msg":"trace[600497712] transaction","detail":"{read_only:false; response_revision:4298; number_of_response:1; }","duration":"127.037721ms","start":"2026-03-11T01:50:13.915273Z","end":"2026-03-11T01:50:14.04231Z","steps":["trace[600497712] 'process raft request' (duration: 126.909357ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:50:14.042367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.972047ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-03-11T01:50:14.042503Z","caller":"traceutil/trace.go:171","msg":"trace[1403823889] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4297; }","duration":"186.155502ms","start":"2026-03-11T01:50:13.856321Z","end":"2026-03-11T01:50:14.042477Z","steps":["trace[1403823889] 'agreement among raft nodes before linearized reading' (duration: 167.227555ms)","trace[1403823889] 'range keys from in-memory index tree' (duration: 18.66149ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:50:14.042629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.668628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:10 size:76142"} {"level":"info","ts":"2026-03-11T01:50:14.042726Z","caller":"traceutil/trace.go:171","msg":"trace[250658657] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:10; response_revision:4298; }","duration":"185.799152ms","start":"2026-03-11T01:50:13.856904Z","end":"2026-03-11T01:50:14.042703Z","steps":["trace[250658657] 'agreement among raft nodes before linearized reading' (duration: 185.401953ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:50:22.165079Z","caller":"traceutil/trace.go:171","msg":"trace[662861637] transaction","detail":"{read_only:false; response_revision:4520; number_of_response:1; }","duration":"275.864771ms","start":"2026-03-11T01:50:21.889188Z","end":"2026-03-11T01:50:22.165053Z","steps":["trace[662861637] 'process raft request' (duration: 275.715527ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:50:22.202857Z","caller":"traceutil/trace.go:171","msg":"trace[1354129023] linearizableReadLoop","detail":"{readStateIndex:4703; appliedIndex:4701; }","duration":"279.523215ms","start":"2026-03-11T01:50:21.923308Z","end":"2026-03-11T01:50:22.202831Z","steps":["trace[1354129023] 'read index received' (duration: 241.627059ms)","trace[1354129023] 'applied index is now lower than readState.Index' (duration: 37.895166ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:50:22.202919Z","caller":"traceutil/trace.go:171","msg":"trace[1866700360] transaction","detail":"{read_only:false; response_revision:4521; number_of_response:1; }","duration":"293.115615ms","start":"2026-03-11T01:50:21.909768Z","end":"2026-03-11T01:50:22.202884Z","steps":["trace[1866700360] 'process raft request' (duration: 292.949281ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:50:22.203145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.896875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-11T01:50:22.203182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.45547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T01:50:22.20321Z","caller":"traceutil/trace.go:171","msg":"trace[1078927297] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:4521; }","duration":"179.017048ms","start":"2026-03-11T01:50:22.024178Z","end":"2026-03-11T01:50:22.203195Z","steps":["trace[1078927297] 'agreement among raft nodes before linearized reading' (duration: 178.870744ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:50:22.203263Z","caller":"traceutil/trace.go:171","msg":"trace[1429188021] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:4521; }","duration":"113.592125ms","start":"2026-03-11T01:50:22.08965Z","end":"2026-03-11T01:50:22.203242Z","steps":["trace[1429188021] 'agreement among raft nodes before linearized reading' (duration: 113.397359ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:50:22.203403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.073099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:714605"} {"level":"info","ts":"2026-03-11T01:50:22.203445Z","caller":"traceutil/trace.go:171","msg":"trace[1831412953] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:4521; }","duration":"280.14947ms","start":"2026-03-11T01:50:21.923283Z","end":"2026-03-11T01:50:22.203433Z","steps":["trace[1831412953] 'agreement among raft nodes before linearized reading' (duration: 279.677608ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:50:22.868698Z","caller":"traceutil/trace.go:171","msg":"trace[1013595379] linearizableReadLoop","detail":"{readStateIndex:4705; appliedIndex:4704; }","duration":"114.60566ms","start":"2026-03-11T01:50:22.754063Z","end":"2026-03-11T01:50:22.868668Z","steps":["trace[1013595379] 'read index received' (duration: 59.086802ms)","trace[1013595379] 'applied index is now lower than readState.Index' (duration: 55.517438ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:50:22.868873Z","caller":"traceutil/trace.go:171","msg":"trace[1486833677] transaction","detail":"{read_only:false; response_revision:4523; number_of_response:1; }","duration":"364.711178ms","start":"2026-03-11T01:50:22.504109Z","end":"2026-03-11T01:50:22.86882Z","steps":["trace[1486833677] 'process raft request' (duration: 309.054746ms)","trace[1486833677] 'compare' (duration: 55.217471ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:50:22.868996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:50:22.504076Z","time spent":"364.839821ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:50:22.869316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.230306ms","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-03-11T01:50:22.869427Z","caller":"traceutil/trace.go:171","msg":"trace[1700733436] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4523; }","duration":"115.39136ms","start":"2026-03-11T01:50:22.754009Z","end":"2026-03-11T01:50:22.8694Z","steps":["trace[1700733436] 'agreement among raft nodes before linearized reading' (duration: 115.118383ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:50:23.825016Z","caller":"traceutil/trace.go:171","msg":"trace[276082431] transaction","detail":"{read_only:false; response_revision:4551; number_of_response:1; }","duration":"150.444673ms","start":"2026-03-11T01:50:23.674536Z","end":"2026-03-11T01:50:23.824981Z","steps":["trace[276082431] 'process raft request' (duration: 73.386829ms)","trace[276082431] 'compare' (duration: 76.814208ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:50:25.266018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.257806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/goldpinger\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-11T01:50:25.266082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.833852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/storage-loki-0.189ba6628b27f28c\" ","response":"range_response_count:1 size:943"} {"level":"info","ts":"2026-03-11T01:50:25.266091Z","caller":"traceutil/trace.go:171","msg":"trace[677528258] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/goldpinger; range_end:; response_count:0; response_revision:4564; }","duration":"183.37075ms","start":"2026-03-11T01:50:25.082702Z","end":"2026-03-11T01:50:25.266073Z","steps":["trace[677528258] 'range keys from in-memory index tree' (duration: 183.200264ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:50:25.266189Z","caller":"traceutil/trace.go:171","msg":"trace[163115111] range","detail":"{range_begin:/registry/events/monitoring/storage-loki-0.189ba6628b27f28c; range_end:; response_count:1; response_revision:4564; }","duration":"204.979617ms","start":"2026-03-11T01:50:25.061177Z","end":"2026-03-11T01:50:25.266156Z","steps":["trace[163115111] 'range keys from in-memory index tree' (duration: 204.604307ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:50:25.266254Z","caller":"traceutil/trace.go:171","msg":"trace[398514054] transaction","detail":"{read_only:false; response_revision:4565; number_of_response:1; }","duration":"144.819465ms","start":"2026-03-11T01:50:25.121408Z","end":"2026-03-11T01:50:25.266228Z","steps":["trace[398514054] 'process raft request' (duration: 137.324132ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:51:05.27583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.129768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/local-path-storage/\" range_end:\"/registry/daemonsets/local-path-storage0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:51:05.275935Z","caller":"traceutil/trace.go:171","msg":"trace[618252445] range","detail":"{range_begin:/registry/daemonsets/local-path-storage/; range_end:/registry/daemonsets/local-path-storage0; response_count:0; response_revision:5079; }","duration":"462.281412ms","start":"2026-03-11T01:51:04.813636Z","end":"2026-03-11T01:51:05.275918Z","steps":["trace[618252445] 'range keys from in-memory index tree' (duration: 462.049006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:51:05.275983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:51:04.81362Z","time spent":"462.351494ms","remote":"127.0.0.1:43258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":87,"response count":0,"response size":27,"request content":"key:\"/registry/daemonsets/local-path-storage/\" range_end:\"/registry/daemonsets/local-path-storage0\" limit:500 "} {"level":"warn","ts":"2026-03-11T01:51:05.275941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.78264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/ingress-nginx/ingress-nginx-defaultbackend\" ","response":"range_response_count:1 size:4494"} {"level":"info","ts":"2026-03-11T01:51:05.276042Z","caller":"traceutil/trace.go:171","msg":"trace[1219159373] range","detail":"{range_begin:/registry/deployments/ingress-nginx/ingress-nginx-defaultbackend; range_end:; response_count:1; response_revision:5079; }","duration":"456.902054ms","start":"2026-03-11T01:51:04.819126Z","end":"2026-03-11T01:51:05.276028Z","steps":["trace[1219159373] 'range keys from in-memory index tree' (duration: 456.663777ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:51:05.276135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:51:04.819117Z","time spent":"457.002576ms","remote":"127.0.0.1:43254","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":4516,"request content":"key:\"/registry/deployments/ingress-nginx/ingress-nginx-defaultbackend\" "} {"level":"warn","ts":"2026-03-11T01:51:05.276251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.08053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1217"} {"level":"warn","ts":"2026-03-11T01:51:05.276288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.027006ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-11T01:51:05.2775Z","caller":"traceutil/trace.go:171","msg":"trace[137704761] transaction","detail":"{read_only:false; response_revision:5080; number_of_response:1; }","duration":"271.232446ms","start":"2026-03-11T01:51:05.006241Z","end":"2026-03-11T01:51:05.277474Z","steps":["trace[137704761] 'process raft request' (duration: 71.964967ms)","trace[137704761] 'compare' (duration: 197.788541ms)"],"step_count":2} {"level":"warn","ts":"2026-03-11T01:51:05.277924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.303213ms","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-03-11T01:51:05.27803Z","caller":"traceutil/trace.go:171","msg":"trace[1899709909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5080; }","duration":"217.404907ms","start":"2026-03-11T01:51:05.060566Z","end":"2026-03-11T01:51:05.277971Z","steps":["trace[1899709909] 'agreement among raft nodes before linearized reading' (duration: 216.700769ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:51:05.278069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"473.225534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/\" range_end:\"/registry/daemonsets/kube-system0\" limit:500 ","response":"range_response_count:2 size:17391"} {"level":"info","ts":"2026-03-11T01:51:05.282775Z","caller":"traceutil/trace.go:171","msg":"trace[1400299071] range","detail":"{range_begin:/registry/daemonsets/kube-system/; range_end:/registry/daemonsets/kube-system0; response_count:2; response_revision:5079; }","duration":"478.859129ms","start":"2026-03-11T01:51:04.80388Z","end":"2026-03-11T01:51:05.282739Z","steps":["trace[1400299071] 'range keys from in-memory index tree' (duration: 472.251569ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:51:05.282887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:51:04.803863Z","time spent":"478.994734ms","remote":"127.0.0.1:43258","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":2,"response size":17413,"request content":"key:\"/registry/daemonsets/kube-system/\" range_end:\"/registry/daemonsets/kube-system0\" limit:500 "} {"level":"info","ts":"2026-03-11T01:51:05.278172Z","caller":"traceutil/trace.go:171","msg":"trace[973247708] linearizableReadLoop","detail":"{readStateIndex:5279; appliedIndex:5278; }","duration":"216.610486ms","start":"2026-03-11T01:51:05.060618Z","end":"2026-03-11T01:51:05.277229Z","steps":["trace[973247708] 'read index received' (duration: 17.578874ms)","trace[973247708] 'applied index is now lower than readState.Index' (duration: 199.027892ms)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:51:05.27824Z","caller":"traceutil/trace.go:171","msg":"trace[1296187878] range","detail":"{range_begin:/registry/services/endpoints/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5079; }","duration":"440.243873ms","start":"2026-03-11T01:51:04.836103Z","end":"2026-03-11T01:51:05.276347Z","steps":["trace[1296187878] 'range keys from in-memory index tree' (duration: 439.889154ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:51:05.284289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.42765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-fd5zc\" ","response":"range_response_count:1 size:6441"} {"level":"warn","ts":"2026-03-11T01:51:05.284364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.809309ms","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-03-11T01:51:05.284353Z","caller":"traceutil/trace.go:171","msg":"trace[1185012356] range","detail":"{range_begin:/registry/pods/openstack/keepalived-fd5zc; range_end:; response_count:1; response_revision:5080; }","duration":"143.521971ms","start":"2026-03-11T01:51:05.140815Z","end":"2026-03-11T01:51:05.284337Z","steps":["trace[1185012356] 'agreement among raft nodes before linearized reading' (duration: 143.365456ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:51:05.284433Z","caller":"traceutil/trace.go:171","msg":"trace[759987823] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5080; }","duration":"162.886451ms","start":"2026-03-11T01:51:05.121528Z","end":"2026-03-11T01:51:05.284415Z","steps":["trace[759987823] 'agreement among raft nodes before linearized reading' (duration: 162.712717ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:51:05.284497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:51:04.83609Z","time spent":"448.215039ms","remote":"127.0.0.1:42988","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":1239,"request content":"key:\"/registry/services/endpoints/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-03-11T01:51:05.284719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.700652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-03-11T01:51:05.284753Z","caller":"traceutil/trace.go:171","msg":"trace[685449006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5080; }","duration":"139.756884ms","start":"2026-03-11T01:51:05.144987Z","end":"2026-03-11T01:51:05.284744Z","steps":["trace[685449006] 'agreement among raft nodes before linearized reading' (duration: 139.190449ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:51:05.285814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.052232ms","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-03-11T01:51:05.285887Z","caller":"traceutil/trace.go:171","msg":"trace[238890326] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5080; }","duration":"167.364986ms","start":"2026-03-11T01:51:05.118501Z","end":"2026-03-11T01:51:05.285866Z","steps":["trace[238890326] 'agreement among raft nodes before linearized reading' (duration: 165.541049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:51:16.339932Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939133286605897817,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-11T01:51:16.394781Z","caller":"traceutil/trace.go:171","msg":"trace[56023575] linearizableReadLoop","detail":"{readStateIndex:5340; appliedIndex:5339; }","duration":"555.129707ms","start":"2026-03-11T01:51:15.839622Z","end":"2026-03-11T01:51:16.394752Z","steps":["trace[56023575] 'read index received' (duration: 554.809538ms)","trace[56023575] 'applied index is now lower than readState.Index' (duration: 318.799µs)"],"step_count":2} {"level":"info","ts":"2026-03-11T01:51:16.394906Z","caller":"traceutil/trace.go:171","msg":"trace[386996981] transaction","detail":"{read_only:false; response_revision:5139; number_of_response:1; }","duration":"610.441592ms","start":"2026-03-11T01:51:15.784386Z","end":"2026-03-11T01:51:16.394828Z","steps":["trace[386996981] 'process raft request' (duration: 610.162275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:51:16.395029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.385113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-03-11T01:51:16.395071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"553.768602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-11T01:51:16.395109Z","caller":"traceutil/trace.go:171","msg":"trace[2108421968] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replication-controller; range_end:; response_count:1; response_revision:5139; }","duration":"555.512596ms","start":"2026-03-11T01:51:15.839577Z","end":"2026-03-11T01:51:16.395089Z","steps":["trace[2108421968] 'agreement among raft nodes before linearized reading' (duration: 555.329091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:51:16.395159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:51:15.839559Z","time spent":"555.587637ms","remote":"127.0.0.1:43020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replication-controller\" "} {"level":"warn","ts":"2026-03-11T01:51:16.395156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.404426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-03-11T01:51:16.39516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:51:15.784368Z","time spent":"610.612977ms","remote":"127.0.0.1:43086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-11T01:51:16.39516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.186696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:51:16.395211Z","caller":"traceutil/trace.go:171","msg":"trace[185300176] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5139; }","duration":"217.501819ms","start":"2026-03-11T01:51:16.177695Z","end":"2026-03-11T01:51:16.395197Z","steps":["trace[185300176] 'agreement among raft nodes before linearized reading' (duration: 217.322645ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:51:16.395235Z","caller":"traceutil/trace.go:171","msg":"trace[1404307356] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:5139; }","duration":"273.340099ms","start":"2026-03-11T01:51:16.121879Z","end":"2026-03-11T01:51:16.395219Z","steps":["trace[1404307356] 'agreement among raft nodes before linearized reading' (duration: 273.163535ms)"],"step_count":1} {"level":"info","ts":"2026-03-11T01:51:16.395165Z","caller":"traceutil/trace.go:171","msg":"trace[2007524675] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:5139; }","duration":"553.875934ms","start":"2026-03-11T01:51:15.841254Z","end":"2026-03-11T01:51:16.39513Z","steps":["trace[2007524675] 'agreement among raft nodes before linearized reading' (duration: 553.689339ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:51:16.395343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-11T01:51:15.841246Z","time spent":"554.058208ms","remote":"127.0.0.1:43020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-03-11T01:51:16.704754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.522628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:51:16.705002Z","caller":"traceutil/trace.go:171","msg":"trace[1753266393] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:5139; }","duration":"241.814887ms","start":"2026-03-11T01:51:16.463174Z","end":"2026-03-11T01:51:16.704989Z","steps":["trace[1753266393] 'range keys from in-memory index tree' (duration: 241.463187ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:51:16.704988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.020996ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-11T01:51:16.705059Z","caller":"traceutil/trace.go:171","msg":"trace[661866682] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5139; }","duration":"203.329064ms","start":"2026-03-11T01:51:16.501711Z","end":"2026-03-11T01:51:16.70504Z","steps":["trace[661866682] 'range keys from in-memory index tree' (duration: 202.933093ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:51:16.705079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.816319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-11T01:51:16.7052Z","caller":"traceutil/trace.go:171","msg":"trace[1294258629] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:5139; }","duration":"276.953273ms","start":"2026-03-11T01:51:16.42819Z","end":"2026-03-11T01:51:16.705144Z","steps":["trace[1294258629] 'count revisions from in-memory index tree' (duration: 276.732267ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:51:16.705527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.068545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:85 size:63141"} {"level":"info","ts":"2026-03-11T01:51:16.70556Z","caller":"traceutil/trace.go:171","msg":"trace[1456916360] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:85; response_revision:5139; }","duration":"277.120756ms","start":"2026-03-11T01:51:16.428432Z","end":"2026-03-11T01:51:16.705553Z","steps":["trace[1456916360] 'range keys from in-memory index tree' (duration: 276.752637ms)"],"step_count":1} {"level":"warn","ts":"2026-03-11T01:51:16.705643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.803749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/\" range_end:\"/registry/events/kube-system0\" limit:500 ","response":"range_response_count:85 size:63141"} {"level":"info","ts":"2026-03-11T01:51:16.705717Z","caller":"traceutil/trace.go:171","msg":"trace[1852957387] range","detail":"{range_begin:/registry/events/kube-system/; range_end:/registry/events/kube-system0; response_count:85; response_revision:5139; }","duration":"276.909051ms","start":"2026-03-11T01:51:16.428789Z","end":"2026-03-11T01:51:16.705698Z","steps":["trace[1852957387] 'range keys from in-memory index tree' (duration: 276.342797ms)"],"step_count":1}