{"level":"warn","ts":"2026-02-26T01:42:06.236902Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T01:42:06.237039Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.169:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.169:2380","--initial-cluster=instance=https://199.204.45.169:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.169:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.169: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-02-26T01:42:06.237162Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-26T01:42:06.237186Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-26T01:42:06.237221Z","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-02-26T01:42:06.238142Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"]} {"level":"info","ts":"2026-02-26T01:42:06.238245Z","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://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.169: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-02-26T01:42:06.245082Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.57848ms"} {"level":"info","ts":"2026-02-26T01:42:06.252782Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1c917701ce165bac","cluster-id":"bfb5247022b87cb1"} {"level":"info","ts":"2026-02-26T01:42:06.25291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=()"} {"level":"info","ts":"2026-02-26T01:42:06.252951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 0"} {"level":"info","ts":"2026-02-26T01:42:06.252971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1c917701ce165bac [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-26T01:42:06.252994Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became follower at term 1"} {"level":"info","ts":"2026-02-26T01:42:06.253049Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"warn","ts":"2026-02-26T01:42:06.26053Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-26T01:42:06.262941Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-26T01:42:06.264372Z","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-02-26T01:42:06.267344Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1c917701ce165bac","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-26T01:42:06.26772Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1c917701ce165bac","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-26T01:42:06.26785Z","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-02-26T01:42:06.267971Z","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-02-26T01:42:06.267993Z","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-02-26T01:42:06.269008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac switched to configuration voters=(2058557354321271724)"} {"level":"info","ts":"2026-02-26T01:42:06.269114Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","added-peer-id":"1c917701ce165bac","added-peer-peer-urls":["https://199.204.45.169:2380"]} {"level":"info","ts":"2026-02-26T01:42:06.272751Z","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-02-26T01:42:06.272948Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-26T01:42:06.273028Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.169:2380"} {"level":"info","ts":"2026-02-26T01:42:06.274311Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1c917701ce165bac","initial-advertise-peer-urls":["https://199.204.45.169:2380"],"listen-peer-urls":["https://199.204.45.169:2380"],"advertise-client-urls":["https://199.204.45.169:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.169:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-26T01:42:06.274357Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-26T01:42:07.053861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac is starting a new election at term 1"} {"level":"info","ts":"2026-02-26T01:42:07.05392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became pre-candidate at term 1"} {"level":"info","ts":"2026-02-26T01:42:07.053943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgPreVoteResp from 1c917701ce165bac at term 1"} {"level":"info","ts":"2026-02-26T01:42:07.053962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became candidate at term 2"} {"level":"info","ts":"2026-02-26T01:42:07.053974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac received MsgVoteResp from 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-26T01:42:07.05399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1c917701ce165bac became leader at term 2"} {"level":"info","ts":"2026-02-26T01:42:07.054003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1c917701ce165bac elected leader 1c917701ce165bac at term 2"} {"level":"info","ts":"2026-02-26T01:42:07.055216Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:42:07.056273Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1c917701ce165bac","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.169:2379]}","request-path":"/0/members/1c917701ce165bac/attributes","cluster-id":"bfb5247022b87cb1","publish-timeout":"7s"} {"level":"info","ts":"2026-02-26T01:42:07.056312Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T01:42:07.056356Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-26T01:42:07.056672Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-26T01:42:07.056767Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-26T01:42:07.057018Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bfb5247022b87cb1","local-member-id":"1c917701ce165bac","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:42:07.057281Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:42:07.057341Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-26T01:42:07.059534Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.169:2379"} {"level":"info","ts":"2026-02-26T01:42:07.060038Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-26T01:42:15.930898Z","caller":"traceutil/trace.go:171","msg":"trace[1591959197] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"124.079134ms","start":"2026-02-26T01:42:15.806801Z","end":"2026-02-26T01:42:15.93088Z","steps":["trace[1591959197] 'process raft request' (duration: 29.203726ms)","trace[1591959197] 'compare' (duration: 94.787706ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:16.604865Z","caller":"traceutil/trace.go:171","msg":"trace[929450793] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"106.986528ms","start":"2026-02-26T01:42:16.497849Z","end":"2026-02-26T01:42:16.604835Z","steps":["trace[929450793] 'process raft request' (duration: 24.600405ms)","trace[929450793] 'compare' (duration: 81.806531ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:25.856868Z","caller":"traceutil/trace.go:171","msg":"trace[1504959946] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"297.304618ms","start":"2026-02-26T01:42:25.559539Z","end":"2026-02-26T01:42:25.856843Z","steps":["trace[1504959946] 'process raft request' (duration: 297.080754ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:35.140718Z","caller":"traceutil/trace.go:171","msg":"trace[1708877905] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"108.344615ms","start":"2026-02-26T01:42:35.032345Z","end":"2026-02-26T01:42:35.140689Z","steps":["trace[1708877905] 'process raft request' (duration: 107.985168ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:35.283441Z","caller":"traceutil/trace.go:171","msg":"trace[591026244] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"111.826884ms","start":"2026-02-26T01:42:35.171584Z","end":"2026-02-26T01:42:35.283411Z","steps":["trace[591026244] 'process raft request' (duration: 52.470764ms)","trace[591026244] 'compare' (duration: 59.152966ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:36.900611Z","caller":"traceutil/trace.go:171","msg":"trace[271030334] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"102.865927ms","start":"2026-02-26T01:42:36.797722Z","end":"2026-02-26T01:42:36.900588Z","steps":["trace[271030334] 'read index received' (duration: 102.764465ms)","trace[271030334] 'applied index is now lower than readState.Index' (duration: 100.632µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:36.900676Z","caller":"traceutil/trace.go:171","msg":"trace[1528873720] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"153.619328ms","start":"2026-02-26T01:42:36.747008Z","end":"2026-02-26T01:42:36.900628Z","steps":["trace[1528873720] 'process raft request' (duration: 153.447674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:36.900848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.109022ms","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-02-26T01:42:36.900975Z","caller":"traceutil/trace.go:171","msg":"trace[349146245] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:507; }","duration":"103.288546ms","start":"2026-02-26T01:42:36.797664Z","end":"2026-02-26T01:42:36.900953Z","steps":["trace[349146245] 'agreement among raft nodes before linearized reading' (duration: 103.061621ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:37.163983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.499501ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:42:37.164176Z","caller":"traceutil/trace.go:171","msg":"trace[1866226452] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"228.148485ms","start":"2026-02-26T01:42:36.936007Z","end":"2026-02-26T01:42:37.164155Z","steps":["trace[1866226452] 'read index received' (duration: 97.754585ms)","trace[1866226452] 'applied index is now lower than readState.Index' (duration: 130.39238ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:42:37.16439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.38413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:42:37.164454Z","caller":"traceutil/trace.go:171","msg":"trace[504387942] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"228.485042ms","start":"2026-02-26T01:42:36.935959Z","end":"2026-02-26T01:42:37.164444Z","steps":["trace[504387942] 'agreement among raft nodes before linearized reading' (duration: 228.261887ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:37.164896Z","caller":"traceutil/trace.go:171","msg":"trace[116394691] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"259.172456ms","start":"2026-02-26T01:42:36.9057Z","end":"2026-02-26T01:42:37.164872Z","steps":["trace[116394691] 'process raft request' (duration: 128.149635ms)","trace[116394691] 'compare' (duration: 128.243626ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:44.04791Z","caller":"traceutil/trace.go:171","msg":"trace[1177606411] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:571; }","duration":"138.443611ms","start":"2026-02-26T01:42:43.909442Z","end":"2026-02-26T01:42:44.047885Z","steps":["trace[1177606411] 'read index received' (duration: 138.129665ms)","trace[1177606411] 'applied index is now lower than readState.Index' (duration: 312.956µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:44.047967Z","caller":"traceutil/trace.go:171","msg":"trace[974698330] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"179.237139ms","start":"2026-02-26T01:42:43.868693Z","end":"2026-02-26T01:42:44.04793Z","steps":["trace[974698330] 'process raft request' (duration: 178.962854ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:44.048078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.607495ms","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-02-26T01:42:44.048169Z","caller":"traceutil/trace.go:171","msg":"trace[641658519] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:551; }","duration":"138.737898ms","start":"2026-02-26T01:42:43.909409Z","end":"2026-02-26T01:42:44.048147Z","steps":["trace[641658519] 'agreement among raft nodes before linearized reading' (duration: 138.580515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:44.350448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.975192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:42:44.350531Z","caller":"traceutil/trace.go:171","msg":"trace[429425057] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:552; }","duration":"139.102645ms","start":"2026-02-26T01:42:44.211409Z","end":"2026-02-26T01:42:44.350512Z","steps":["trace[429425057] 'range keys from in-memory index tree' (duration: 138.841919ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:45.217199Z","caller":"traceutil/trace.go:171","msg":"trace[1915715412] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"228.263141ms","start":"2026-02-26T01:42:44.98891Z","end":"2026-02-26T01:42:45.217173Z","steps":["trace[1915715412] 'process raft request' (duration: 228.104688ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:45.762859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.368512ms","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-02-26T01:42:45.762934Z","caller":"traceutil/trace.go:171","msg":"trace[1459401334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"481.484315ms","start":"2026-02-26T01:42:45.281432Z","end":"2026-02-26T01:42:45.762917Z","steps":["trace[1459401334] 'range keys from in-memory index tree' (duration: 481.180399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:45.762976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:45.281413Z","time spent":"481.553457ms","remote":"127.0.0.1:53058","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-02-26T01:42:45.772185Z","caller":"traceutil/trace.go:171","msg":"trace[1989526109] linearizableReadLoop","detail":"{readStateIndex:575; appliedIndex:574; }","duration":"447.955662ms","start":"2026-02-26T01:42:45.324196Z","end":"2026-02-26T01:42:45.772152Z","steps":["trace[1989526109] 'read index received' (duration: 435.681688ms)","trace[1989526109] 'applied index is now lower than readState.Index' (duration: 12.270653ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:42:45.772337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.131935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:42:45.772412Z","caller":"traceutil/trace.go:171","msg":"trace[1186133537] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:554; }","duration":"448.227396ms","start":"2026-02-26T01:42:45.324169Z","end":"2026-02-26T01:42:45.772396Z","steps":["trace[1186133537] 'agreement among raft nodes before linearized reading' (duration: 448.089044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:45.772455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:45.324156Z","time spent":"448.290928ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-26T01:42:45.772457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.205911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/cert-manager/cert-manager-cainjector\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:42:45.772526Z","caller":"traceutil/trace.go:171","msg":"trace[763528679] range","detail":"{range_begin:/registry/serviceaccounts/cert-manager/cert-manager-cainjector; range_end:; response_count:0; response_revision:554; }","duration":"169.332224ms","start":"2026-02-26T01:42:45.603157Z","end":"2026-02-26T01:42:45.772489Z","steps":["trace[763528679] 'agreement among raft nodes before linearized reading' (duration: 169.205202ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:46.455862Z","caller":"traceutil/trace.go:171","msg":"trace[1316389307] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"288.083656ms","start":"2026-02-26T01:42:46.167746Z","end":"2026-02-26T01:42:46.45583Z","steps":["trace[1316389307] 'process raft request' (duration: 226.068798ms)","trace[1316389307] 'compare' (duration: 61.870655ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:46.473381Z","caller":"traceutil/trace.go:171","msg":"trace[693574896] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"305.552302ms","start":"2026-02-26T01:42:46.167802Z","end":"2026-02-26T01:42:46.473354Z","steps":["trace[693574896] 'process raft request' (duration: 305.335507ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:46.473681Z","caller":"traceutil/trace.go:171","msg":"trace[2115333626] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"305.617732ms","start":"2026-02-26T01:42:46.167972Z","end":"2026-02-26T01:42:46.47359Z","steps":["trace[2115333626] 'process raft request' (duration: 305.266245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:46.473839Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:46.167959Z","time spent":"305.811566ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1174,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:42:46.474021Z","caller":"traceutil/trace.go:171","msg":"trace[1498928971] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"286.074105ms","start":"2026-02-26T01:42:46.187919Z","end":"2026-02-26T01:42:46.473994Z","steps":["trace[1498928971] 'process raft request' (duration: 285.390742ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:46.47417Z","caller":"traceutil/trace.go:171","msg":"trace[748325636] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:633; }","duration":"300.502311ms","start":"2026-02-26T01:42:46.173646Z","end":"2026-02-26T01:42:46.474149Z","steps":["trace[748325636] 'read index received' (duration: 220.09911ms)","trace[748325636] 'applied index is now lower than readState.Index' (duration: 80.402021ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:42:46.474183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:46.167779Z","time spent":"305.663763ms","remote":"127.0.0.1:53140","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1156,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:42:46.474222Z","caller":"traceutil/trace.go:171","msg":"trace[1470674939] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"285.454122ms","start":"2026-02-26T01:42:46.188744Z","end":"2026-02-26T01:42:46.474198Z","steps":["trace[1470674939] 'process raft request' (duration: 285.182857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:46.474416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.765836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:1 size:3871"} {"level":"warn","ts":"2026-02-26T01:42:46.474455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.380466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:42:46.474468Z","caller":"traceutil/trace.go:171","msg":"trace[280612246] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:1; response_revision:616; }","duration":"300.877409ms","start":"2026-02-26T01:42:46.173575Z","end":"2026-02-26T01:42:46.474452Z","steps":["trace[280612246] 'agreement among raft nodes before linearized reading' (duration: 300.706775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:46.474483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.882337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:42:46.474527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:42:46.173549Z","time spent":"300.966211ms","remote":"127.0.0.1:53142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":3894,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-02-26T01:42:46.47457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.06123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:42:46.474621Z","caller":"traceutil/trace.go:171","msg":"trace[1143381086] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:616; }","duration":"161.138332ms","start":"2026-02-26T01:42:46.313468Z","end":"2026-02-26T01:42:46.474606Z","steps":["trace[1143381086] 'agreement among raft nodes before linearized reading' (duration: 161.068641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:46.474634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.43968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:42:46.474646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.265957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:42:46.474659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.552646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:42:46.474677Z","caller":"traceutil/trace.go:171","msg":"trace[1980577901] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:616; }","duration":"145.501521ms","start":"2026-02-26T01:42:46.329164Z","end":"2026-02-26T01:42:46.474665Z","steps":["trace[1980577901] 'agreement among raft nodes before linearized reading' (duration: 145.4347ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:46.474699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.170779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:42:46.474702Z","caller":"traceutil/trace.go:171","msg":"trace[12158828] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:616; }","duration":"145.348669ms","start":"2026-02-26T01:42:46.329339Z","end":"2026-02-26T01:42:46.474688Z","steps":["trace[12158828] 'agreement among raft nodes before linearized reading' (duration: 145.262947ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:46.474643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.464974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:42:46.474749Z","caller":"traceutil/trace.go:171","msg":"trace[1981709140] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:616; }","duration":"180.617248ms","start":"2026-02-26T01:42:46.294119Z","end":"2026-02-26T01:42:46.474737Z","steps":["trace[1981709140] 'agreement among raft nodes before linearized reading' (duration: 180.450534ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:46.474742Z","caller":"traceutil/trace.go:171","msg":"trace[1695305052] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:616; }","duration":"180.227209ms","start":"2026-02-26T01:42:46.294497Z","end":"2026-02-26T01:42:46.474724Z","steps":["trace[1695305052] 'agreement among raft nodes before linearized reading' (duration: 180.177798ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:46.47457Z","caller":"traceutil/trace.go:171","msg":"trace[926302840] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:616; }","duration":"160.974849ms","start":"2026-02-26T01:42:46.313572Z","end":"2026-02-26T01:42:46.474547Z","steps":["trace[926302840] 'agreement among raft nodes before linearized reading' (duration: 160.848735ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:46.47451Z","caller":"traceutil/trace.go:171","msg":"trace[1981396865] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"264.460508ms","start":"2026-02-26T01:42:46.21003Z","end":"2026-02-26T01:42:46.474491Z","steps":["trace[1981396865] 'agreement among raft nodes before linearized reading' (duration: 264.371825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:46.47484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.193773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:42:46.474707Z","caller":"traceutil/trace.go:171","msg":"trace[1400012398] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:616; }","duration":"180.636138ms","start":"2026-02-26T01:42:46.294058Z","end":"2026-02-26T01:42:46.474694Z","steps":["trace[1400012398] 'agreement among raft nodes before linearized reading' (duration: 180.557496ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:46.474885Z","caller":"traceutil/trace.go:171","msg":"trace[159619467] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:616; }","duration":"161.289955ms","start":"2026-02-26T01:42:46.313582Z","end":"2026-02-26T01:42:46.474872Z","steps":["trace[159619467] 'agreement among raft nodes before linearized reading' (duration: 161.219944ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:46.474567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.331968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:42:46.474993Z","caller":"traceutil/trace.go:171","msg":"trace[293867135] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:616; }","duration":"145.776657ms","start":"2026-02-26T01:42:46.329201Z","end":"2026-02-26T01:42:46.474978Z","steps":["trace[293867135] 'agreement among raft nodes before linearized reading' (duration: 145.317588ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:46.625331Z","caller":"traceutil/trace.go:171","msg":"trace[1806515193] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"144.676944ms","start":"2026-02-26T01:42:46.480602Z","end":"2026-02-26T01:42:46.625279Z","steps":["trace[1806515193] 'process raft request' (duration: 111.068888ms)","trace[1806515193] 'compare' (duration: 33.372672ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:46.625928Z","caller":"traceutil/trace.go:171","msg":"trace[395473205] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"125.625438ms","start":"2026-02-26T01:42:46.500275Z","end":"2026-02-26T01:42:46.6259Z","steps":["trace[395473205] 'process raft request' (duration: 124.969815ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:46.6264Z","caller":"traceutil/trace.go:171","msg":"trace[45865182] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"124.994146ms","start":"2026-02-26T01:42:46.501393Z","end":"2026-02-26T01:42:46.626387Z","steps":["trace[45865182] 'process raft request' (duration: 124.387724ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:46.918675Z","caller":"traceutil/trace.go:171","msg":"trace[637066344] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"147.713125ms","start":"2026-02-26T01:42:46.770928Z","end":"2026-02-26T01:42:46.918641Z","steps":["trace[637066344] 'process raft request' (duration: 147.348728ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:47.172376Z","caller":"traceutil/trace.go:171","msg":"trace[279896157] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"128.213339ms","start":"2026-02-26T01:42:47.044111Z","end":"2026-02-26T01:42:47.172324Z","steps":["trace[279896157] 'process raft request' (duration: 126.019516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:50.161344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.98857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.169\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-26T01:42:50.16144Z","caller":"traceutil/trace.go:171","msg":"trace[1382915715] range","detail":"{range_begin:/registry/masterleases/199.204.45.169; range_end:; response_count:1; response_revision:737; }","duration":"105.131553ms","start":"2026-02-26T01:42:50.056287Z","end":"2026-02-26T01:42:50.161418Z","steps":["trace[1382915715] 'range keys from in-memory index tree' (duration: 104.818576ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:55.856144Z","caller":"traceutil/trace.go:171","msg":"trace[1724379517] linearizableReadLoop","detail":"{readStateIndex:825; appliedIndex:823; }","duration":"121.17239ms","start":"2026-02-26T01:42:55.734956Z","end":"2026-02-26T01:42:55.856128Z","steps":["trace[1724379517] 'read index received' (duration: 51.99133ms)","trace[1724379517] 'applied index is now lower than readState.Index' (duration: 69.18018ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:42:55.856202Z","caller":"traceutil/trace.go:171","msg":"trace[1141594729] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"124.28229ms","start":"2026-02-26T01:42:55.731887Z","end":"2026-02-26T01:42:55.856169Z","steps":["trace[1141594729] 'process raft request' (duration: 124.138388ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:42:55.856343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.379794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tc5xs\" ","response":"range_response_count:1 size:18713"} {"level":"info","ts":"2026-02-26T01:42:55.856374Z","caller":"traceutil/trace.go:171","msg":"trace[232955796] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tc5xs; range_end:; response_count:1; response_revision:800; }","duration":"121.449866ms","start":"2026-02-26T01:42:55.734917Z","end":"2026-02-26T01:42:55.856367Z","steps":["trace[232955796] 'agreement among raft nodes before linearized reading' (duration: 121.327024ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:42:58.32521Z","caller":"traceutil/trace.go:171","msg":"trace[1437450789] transaction","detail":"{read_only:false; response_revision:862; number_of_response:1; }","duration":"103.893997ms","start":"2026-02-26T01:42:58.221294Z","end":"2026-02-26T01:42:58.325188Z","steps":["trace[1437450789] 'process raft request' (duration: 102.272975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:05.880156Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6605826928421286960,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-26T01:43:06.000603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"788.802972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:43:06.000685Z","caller":"traceutil/trace.go:171","msg":"trace[1104470665] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:914; }","duration":"788.937985ms","start":"2026-02-26T01:43:05.211726Z","end":"2026-02-26T01:43:06.000664Z","steps":["trace[1104470665] 'range keys from in-memory index tree' (duration: 788.655919ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:06.000723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:05.211704Z","time spent":"789.009356ms","remote":"127.0.0.1:52792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T01:43:06.004401Z","caller":"traceutil/trace.go:171","msg":"trace[1692091502] linearizableReadLoop","detail":"{readStateIndex:942; appliedIndex:941; }","duration":"624.704592ms","start":"2026-02-26T01:43:05.379673Z","end":"2026-02-26T01:43:06.004377Z","steps":["trace[1692091502] 'read index received' (duration: 620.270404ms)","trace[1692091502] 'applied index is now lower than readState.Index' (duration: 4.432788ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:43:06.004537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.871415ms","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-02-26T01:43:06.004588Z","caller":"traceutil/trace.go:171","msg":"trace[1515243338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:914; }","duration":"624.948796ms","start":"2026-02-26T01:43:05.379619Z","end":"2026-02-26T01:43:06.004568Z","steps":["trace[1515243338] 'agreement among raft nodes before linearized reading' (duration: 624.841304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:06.004617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:05.379599Z","time spent":"625.011477ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T01:43:06.004589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.006049ms","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-02-26T01:43:06.004776Z","caller":"traceutil/trace.go:171","msg":"trace[696169032] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:914; }","duration":"155.183883ms","start":"2026-02-26T01:43:05.849533Z","end":"2026-02-26T01:43:06.004717Z","steps":["trace[696169032] 'agreement among raft nodes before linearized reading' (duration: 154.956869ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:06.593085Z","caller":"traceutil/trace.go:171","msg":"trace[406054594] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"101.51306ms","start":"2026-02-26T01:43:06.491527Z","end":"2026-02-26T01:43:06.59304Z","steps":["trace[406054594] 'process raft request' (duration: 101.123542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:29.42924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.360508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:43:29.429322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.83743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-02-26T01:43:29.42938Z","caller":"traceutil/trace.go:171","msg":"trace[13755250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1083; }","duration":"101.559432ms","start":"2026-02-26T01:43:29.327802Z","end":"2026-02-26T01:43:29.429361Z","steps":["trace[13755250] 'range keys from in-memory index tree' (duration: 101.218095ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:29.429418Z","caller":"traceutil/trace.go:171","msg":"trace[984168341] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1083; }","duration":"185.985054ms","start":"2026-02-26T01:43:29.243412Z","end":"2026-02-26T01:43:29.429397Z","steps":["trace[984168341] 'range keys from in-memory index tree' (duration: 185.675157ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:36.53694Z","caller":"traceutil/trace.go:171","msg":"trace[1497245485] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"204.305437ms","start":"2026-02-26T01:43:36.332592Z","end":"2026-02-26T01:43:36.536898Z","steps":["trace[1497245485] 'process raft request' (duration: 203.986991ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:36.545379Z","caller":"traceutil/trace.go:171","msg":"trace[179546309] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"211.513949ms","start":"2026-02-26T01:43:36.33383Z","end":"2026-02-26T01:43:36.545343Z","steps":["trace[179546309] 'process raft request' (duration: 211.281385ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:36.545617Z","caller":"traceutil/trace.go:171","msg":"trace[580902422] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1240; }","duration":"210.574251ms","start":"2026-02-26T01:43:36.335008Z","end":"2026-02-26T01:43:36.545582Z","steps":["trace[580902422] 'read index received' (duration: 201.530182ms)","trace[580902422] 'applied index is now lower than readState.Index' (duration: 9.041489ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:43:36.545656Z","caller":"traceutil/trace.go:171","msg":"trace[816245670] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"210.881516ms","start":"2026-02-26T01:43:36.334736Z","end":"2026-02-26T01:43:36.545618Z","steps":["trace[816245670] 'process raft request' (duration: 210.56508ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:36.545885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.855766ms","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-02-26T01:43:36.545922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.432501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-02-26T01:43:36.545973Z","caller":"traceutil/trace.go:171","msg":"trace[1375114732] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1203; }","duration":"210.987999ms","start":"2026-02-26T01:43:36.334967Z","end":"2026-02-26T01:43:36.545955Z","steps":["trace[1375114732] 'agreement among raft nodes before linearized reading' (duration: 210.725564ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:36.545978Z","caller":"traceutil/trace.go:171","msg":"trace[531343538] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:1203; }","duration":"143.558523ms","start":"2026-02-26T01:43:36.402398Z","end":"2026-02-26T01:43:36.545957Z","steps":["trace[531343538] 'agreement among raft nodes before linearized reading' (duration: 143.356899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:36.80855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.540377ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:43:36.808696Z","caller":"traceutil/trace.go:171","msg":"trace[46214365] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"257.470939ms","start":"2026-02-26T01:43:36.5512Z","end":"2026-02-26T01:43:36.808671Z","steps":["trace[46214365] 'process raft request' (duration: 87.700538ms)","trace[46214365] 'compare' (duration: 169.317873ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:43:36.916487Z","caller":"traceutil/trace.go:171","msg":"trace[1722835425] linearizableReadLoop","detail":"{readStateIndex:1245; appliedIndex:1243; }","duration":"319.458717ms","start":"2026-02-26T01:43:36.596999Z","end":"2026-02-26T01:43:36.916458Z","steps":["trace[1722835425] 'read index received' (duration: 41.837569ms)","trace[1722835425] 'applied index is now lower than readState.Index' (duration: 277.620228ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:43:36.916531Z","caller":"traceutil/trace.go:171","msg":"trace[79286969] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"364.319705ms","start":"2026-02-26T01:43:36.552182Z","end":"2026-02-26T01:43:36.916502Z","steps":["trace[79286969] 'process raft request' (duration: 361.230024ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:36.916685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:36.552165Z","time spent":"364.430017ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:43:36.916701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.687092ms","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-02-26T01:43:36.91675Z","caller":"traceutil/trace.go:171","msg":"trace[1408681916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1205; }","duration":"319.784383ms","start":"2026-02-26T01:43:36.596949Z","end":"2026-02-26T01:43:36.916733Z","steps":["trace[1408681916] 'agreement among raft nodes before linearized reading' (duration: 319.61282ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:36.916814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:36.596933Z","time spent":"319.873295ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T01:43:59.230973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.452829ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:43:59.231152Z","caller":"traceutil/trace.go:171","msg":"trace[2093041133] linearizableReadLoop","detail":"{readStateIndex:1664; appliedIndex:1663; }","duration":"321.25866ms","start":"2026-02-26T01:43:58.909843Z","end":"2026-02-26T01:43:59.231102Z","steps":["trace[2093041133] 'read index received' (duration: 172.538936ms)","trace[2093041133] 'applied index is now lower than readState.Index' (duration: 148.718414ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:43:59.231393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.538796ms","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-02-26T01:43:59.231496Z","caller":"traceutil/trace.go:171","msg":"trace[12957746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1600; }","duration":"321.686918ms","start":"2026-02-26T01:43:58.909791Z","end":"2026-02-26T01:43:59.231478Z","steps":["trace[12957746] 'agreement among raft nodes before linearized reading' (duration: 321.439814ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:59.231462Z","caller":"traceutil/trace.go:171","msg":"trace[165944403] transaction","detail":"{read_only:false; response_revision:1600; number_of_response:1; }","duration":"408.365021ms","start":"2026-02-26T01:43:58.822824Z","end":"2026-02-26T01:43:59.231189Z","steps":["trace[165944403] 'process raft request' (duration: 259.563195ms)","trace[165944403] 'compare' (duration: 148.282336ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:43:59.231418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.033808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6144f45b-ef14-4e89-b9a4-0bc07a287dd7\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-02-26T01:43:59.231597Z","caller":"traceutil/trace.go:171","msg":"trace[318701628] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-6144f45b-ef14-4e89-b9a4-0bc07a287dd7; range_end:; response_count:1; response_revision:1600; }","duration":"304.248683ms","start":"2026-02-26T01:43:58.927332Z","end":"2026-02-26T01:43:59.23158Z","steps":["trace[318701628] 'agreement among raft nodes before linearized reading' (duration: 303.947926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:59.231581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.009988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-02-26T01:43:59.23165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:58.927309Z","time spent":"304.329424ms","remote":"127.0.0.1:52974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-6144f45b-ef14-4e89-b9a4-0bc07a287dd7\" "} {"level":"warn","ts":"2026-02-26T01:43:59.231544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:58.90977Z","time spent":"321.764881ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-26T01:43:59.231674Z","caller":"traceutil/trace.go:171","msg":"trace[965685970] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1600; }","duration":"166.139771ms","start":"2026-02-26T01:43:59.065509Z","end":"2026-02-26T01:43:59.231649Z","steps":["trace[965685970] 'agreement among raft nodes before linearized reading' (duration: 165.829094ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:59.231738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:58.822795Z","time spent":"408.81348ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:43:59.871617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.862154ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:43:59.871818Z","caller":"traceutil/trace.go:171","msg":"trace[68466749] linearizableReadLoop","detail":"{readStateIndex:1667; appliedIndex:1666; }","duration":"414.541994ms","start":"2026-02-26T01:43:59.457244Z","end":"2026-02-26T01:43:59.871786Z","steps":["trace[68466749] 'read index received' (duration: 25.401305ms)","trace[68466749] 'applied index is now lower than readState.Index' (duration: 389.139019ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:43:59.871908Z","caller":"traceutil/trace.go:171","msg":"trace[1616239978] transaction","detail":"{read_only:false; response_revision:1603; number_of_response:1; }","duration":"553.932563ms","start":"2026-02-26T01:43:59.317943Z","end":"2026-02-26T01:43:59.871875Z","steps":["trace[1616239978] 'process raft request' (duration: 164.681491ms)","trace[1616239978] 'compare' (duration: 388.68223ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:43:59.87207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.823999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-02-26T01:43:59.872119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.43473ms","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-02-26T01:43:59.872161Z","caller":"traceutil/trace.go:171","msg":"trace[1862380486] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1603; }","duration":"343.501282ms","start":"2026-02-26T01:43:59.528646Z","end":"2026-02-26T01:43:59.872147Z","steps":["trace[1862380486] 'agreement among raft nodes before linearized reading' (duration: 343.300258ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:43:59.872164Z","caller":"traceutil/trace.go:171","msg":"trace[1180714382] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1603; }","duration":"414.968382ms","start":"2026-02-26T01:43:59.457173Z","end":"2026-02-26T01:43:59.872141Z","steps":["trace[1180714382] 'agreement among raft nodes before linearized reading' (duration: 414.710287ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:59.872214Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:59.457147Z","time spent":"415.059314ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-02-26T01:43:59.872217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:59.528632Z","time spent":"343.575213ms","remote":"127.0.0.1:57872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-02-26T01:43:59.872139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:59.317918Z","time spent":"554.088765ms","remote":"127.0.0.1:52974","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:43:59.872124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.8663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:43:59.872366Z","caller":"traceutil/trace.go:171","msg":"trace[2020395927] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1603; }","duration":"342.164216ms","start":"2026-02-26T01:43:59.53018Z","end":"2026-02-26T01:43:59.872344Z","steps":["trace[2020395927] 'agreement among raft nodes before linearized reading' (duration: 341.787678ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:43:59.87245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:43:59.530162Z","time spent":"342.272507ms","remote":"127.0.0.1:53266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":2,"response size":30,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-02-26T01:44:07.470521Z","caller":"traceutil/trace.go:171","msg":"trace[613100980] linearizableReadLoop","detail":"{readStateIndex:1739; appliedIndex:1738; }","duration":"132.211347ms","start":"2026-02-26T01:44:07.338283Z","end":"2026-02-26T01:44:07.470494Z","steps":["trace[613100980] 'read index received' (duration: 115.914573ms)","trace[613100980] 'applied index is now lower than readState.Index' (duration: 16.295864ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:44:07.470688Z","caller":"traceutil/trace.go:171","msg":"trace[39068833] transaction","detail":"{read_only:false; response_revision:1673; number_of_response:1; }","duration":"171.211651ms","start":"2026-02-26T01:44:07.299443Z","end":"2026-02-26T01:44:07.470654Z","steps":["trace[39068833] 'process raft request' (duration: 154.810435ms)","trace[39068833] 'compare' (duration: 16.155751ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:44:07.470789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.490343ms","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-02-26T01:44:07.470835Z","caller":"traceutil/trace.go:171","msg":"trace[1527496072] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1673; }","duration":"132.587534ms","start":"2026-02-26T01:44:07.338235Z","end":"2026-02-26T01:44:07.470823Z","steps":["trace[1527496072] 'agreement among raft nodes before linearized reading' (duration: 132.37632ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:12.732057Z","caller":"traceutil/trace.go:171","msg":"trace[1772122238] transaction","detail":"{read_only:false; response_revision:1700; number_of_response:1; }","duration":"407.057235ms","start":"2026-02-26T01:44:12.324968Z","end":"2026-02-26T01:44:12.732026Z","steps":["trace[1772122238] 'process raft request' (duration: 406.883122ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:12.732223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:12.324944Z","time spent":"407.195599ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:44:12.813662Z","caller":"traceutil/trace.go:171","msg":"trace[1054101628] linearizableReadLoop","detail":"{readStateIndex:1768; appliedIndex:1766; }","duration":"115.510654ms","start":"2026-02-26T01:44:12.698126Z","end":"2026-02-26T01:44:12.813637Z","steps":["trace[1054101628] 'read index received' (duration: 33.821682ms)","trace[1054101628] 'applied index is now lower than readState.Index' (duration: 81.687682ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:44:12.81372Z","caller":"traceutil/trace.go:171","msg":"trace[97201938] transaction","detail":"{read_only:false; response_revision:1701; number_of_response:1; }","duration":"219.682334ms","start":"2026-02-26T01:44:12.593966Z","end":"2026-02-26T01:44:12.813648Z","steps":["trace[97201938] 'process raft request' (duration: 215.325467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:12.813826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.692587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:44:12.81387Z","caller":"traceutil/trace.go:171","msg":"trace[655966668] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1701; }","duration":"115.78504ms","start":"2026-02-26T01:44:12.698074Z","end":"2026-02-26T01:44:12.813859Z","steps":["trace[655966668] 'agreement among raft nodes before linearized reading' (duration: 115.648087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:12.813994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.712889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-02-26T01:44:12.814052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.76609ms","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-02-26T01:44:12.814076Z","caller":"traceutil/trace.go:171","msg":"trace[761088121] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1701; }","duration":"110.832582ms","start":"2026-02-26T01:44:12.703224Z","end":"2026-02-26T01:44:12.814057Z","steps":["trace[761088121] 'agreement among raft nodes before linearized reading' (duration: 110.593698ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:12.814095Z","caller":"traceutil/trace.go:171","msg":"trace[539527382] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1701; }","duration":"110.851332ms","start":"2026-02-26T01:44:12.703229Z","end":"2026-02-26T01:44:12.81408Z","steps":["trace[539527382] 'agreement among raft nodes before linearized reading' (duration: 110.639148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:12.814292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.460555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11989"} {"level":"info","ts":"2026-02-26T01:44:12.81438Z","caller":"traceutil/trace.go:171","msg":"trace[380321844] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1701; }","duration":"101.564837ms","start":"2026-02-26T01:44:12.712783Z","end":"2026-02-26T01:44:12.814348Z","steps":["trace[380321844] 'agreement among raft nodes before linearized reading' (duration: 101.056887ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:50.508656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.650616ms","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-02-26T01:44:50.508793Z","caller":"traceutil/trace.go:171","msg":"trace[1527196918] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1902; }","duration":"198.85915ms","start":"2026-02-26T01:44:50.309904Z","end":"2026-02-26T01:44:50.508763Z","steps":["trace[1527196918] 'range keys from in-memory index tree' (duration: 198.35625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:51.551662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.098157ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:44:51.55174Z","caller":"traceutil/trace.go:171","msg":"trace[2106908564] linearizableReadLoop","detail":"{readStateIndex:1980; appliedIndex:1979; }","duration":"547.910352ms","start":"2026-02-26T01:44:51.003816Z","end":"2026-02-26T01:44:51.551726Z","steps":["trace[2106908564] 'read index received' (duration: 293.58219ms)","trace[2106908564] 'applied index is now lower than readState.Index' (duration: 254.327202ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:44:51.551792Z","caller":"traceutil/trace.go:171","msg":"trace[1121287779] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"598.566148ms","start":"2026-02-26T01:44:50.95322Z","end":"2026-02-26T01:44:51.551786Z","steps":["trace[1121287779] 'process raft request' (duration: 344.272727ms)","trace[1121287779] 'compare' (duration: 253.838362ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:44:51.551821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:50.953198Z","time spent":"598.609159ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:44:51.552025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.217048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-26T01:44:51.552048Z","caller":"traceutil/trace.go:171","msg":"trace[2116782883] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1904; }","duration":"548.269179ms","start":"2026-02-26T01:44:51.003771Z","end":"2026-02-26T01:44:51.55204Z","steps":["trace[2116782883] 'agreement among raft nodes before linearized reading' (duration: 548.171107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:51.552065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:51.003755Z","time spent":"548.30688ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-26T01:44:51.980279Z","caller":"traceutil/trace.go:171","msg":"trace[1826990554] linearizableReadLoop","detail":"{readStateIndex:1981; appliedIndex:1980; }","duration":"428.491821ms","start":"2026-02-26T01:44:51.551756Z","end":"2026-02-26T01:44:51.980247Z","steps":["trace[1826990554] 'read index received' (duration: 376.194632ms)","trace[1826990554] 'applied index is now lower than readState.Index' (duration: 52.295819ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:44:51.980427Z","caller":"traceutil/trace.go:171","msg":"trace[1244012844] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"477.646127ms","start":"2026-02-26T01:44:51.502767Z","end":"2026-02-26T01:44:51.980413Z","steps":["trace[1244012844] 'process raft request' (duration: 425.159895ms)","trace[1244012844] 'compare' (duration: 52.134446ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:44:51.980461Z","caller":"traceutil/trace.go:171","msg":"trace[429954395] transaction","detail":"{read_only:false; response_revision:1906; number_of_response:1; }","duration":"422.35547ms","start":"2026-02-26T01:44:51.558083Z","end":"2026-02-26T01:44:51.980438Z","steps":["trace[429954395] 'process raft request' (duration: 422.127876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:51.980552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:51.502736Z","time spent":"477.719528ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:44:51.980584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:51.558049Z","time spent":"422.470292ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:44:51.980792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"877.754374ms","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-02-26T01:44:51.980914Z","caller":"traceutil/trace.go:171","msg":"trace[548411505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1906; }","duration":"877.908188ms","start":"2026-02-26T01:44:51.102977Z","end":"2026-02-26T01:44:51.980886Z","steps":["trace[548411505] 'agreement among raft nodes before linearized reading' (duration: 877.587831ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:51.980938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.209908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:44:51.980978Z","caller":"traceutil/trace.go:171","msg":"trace[514033696] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1907; }","duration":"769.275349ms","start":"2026-02-26T01:44:51.211691Z","end":"2026-02-26T01:44:51.980967Z","steps":["trace[514033696] 'agreement among raft nodes before linearized reading' (duration: 769.073025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:51.980977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:51.10296Z","time spent":"878.002829ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-26T01:44:51.981001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.573508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-26T01:44:51.981008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:51.211637Z","time spent":"769.364321ms","remote":"127.0.0.1:52792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-26T01:44:51.981026Z","caller":"traceutil/trace.go:171","msg":"trace[1228137425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1907; }","duration":"317.649129ms","start":"2026-02-26T01:44:51.663369Z","end":"2026-02-26T01:44:51.981019Z","steps":["trace[1228137425] 'agreement among raft nodes before linearized reading' (duration: 317.514587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:51.981044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:51.663344Z","time spent":"317.69711ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-26T01:44:51.981129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.023846ms","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-02-26T01:44:51.981141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.360995ms","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-02-26T01:44:51.98113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.016606ms","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-02-26T01:44:51.981192Z","caller":"traceutil/trace.go:171","msg":"trace[107245888] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1907; }","duration":"255.156768ms","start":"2026-02-26T01:44:51.726019Z","end":"2026-02-26T01:44:51.981176Z","steps":["trace[107245888] 'agreement among raft nodes before linearized reading' (duration: 254.966275ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:51.981197Z","caller":"traceutil/trace.go:171","msg":"trace[1305413350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1907; }","duration":"879.457407ms","start":"2026-02-26T01:44:51.101727Z","end":"2026-02-26T01:44:51.981184Z","steps":["trace[1305413350] 'agreement among raft nodes before linearized reading' (duration: 878.959998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:51.981225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:51.101708Z","time spent":"879.511619ms","remote":"127.0.0.1:53058","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-02-26T01:44:51.981219Z","caller":"traceutil/trace.go:171","msg":"trace[1843191615] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1907; }","duration":"464.174691ms","start":"2026-02-26T01:44:51.517022Z","end":"2026-02-26T01:44:51.981197Z","steps":["trace[1843191615] 'agreement among raft nodes before linearized reading' (duration: 463.739061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:44:51.981275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:44:51.51699Z","time spent":"464.271102ms","remote":"127.0.0.1:57872","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-02-26T01:44:52.184583Z","caller":"traceutil/trace.go:171","msg":"trace[1557268198] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"197.598154ms","start":"2026-02-26T01:44:51.986957Z","end":"2026-02-26T01:44:52.184556Z","steps":["trace[1557268198] 'process raft request' (duration: 197.526303ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:52.184734Z","caller":"traceutil/trace.go:171","msg":"trace[307371560] transaction","detail":"{read_only:false; response_revision:1908; number_of_response:1; }","duration":"198.612045ms","start":"2026-02-26T01:44:51.986096Z","end":"2026-02-26T01:44:52.184708Z","steps":["trace[307371560] 'process raft request' (duration: 188.555356ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:52.184844Z","caller":"traceutil/trace.go:171","msg":"trace[1252249856] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"198.205727ms","start":"2026-02-26T01:44:51.986626Z","end":"2026-02-26T01:44:52.184832Z","steps":["trace[1252249856] 'process raft request' (duration: 197.794039ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:52.184977Z","caller":"traceutil/trace.go:171","msg":"trace[654245053] transaction","detail":"{read_only:false; response_revision:1911; number_of_response:1; }","duration":"197.942191ms","start":"2026-02-26T01:44:51.987028Z","end":"2026-02-26T01:44:52.18497Z","steps":["trace[654245053] 'process raft request' (duration: 197.493233ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:44:52.185208Z","caller":"traceutil/trace.go:171","msg":"trace[1172821923] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"196.694387ms","start":"2026-02-26T01:44:51.988497Z","end":"2026-02-26T01:44:52.185191Z","steps":["trace[1172821923] 'process raft request' (duration: 196.446862ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:35.304167Z","caller":"traceutil/trace.go:171","msg":"trace[1894949060] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"107.439051ms","start":"2026-02-26T01:45:35.196704Z","end":"2026-02-26T01:45:35.304143Z","steps":["trace[1894949060] 'process raft request' (duration: 104.203267ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:35.30479Z","caller":"traceutil/trace.go:171","msg":"trace[366027544] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2236; }","duration":"106.596944ms","start":"2026-02-26T01:45:35.198165Z","end":"2026-02-26T01:45:35.304762Z","steps":["trace[366027544] 'process raft request' (duration: 106.483462ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:45:41.922222Z","caller":"traceutil/trace.go:171","msg":"trace[5506722] transaction","detail":"{read_only:false; response_revision:2323; number_of_response:1; }","duration":"156.325626ms","start":"2026-02-26T01:45:41.765852Z","end":"2026-02-26T01:45:41.922178Z","steps":["trace[5506722] 'process raft request' (duration: 156.080231ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:46:15.304544Z","caller":"traceutil/trace.go:171","msg":"trace[1707318417] transaction","detail":"{read_only:false; response_revision:2510; number_of_response:1; }","duration":"168.30233ms","start":"2026-02-26T01:46:15.13621Z","end":"2026-02-26T01:46:15.304512Z","steps":["trace[1707318417] 'process raft request' (duration: 167.967484ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:46:15.855942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.881981ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-26T01:48:08.621472Z","caller":"traceutil/trace.go:171","msg":"trace[86536048] transaction","detail":"{read_only:false; response_revision:3174; number_of_response:1; }","duration":"200.161219ms","start":"2026-02-26T01:48:08.421285Z","end":"2026-02-26T01:48:08.621447Z","steps":["trace[86536048] 'process raft request' (duration: 200.012636ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:49:37.379978Z","caller":"traceutil/trace.go:171","msg":"trace[1985412741] transaction","detail":"{read_only:false; response_revision:3763; number_of_response:1; }","duration":"186.097663ms","start":"2026-02-26T01:49:37.193856Z","end":"2026-02-26T01:49:37.379954Z","steps":["trace[1985412741] 'process raft request' (duration: 185.93669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:49:37.540984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.123761ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:49:37.541138Z","caller":"traceutil/trace.go:171","msg":"trace[1575328647] linearizableReadLoop","detail":"{readStateIndex:3924; appliedIndex:3922; }","duration":"172.209757ms","start":"2026-02-26T01:49:37.368905Z","end":"2026-02-26T01:49:37.541115Z","steps":["trace[1575328647] 'read index received' (duration: 10.900367ms)","trace[1575328647] 'applied index is now lower than readState.Index' (duration: 161.3081ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:49:37.541489Z","caller":"traceutil/trace.go:171","msg":"trace[900658883] transaction","detail":"{read_only:false; response_revision:3764; number_of_response:1; }","duration":"222.921616ms","start":"2026-02-26T01:49:37.318534Z","end":"2026-02-26T01:49:37.541455Z","steps":["trace[900658883] 'process raft request' (duration: 107.216353ms)","trace[900658883] 'compare' (duration: 114.876436ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:49:37.541651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.723707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-02-26T01:49:37.541704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.58529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-26T01:49:37.541765Z","caller":"traceutil/trace.go:171","msg":"trace[2145327334] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:3764; }","duration":"172.880781ms","start":"2026-02-26T01:49:37.368855Z","end":"2026-02-26T01:49:37.541735Z","steps":["trace[2145327334] 'agreement among raft nodes before linearized reading' (duration: 172.608415ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:49:37.541782Z","caller":"traceutil/trace.go:171","msg":"trace[1272146109] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3764; }","duration":"114.715962ms","start":"2026-02-26T01:49:37.427047Z","end":"2026-02-26T01:49:37.541763Z","steps":["trace[1272146109] 'agreement among raft nodes before linearized reading' (duration: 114.438117ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:49:56.279727Z","caller":"traceutil/trace.go:171","msg":"trace[207869956] linearizableReadLoop","detail":"{readStateIndex:4440; appliedIndex:4439; }","duration":"239.71393ms","start":"2026-02-26T01:49:56.039969Z","end":"2026-02-26T01:49:56.279683Z","steps":["trace[207869956] 'read index received' (duration: 184.456721ms)","trace[207869956] 'applied index is now lower than readState.Index' (duration: 55.255519ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:49:56.280117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.128448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/grafana-tls\" ","response":"range_response_count:1 size:4299"} {"level":"info","ts":"2026-02-26T01:49:56.280219Z","caller":"traceutil/trace.go:171","msg":"trace[1304461410] range","detail":"{range_begin:/registry/secrets/monitoring/grafana-tls; range_end:; response_count:1; response_revision:4271; }","duration":"240.23948ms","start":"2026-02-26T01:49:56.039953Z","end":"2026-02-26T01:49:56.280193Z","steps":["trace[1304461410] 'agreement among raft nodes before linearized reading' (duration: 239.932994ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:49:56.279849Z","caller":"traceutil/trace.go:171","msg":"trace[1461053462] transaction","detail":"{read_only:false; response_revision:4271; number_of_response:1; }","duration":"256.013995ms","start":"2026-02-26T01:49:56.023796Z","end":"2026-02-26T01:49:56.27981Z","steps":["trace[1461053462] 'process raft request' (duration: 200.669513ms)","trace[1461053462] 'compare' (duration: 54.965803ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:49:56.281509Z","caller":"traceutil/trace.go:171","msg":"trace[1702465777] transaction","detail":"{read_only:false; response_revision:4272; number_of_response:1; }","duration":"209.414198ms","start":"2026-02-26T01:49:56.072067Z","end":"2026-02-26T01:49:56.281481Z","steps":["trace[1702465777] 'process raft request' (duration: 209.188733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:49:56.28188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.531971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/grafana-tls-pnwfh\" ","response":"range_response_count:1 size:2412"} {"level":"info","ts":"2026-02-26T01:49:56.28198Z","caller":"traceutil/trace.go:171","msg":"trace[699492239] transaction","detail":"{read_only:false; response_revision:4273; number_of_response:1; }","duration":"132.728461ms","start":"2026-02-26T01:49:56.149238Z","end":"2026-02-26T01:49:56.281966Z","steps":["trace[699492239] 'process raft request' (duration: 132.16971ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:49:56.281987Z","caller":"traceutil/trace.go:171","msg":"trace[1909302764] range","detail":"{range_begin:/registry/secrets/monitoring/grafana-tls-pnwfh; range_end:; response_count:1; response_revision:4273; }","duration":"194.689864ms","start":"2026-02-26T01:49:56.087271Z","end":"2026-02-26T01:49:56.281961Z","steps":["trace[1909302764] 'agreement among raft nodes before linearized reading' (duration: 194.407068ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:49:57.560558Z","caller":"traceutil/trace.go:171","msg":"trace[330637903] linearizableReadLoop","detail":"{readStateIndex:4505; appliedIndex:4504; }","duration":"111.305395ms","start":"2026-02-26T01:49:57.449219Z","end":"2026-02-26T01:49:57.560525Z","steps":["trace[330637903] 'read index received' (duration: 25.953876ms)","trace[330637903] 'applied index is now lower than readState.Index' (duration: 85.350399ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:49:57.560728Z","caller":"traceutil/trace.go:171","msg":"trace[1641227701] transaction","detail":"{read_only:false; response_revision:4336; number_of_response:1; }","duration":"122.593929ms","start":"2026-02-26T01:49:57.438121Z","end":"2026-02-26T01:49:57.560715Z","steps":["trace[1641227701] 'process raft request' (duration: 37.100478ms)","trace[1641227701] 'compare' (duration: 84.982821ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:49:57.562395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.142921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:50 size:2194600"} {"level":"info","ts":"2026-02-26T01:49:57.562511Z","caller":"traceutil/trace.go:171","msg":"trace[397288028] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:50; response_revision:4336; }","duration":"113.317554ms","start":"2026-02-26T01:49:57.44917Z","end":"2026-02-26T01:49:57.562487Z","steps":["trace[397288028] 'agreement among raft nodes before linearized reading' (duration: 111.644641ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:02.550086Z","caller":"traceutil/trace.go:171","msg":"trace[634950074] transaction","detail":"{read_only:false; response_revision:4449; number_of_response:1; }","duration":"222.948807ms","start":"2026-02-26T01:50:02.327103Z","end":"2026-02-26T01:50:02.550052Z","steps":["trace[634950074] 'process raft request' (duration: 222.788274ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:02.613559Z","caller":"traceutil/trace.go:171","msg":"trace[1019967720] linearizableReadLoop","detail":"{readStateIndex:4623; appliedIndex:4621; }","duration":"169.445712ms","start":"2026-02-26T01:50:02.444092Z","end":"2026-02-26T01:50:02.613538Z","steps":["trace[1019967720] 'read index received' (duration: 105.779525ms)","trace[1019967720] 'applied index is now lower than readState.Index' (duration: 63.664837ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:50:02.613597Z","caller":"traceutil/trace.go:171","msg":"trace[2077456059] transaction","detail":"{read_only:false; response_revision:4450; number_of_response:1; }","duration":"258.408643ms","start":"2026-02-26T01:50:02.355164Z","end":"2026-02-26T01:50:02.613572Z","steps":["trace[2077456059] 'process raft request' (duration: 258.27647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:02.613822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.677888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/monitoring/loki-memcached-chunks-cache\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:50:02.613883Z","caller":"traceutil/trace.go:171","msg":"trace[2021340213] range","detail":"{range_begin:/registry/poddisruptionbudgets/monitoring/loki-memcached-chunks-cache; range_end:; response_count:0; response_revision:4450; }","duration":"169.82896ms","start":"2026-02-26T01:50:02.444037Z","end":"2026-02-26T01:50:02.613866Z","steps":["trace[2021340213] 'agreement among raft nodes before linearized reading' (duration: 169.614896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:02.613925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.5469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-8lz4m\" ","response":"range_response_count:1 size:5148"} {"level":"info","ts":"2026-02-26T01:50:02.613987Z","caller":"traceutil/trace.go:171","msg":"trace[781866633] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-8lz4m; range_end:; response_count:1; response_revision:4450; }","duration":"110.655713ms","start":"2026-02-26T01:50:02.503316Z","end":"2026-02-26T01:50:02.613972Z","steps":["trace[781866633] 'agreement among raft nodes before linearized reading' (duration: 110.337807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:04.724866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.774741ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:50:04.725076Z","caller":"traceutil/trace.go:171","msg":"trace[535569504] transaction","detail":"{read_only:false; response_revision:4566; number_of_response:1; }","duration":"169.297189ms","start":"2026-02-26T01:50:04.555753Z","end":"2026-02-26T01:50:04.72505Z","steps":["trace[535569504] 'process raft request' (duration: 169.226838ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:04.725278Z","caller":"traceutil/trace.go:171","msg":"trace[399573377] transaction","detail":"{read_only:false; response_revision:4565; number_of_response:1; }","duration":"584.846328ms","start":"2026-02-26T01:50:04.140421Z","end":"2026-02-26T01:50:04.725267Z","steps":["trace[399573377] 'process raft request' (duration: 29.606289ms)","trace[399573377] 'compare' (duration: 554.650078ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:50:04.725333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:04.140404Z","time spent":"584.904189ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-26T01:50:04.725452Z","caller":"traceutil/trace.go:171","msg":"trace[802390172] linearizableReadLoop","detail":"{readStateIndex:4740; appliedIndex:4739; }","duration":"414.018519ms","start":"2026-02-26T01:50:04.311393Z","end":"2026-02-26T01:50:04.725411Z","steps":["trace[802390172] 'read index received' (duration: 41.441µs)","trace[802390172] 'applied index is now lower than readState.Index' (duration: 413.974148ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:50:04.725559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.159031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:50:04.725586Z","caller":"traceutil/trace.go:171","msg":"trace[548101318] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:4566; }","duration":"414.241213ms","start":"2026-02-26T01:50:04.311339Z","end":"2026-02-26T01:50:04.72558Z","steps":["trace[548101318] 'agreement among raft nodes before linearized reading' (duration: 414.162092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:04.725604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:04.311318Z","time spent":"414.281765ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":13,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-02-26T01:50:04.726036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.651946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:718812"} {"level":"info","ts":"2026-02-26T01:50:04.726116Z","caller":"traceutil/trace.go:171","msg":"trace[719361046] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:4566; }","duration":"375.791658ms","start":"2026-02-26T01:50:04.350307Z","end":"2026-02-26T01:50:04.726099Z","steps":["trace[719361046] 'agreement among raft nodes before linearized reading' (duration: 375.270247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:04.726166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:04.350285Z","time spent":"375.865869ms","remote":"127.0.0.1:52880","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":24,"response size":718835,"request content":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" "} {"level":"info","ts":"2026-02-26T01:50:08.339737Z","caller":"traceutil/trace.go:171","msg":"trace[1024542780] transaction","detail":"{read_only:false; response_revision:4641; number_of_response:1; }","duration":"106.201104ms","start":"2026-02-26T01:50:08.233492Z","end":"2026-02-26T01:50:08.339693Z","steps":["trace[1024542780] 'process raft request' (duration: 66.987084ms)","trace[1024542780] 'compare' (duration: 38.910404ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:50:08.662586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.210114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:50:08.662696Z","caller":"traceutil/trace.go:171","msg":"trace[2002283456] linearizableReadLoop","detail":"{readStateIndex:4823; appliedIndex:4821; }","duration":"315.368197ms","start":"2026-02-26T01:50:08.34731Z","end":"2026-02-26T01:50:08.662679Z","steps":["trace[2002283456] 'read index received' (duration: 19.08726ms)","trace[2002283456] 'applied index is now lower than readState.Index' (duration: 296.279646ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:50:08.662731Z","caller":"traceutil/trace.go:171","msg":"trace[818133175] transaction","detail":"{read_only:false; response_revision:4644; number_of_response:1; }","duration":"317.098611ms","start":"2026-02-26T01:50:08.345588Z","end":"2026-02-26T01:50:08.662687Z","steps":["trace[818133175] 'process raft request' (duration: 107.699443ms)","trace[818133175] 'compare' (duration: 208.758775ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:50:08.662886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.830139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/monitoring/ipmi-exporter\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:50:08.662901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:08.345569Z","time spent":"317.248733ms","remote":"127.0.0.1:52870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-26T01:50:08.662946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.631252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-02-26T01:50:08.662959Z","caller":"traceutil/trace.go:171","msg":"trace[2031073680] range","detail":"{range_begin:/registry/daemonsets/monitoring/ipmi-exporter; range_end:; response_count:0; response_revision:4644; }","duration":"280.965812ms","start":"2026-02-26T01:50:08.381976Z","end":"2026-02-26T01:50:08.662941Z","steps":["trace[2031073680] 'agreement among raft nodes before linearized reading' (duration: 280.802998ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:08.662986Z","caller":"traceutil/trace.go:171","msg":"trace[2027782249] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4644; }","duration":"315.681872ms","start":"2026-02-26T01:50:08.347294Z","end":"2026-02-26T01:50:08.662976Z","steps":["trace[2027782249] 'agreement among raft nodes before linearized reading' (duration: 315.465818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:08.663016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:08.347289Z","time spent":"315.719673ms","remote":"127.0.0.1:52974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-02-26T01:50:08.943054Z","caller":"traceutil/trace.go:171","msg":"trace[353582542] linearizableReadLoop","detail":"{readStateIndex:4827; appliedIndex:4825; }","duration":"192.165325ms","start":"2026-02-26T01:50:08.750868Z","end":"2026-02-26T01:50:08.943034Z","steps":["trace[353582542] 'read index received' (duration: 119.628331ms)","trace[353582542] 'applied index is now lower than readState.Index' (duration: 72.536194ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:50:08.943186Z","caller":"traceutil/trace.go:171","msg":"trace[275220192] transaction","detail":"{read_only:false; response_revision:4647; number_of_response:1; }","duration":"224.187592ms","start":"2026-02-26T01:50:08.718967Z","end":"2026-02-26T01:50:08.943155Z","steps":["trace[275220192] 'process raft request' (duration: 151.376403ms)","trace[275220192] 'compare' (duration: 72.511443ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:50:08.943202Z","caller":"traceutil/trace.go:171","msg":"trace[220173248] transaction","detail":"{read_only:false; response_revision:4648; number_of_response:1; }","duration":"196.734665ms","start":"2026-02-26T01:50:08.746433Z","end":"2026-02-26T01:50:08.943167Z","steps":["trace[220173248] 'process raft request' (duration: 196.546641ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:08.943264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.384599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gb4bb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-02-26T01:50:08.943309Z","caller":"traceutil/trace.go:171","msg":"trace[283659192] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gb4bb; range_end:; response_count:1; response_revision:4648; }","duration":"192.479651ms","start":"2026-02-26T01:50:08.750817Z","end":"2026-02-26T01:50:08.943297Z","steps":["trace[283659192] 'agreement among raft nodes before linearized reading' (duration: 192.314447ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:09.222918Z","caller":"traceutil/trace.go:171","msg":"trace[1442790986] transaction","detail":"{read_only:false; response_revision:4658; number_of_response:1; }","duration":"105.602282ms","start":"2026-02-26T01:50:09.11728Z","end":"2026-02-26T01:50:09.222882Z","steps":["trace[1442790986] 'process raft request' (duration: 94.058852ms)","trace[1442790986] 'compare' (duration: 11.246204ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:50:09.22384Z","caller":"traceutil/trace.go:171","msg":"trace[603681901] linearizableReadLoop","detail":"{readStateIndex:4838; appliedIndex:4836; }","duration":"104.237784ms","start":"2026-02-26T01:50:09.119568Z","end":"2026-02-26T01:50:09.223806Z","steps":["trace[603681901] 'read index received' (duration: 91.783666ms)","trace[603681901] 'applied index is now lower than readState.Index' (duration: 12.451598ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:50:09.223844Z","caller":"traceutil/trace.go:171","msg":"trace[65727119] transaction","detail":"{read_only:false; response_revision:4659; number_of_response:1; }","duration":"106.403397ms","start":"2026-02-26T01:50:09.11741Z","end":"2026-02-26T01:50:09.223814Z","steps":["trace[65727119] 'process raft request' (duration: 105.379977ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:09.224095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.492549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ab79b2e0-fdf0-4a5b-b5ab-056eb37d1e20\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-02-26T01:50:09.224149Z","caller":"traceutil/trace.go:171","msg":"trace[1463088598] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ab79b2e0-fdf0-4a5b-b5ab-056eb37d1e20; range_end:; response_count:1; response_revision:4659; }","duration":"104.588761ms","start":"2026-02-26T01:50:09.119542Z","end":"2026-02-26T01:50:09.224131Z","steps":["trace[1463088598] 'agreement among raft nodes before linearized reading' (duration: 104.362467ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:15.719416Z","caller":"traceutil/trace.go:171","msg":"trace[78966846] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"151.49864ms","start":"2026-02-26T01:50:15.567892Z","end":"2026-02-26T01:50:15.71939Z","steps":["trace[78966846] 'process raft request' (duration: 129.126648ms)","trace[78966846] 'compare' (duration: 22.133358ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:50:24.093787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.679517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8ef17a14-95b9-43a8-ba4e-15e1dcaad19f\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-02-26T01:50:24.093918Z","caller":"traceutil/trace.go:171","msg":"trace[1365329179] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8ef17a14-95b9-43a8-ba4e-15e1dcaad19f; range_end:; response_count:1; response_revision:4917; }","duration":"105.875701ms","start":"2026-02-26T01:50:23.988014Z","end":"2026-02-26T01:50:24.09389Z","steps":["trace[1365329179] 'range keys from in-memory index tree' (duration: 105.490234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:24.093937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.008216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-02-26T01:50:24.094022Z","caller":"traceutil/trace.go:171","msg":"trace[1207739433] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4917; }","duration":"176.129238ms","start":"2026-02-26T01:50:23.917873Z","end":"2026-02-26T01:50:24.094002Z","steps":["trace[1207739433] 'range keys from in-memory index tree' (duration: 175.853034ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:24.421831Z","caller":"traceutil/trace.go:171","msg":"trace[1861579059] transaction","detail":"{read_only:false; response_revision:4920; number_of_response:1; }","duration":"322.345718ms","start":"2026-02-26T01:50:24.099456Z","end":"2026-02-26T01:50:24.421802Z","steps":["trace[1861579059] 'process raft request' (duration: 322.243646ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:24.421995Z","caller":"traceutil/trace.go:171","msg":"trace[1157367420] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"324.0201ms","start":"2026-02-26T01:50:24.097937Z","end":"2026-02-26T01:50:24.421957Z","steps":["trace[1157367420] 'process raft request' (duration: 323.586311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:24.422029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:24.099434Z","time spent":"322.49009ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:50:24.421998Z","caller":"traceutil/trace.go:171","msg":"trace[1823189654] linearizableReadLoop","detail":"{readStateIndex:5105; appliedIndex:5101; }","duration":"273.811158ms","start":"2026-02-26T01:50:24.148148Z","end":"2026-02-26T01:50:24.421959Z","steps":["trace[1823189654] 'read index received' (duration: 15.935034ms)","trace[1823189654] 'applied index is now lower than readState.Index' (duration: 257.873584ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:50:24.422096Z","caller":"traceutil/trace.go:171","msg":"trace[287106942] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"320.543143ms","start":"2026-02-26T01:50:24.101516Z","end":"2026-02-26T01:50:24.422059Z","steps":["trace[287106942] 'process raft request' (duration: 320.244776ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:24.422171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:24.097926Z","time spent":"324.156444ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:50:24.422236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.059513ms","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-02-26T01:50:24.422266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:24.101502Z","time spent":"320.668075ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:50:24.422285Z","caller":"traceutil/trace.go:171","msg":"trace[1059554111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4921; }","duration":"274.129755ms","start":"2026-02-26T01:50:24.148142Z","end":"2026-02-26T01:50:24.422272Z","steps":["trace[1059554111] 'agreement among raft nodes before linearized reading' (duration: 273.920791ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:24.42228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.918642ms","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-02-26T01:50:24.422348Z","caller":"traceutil/trace.go:171","msg":"trace[291240507] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4921; }","duration":"272.012413ms","start":"2026-02-26T01:50:24.150318Z","end":"2026-02-26T01:50:24.42233Z","steps":["trace[291240507] 'agreement among raft nodes before linearized reading' (duration: 271.87423ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:24.422472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.875692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8ef17a14-95b9-43a8-ba4e-15e1dcaad19f\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-02-26T01:50:24.422573Z","caller":"traceutil/trace.go:171","msg":"trace[1465047911] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8ef17a14-95b9-43a8-ba4e-15e1dcaad19f; range_end:; response_count:1; response_revision:4921; }","duration":"184.052705ms","start":"2026-02-26T01:50:24.238502Z","end":"2026-02-26T01:50:24.422555Z","steps":["trace[1465047911] 'agreement among raft nodes before linearized reading' (duration: 183.686828ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:24.422958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.776483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:50:24.423016Z","caller":"traceutil/trace.go:171","msg":"trace[1059407851] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4921; }","duration":"210.855085ms","start":"2026-02-26T01:50:24.212146Z","end":"2026-02-26T01:50:24.423001Z","steps":["trace[1059407851] 'agreement among raft nodes before linearized reading' (duration: 210.138741ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:29.003256Z","caller":"traceutil/trace.go:171","msg":"trace[339668696] linearizableReadLoop","detail":"{readStateIndex:5175; appliedIndex:5174; }","duration":"116.562043ms","start":"2026-02-26T01:50:28.886673Z","end":"2026-02-26T01:50:29.003235Z","steps":["trace[339668696] 'read index received' (duration: 69.641226ms)","trace[339668696] 'applied index is now lower than readState.Index' (duration: 46.919467ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:50:29.003329Z","caller":"traceutil/trace.go:171","msg":"trace[215642873] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"129.395086ms","start":"2026-02-26T01:50:28.873889Z","end":"2026-02-26T01:50:29.003284Z","steps":["trace[215642873] 'process raft request' (duration: 82.410577ms)","trace[215642873] 'compare' (duration: 46.807726ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:50:29.003603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.90653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/\" range_end:\"/registry/pods/monitoring0\" ","response":"range_response_count:16 size:110695"} {"level":"info","ts":"2026-02-26T01:50:29.003646Z","caller":"traceutil/trace.go:171","msg":"trace[1128450325] range","detail":"{range_begin:/registry/pods/monitoring/; range_end:/registry/pods/monitoring0; response_count:16; response_revision:4989; }","duration":"116.997642ms","start":"2026-02-26T01:50:28.886639Z","end":"2026-02-26T01:50:29.003637Z","steps":["trace[1128450325] 'agreement among raft nodes before linearized reading' (duration: 116.717666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:39.201187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.0737ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:50:39.201343Z","caller":"traceutil/trace.go:171","msg":"trace[1143505365] transaction","detail":"{read_only:false; response_revision:5119; number_of_response:1; }","duration":"423.487516ms","start":"2026-02-26T01:50:38.77783Z","end":"2026-02-26T01:50:39.201318Z","steps":["trace[1143505365] 'process raft request' (duration: 132.163701ms)","trace[1143505365] 'compare' (duration: 290.58067ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:50:39.201707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:50:38.777813Z","time spent":"423.847792ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:50:39.287172Z","caller":"traceutil/trace.go:171","msg":"trace[1638506009] linearizableReadLoop","detail":"{readStateIndex:5310; appliedIndex:5309; }","duration":"146.075346ms","start":"2026-02-26T01:50:39.141069Z","end":"2026-02-26T01:50:39.287145Z","steps":["trace[1638506009] 'read index received' (duration: 145.900042ms)","trace[1638506009] 'applied index is now lower than readState.Index' (duration: 174.104µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:50:39.287198Z","caller":"traceutil/trace.go:171","msg":"trace[2111824354] transaction","detail":"{read_only:false; response_revision:5120; number_of_response:1; }","duration":"185.402272ms","start":"2026-02-26T01:50:39.101757Z","end":"2026-02-26T01:50:39.287159Z","steps":["trace[2111824354] 'process raft request' (duration: 185.197518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:39.287486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.402452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gb4bb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-02-26T01:50:39.287583Z","caller":"traceutil/trace.go:171","msg":"trace[25872527] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gb4bb; range_end:; response_count:1; response_revision:5120; }","duration":"146.574925ms","start":"2026-02-26T01:50:39.14099Z","end":"2026-02-26T01:50:39.287565Z","steps":["trace[25872527] 'agreement among raft nodes before linearized reading' (duration: 146.244288ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:50:39.385954Z","caller":"traceutil/trace.go:171","msg":"trace[912577046] transaction","detail":"{read_only:false; response_revision:5121; number_of_response:1; }","duration":"225.26888ms","start":"2026-02-26T01:50:39.160658Z","end":"2026-02-26T01:50:39.385926Z","steps":["trace[912577046] 'process raft request' (duration: 225.071536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:50:39.386158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.2816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-02-26T01:50:39.386241Z","caller":"traceutil/trace.go:171","msg":"trace[1557255534] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5121; }","duration":"102.433863ms","start":"2026-02-26T01:50:39.283787Z","end":"2026-02-26T01:50:39.386221Z","steps":["trace[1557255534] 'agreement among raft nodes before linearized reading' (duration: 102.207389ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:51:24.81482Z","caller":"traceutil/trace.go:171","msg":"trace[2002173950] transaction","detail":"{read_only:false; response_revision:5480; number_of_response:1; }","duration":"104.039025ms","start":"2026-02-26T01:51:24.710754Z","end":"2026-02-26T01:51:24.814793Z","steps":["trace[2002173950] 'process raft request' (duration: 103.870752ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:52:07.093651Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2768} {"level":"info","ts":"2026-02-26T01:52:07.154367Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2768,"took":"59.43516ms","hash":3443578715,"current-db-size-bytes":41177088,"current-db-size":"41 MB","current-db-size-in-use-bytes":31059968,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-02-26T01:52:07.154426Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3443578715,"revision":2768,"compact-revision":-1} {"level":"info","ts":"2026-02-26T01:56:33.251382Z","caller":"traceutil/trace.go:171","msg":"trace[1750374908] transaction","detail":"{read_only:false; response_revision:7623; number_of_response:1; }","duration":"104.870382ms","start":"2026-02-26T01:56:33.146443Z","end":"2026-02-26T01:56:33.251313Z","steps":["trace[1750374908] 'process raft request' (duration: 104.423023ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:33.848752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.558014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-gb4bb\" ","response":"range_response_count:1 size:6524"} {"level":"info","ts":"2026-02-26T01:56:33.848893Z","caller":"traceutil/trace.go:171","msg":"trace[2041745440] range","detail":"{range_begin:/registry/pods/openstack/keepalived-gb4bb; range_end:; response_count:1; response_revision:7623; }","duration":"468.713277ms","start":"2026-02-26T01:56:33.380136Z","end":"2026-02-26T01:56:33.848849Z","steps":["trace[2041745440] 'range keys from in-memory index tree' (duration: 468.415501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:33.84895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:56:33.380121Z","time spent":"468.81689ms","remote":"127.0.0.1:52974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6547,"request content":"key:\"/registry/pods/openstack/keepalived-gb4bb\" "} {"level":"warn","ts":"2026-02-26T01:56:33.848945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.857974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:56:33.849046Z","caller":"traceutil/trace.go:171","msg":"trace[508867178] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:7623; }","duration":"442.011726ms","start":"2026-02-26T01:56:33.407004Z","end":"2026-02-26T01:56:33.849016Z","steps":["trace[508867178] 'count revisions from in-memory index tree' (duration: 441.70692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:33.849115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:56:33.406982Z","time spent":"442.111039ms","remote":"127.0.0.1:43686","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true "} {"level":"info","ts":"2026-02-26T01:56:44.556011Z","caller":"traceutil/trace.go:171","msg":"trace[992335751] transaction","detail":"{read_only:false; response_revision:7674; number_of_response:1; }","duration":"128.352168ms","start":"2026-02-26T01:56:44.427628Z","end":"2026-02-26T01:56:44.55598Z","steps":["trace[992335751] 'process raft request' (duration: 84.774904ms)","trace[992335751] 'compare' (duration: 43.099535ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:56:45.049029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.227144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:56:45.049182Z","caller":"traceutil/trace.go:171","msg":"trace[1457091674] transaction","detail":"{read_only:false; response_revision:7675; number_of_response:1; }","duration":"479.042752ms","start":"2026-02-26T01:56:44.570111Z","end":"2026-02-26T01:56:45.049154Z","steps":["trace[1457091674] 'process raft request' (duration: 339.579503ms)","trace[1457091674] 'compare' (duration: 139.086621ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:56:45.049296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:56:44.570087Z","time spent":"479.160895ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:56:45.081051Z","caller":"traceutil/trace.go:171","msg":"trace[1660894079] transaction","detail":"{read_only:false; response_revision:7677; number_of_response:1; }","duration":"478.870809ms","start":"2026-02-26T01:56:44.602162Z","end":"2026-02-26T01:56:45.081032Z","steps":["trace[1660894079] 'process raft request' (duration: 478.790288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:45.081176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:56:44.602141Z","time spent":"478.969742ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:56:45.08119Z","caller":"traceutil/trace.go:171","msg":"trace[2091906414] transaction","detail":"{read_only:false; response_revision:7676; number_of_response:1; }","duration":"508.629871ms","start":"2026-02-26T01:56:44.572433Z","end":"2026-02-26T01:56:45.081063Z","steps":["trace[2091906414] 'process raft request' (duration: 508.420477ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:45.081267Z","caller":"traceutil/trace.go:171","msg":"trace[823901505] linearizableReadLoop","detail":"{readStateIndex:7955; appliedIndex:7952; }","duration":"135.394929ms","start":"2026-02-26T01:56:44.94582Z","end":"2026-02-26T01:56:45.081215Z","steps":["trace[823901505] 'read index received' (duration: 135.051732ms)","trace[823901505] 'applied index is now lower than readState.Index' (duration: 339.417µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:56:45.081312Z","caller":"traceutil/trace.go:171","msg":"trace[1120169835] transaction","detail":"{read_only:false; response_revision:7678; number_of_response:1; }","duration":"445.851623ms","start":"2026-02-26T01:56:44.635449Z","end":"2026-02-26T01:56:45.0813Z","steps":["trace[1120169835] 'process raft request' (duration: 445.547877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:45.08147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.625633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-26T01:56:45.081519Z","caller":"traceutil/trace.go:171","msg":"trace[350657230] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:7678; }","duration":"135.741495ms","start":"2026-02-26T01:56:44.945765Z","end":"2026-02-26T01:56:45.081507Z","steps":["trace[350657230] 'agreement among raft nodes before linearized reading' (duration: 135.576222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:45.081564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:56:44.635428Z","time spent":"446.056417ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:56:45.081664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:56:44.572406Z","time spent":"508.969107ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:56:45.98661Z","caller":"traceutil/trace.go:171","msg":"trace[2118602914] transaction","detail":"{read_only:false; response_revision:7680; number_of_response:1; }","duration":"252.918732ms","start":"2026-02-26T01:56:45.733658Z","end":"2026-02-26T01:56:45.986577Z","steps":["trace[2118602914] 'process raft request' (duration: 252.635657ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:47.380652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.836109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:56:47.380708Z","caller":"traceutil/trace.go:171","msg":"trace[1347006120] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:7688; }","duration":"117.912911ms","start":"2026-02-26T01:56:47.262784Z","end":"2026-02-26T01:56:47.380696Z","steps":["trace[1347006120] 'range keys from in-memory index tree' (duration: 117.807759ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:47.3808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.671519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:56:47.38088Z","caller":"traceutil/trace.go:171","msg":"trace[1984628522] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:7688; }","duration":"107.787481ms","start":"2026-02-26T01:56:47.273073Z","end":"2026-02-26T01:56:47.38086Z","steps":["trace[1984628522] 'count revisions from in-memory index tree' (duration: 107.578837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:47.38095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.807452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:56:47.381051Z","caller":"traceutil/trace.go:171","msg":"trace[2014519893] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:7688; }","duration":"107.947854ms","start":"2026-02-26T01:56:47.273081Z","end":"2026-02-26T01:56:47.381029Z","steps":["trace[2014519893] 'range keys from in-memory index tree' (duration: 107.728369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:47.381057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.056086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:56:47.381118Z","caller":"traceutil/trace.go:171","msg":"trace[1394217991] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:7688; }","duration":"178.142037ms","start":"2026-02-26T01:56:47.202961Z","end":"2026-02-26T01:56:47.381103Z","steps":["trace[1394217991] 'count revisions from in-memory index tree' (duration: 177.981474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:47.381143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.120447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:56:47.381173Z","caller":"traceutil/trace.go:171","msg":"trace[313594596] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:7688; }","duration":"178.180619ms","start":"2026-02-26T01:56:47.202984Z","end":"2026-02-26T01:56:47.381165Z","steps":["trace[313594596] 'range keys from in-memory index tree' (duration: 178.032556ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:47.381242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.454884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephnfses/\" range_end:\"/registry/ceph.rook.io/cephnfses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:56:47.38133Z","caller":"traceutil/trace.go:171","msg":"trace[658750678] range","detail":"{range_begin:/registry/ceph.rook.io/cephnfses/; range_end:/registry/ceph.rook.io/cephnfses0; response_count:0; response_revision:7688; }","duration":"177.539965ms","start":"2026-02-26T01:56:47.203765Z","end":"2026-02-26T01:56:47.381305Z","steps":["trace[658750678] 'range keys from in-memory index tree' (duration: 177.320921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:47.381353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.224391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:56:47.381391Z","caller":"traceutil/trace.go:171","msg":"trace[519724868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7688; }","duration":"165.276022ms","start":"2026-02-26T01:56:47.216104Z","end":"2026-02-26T01:56:47.38138Z","steps":["trace[519724868] 'range keys from in-memory index tree' (duration: 165.18434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:47.381487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.490437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:56:47.381084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.741558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:56:47.381539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.615499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:56:47.381544Z","caller":"traceutil/trace.go:171","msg":"trace[1744349110] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:7688; }","duration":"158.560478ms","start":"2026-02-26T01:56:47.222969Z","end":"2026-02-26T01:56:47.38153Z","steps":["trace[1744349110] 'range keys from in-memory index tree' (duration: 158.455846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:56:47.381586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.15226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:56:47.38183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.073033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-26T01:56:47.381862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.159195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces/\" range_end:\"/registry/ceph.rook.io/cephblockpoolradosnamespaces0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:56:47.3831Z","caller":"traceutil/trace.go:171","msg":"trace[2040764032] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:7688; }","duration":"108.26427ms","start":"2026-02-26T01:56:47.273292Z","end":"2026-02-26T01:56:47.381556Z","steps":["trace[2040764032] 'range keys from in-memory index tree' (duration: 107.650317ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:47.38354Z","caller":"traceutil/trace.go:171","msg":"trace[1604540501] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:7688; }","duration":"158.65848ms","start":"2026-02-26T01:56:47.222912Z","end":"2026-02-26T01:56:47.381571Z","steps":["trace[1604540501] 'count revisions from in-memory index tree' (duration: 158.458806ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:47.383771Z","caller":"traceutil/trace.go:171","msg":"trace[2111230785] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:7688; }","duration":"158.200511ms","start":"2026-02-26T01:56:47.223401Z","end":"2026-02-26T01:56:47.381602Z","steps":["trace[2111230785] 'range keys from in-memory index tree' (duration: 158.100129ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:47.383925Z","caller":"traceutil/trace.go:171","msg":"trace[687666269] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:7688; }","duration":"119.109644ms","start":"2026-02-26T01:56:47.262743Z","end":"2026-02-26T01:56:47.381852Z","steps":["trace[687666269] 'count revisions from in-memory index tree' (duration: 119.049743ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:47.384131Z","caller":"traceutil/trace.go:171","msg":"trace[1459869639] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpoolradosnamespaces/; range_end:/registry/ceph.rook.io/cephblockpoolradosnamespaces0; response_count:0; response_revision:7688; }","duration":"119.191476ms","start":"2026-02-26T01:56:47.262681Z","end":"2026-02-26T01:56:47.381873Z","steps":["trace[1459869639] 'range keys from in-memory index tree' (duration: 119.052113ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:56:47.944802Z","caller":"traceutil/trace.go:171","msg":"trace[1858955933] transaction","detail":"{read_only:false; response_revision:7690; number_of_response:1; }","duration":"149.199412ms","start":"2026-02-26T01:56:47.795577Z","end":"2026-02-26T01:56:47.944777Z","steps":["trace[1858955933] 'process raft request' (duration: 149.049239ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:57:07.100459Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5765} {"level":"info","ts":"2026-02-26T01:57:07.187825Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5765,"took":"86.024847ms","hash":1196539631,"current-db-size-bytes":44105728,"current-db-size":"44 MB","current-db-size-in-use-bytes":23281664,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-02-26T01:57:07.187895Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1196539631,"revision":5765,"compact-revision":2768} {"level":"info","ts":"2026-02-26T01:57:11.399405Z","caller":"traceutil/trace.go:171","msg":"trace[1376028751] transaction","detail":"{read_only:false; response_revision:7857; number_of_response:1; }","duration":"156.462787ms","start":"2026-02-26T01:57:11.242919Z","end":"2026-02-26T01:57:11.399382Z","steps":["trace[1376028751] 'process raft request' (duration: 156.292234ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:57:11.400806Z","caller":"traceutil/trace.go:171","msg":"trace[376450394] linearizableReadLoop","detail":"{readStateIndex:8144; appliedIndex:8142; }","duration":"135.117682ms","start":"2026-02-26T01:57:11.26567Z","end":"2026-02-26T01:57:11.400787Z","steps":["trace[376450394] 'read index received' (duration: 133.598333ms)","trace[376450394] 'applied index is now lower than readState.Index' (duration: 1.518749ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:57:11.400886Z","caller":"traceutil/trace.go:171","msg":"trace[1720821368] transaction","detail":"{read_only:false; response_revision:7858; number_of_response:1; }","duration":"141.405797ms","start":"2026-02-26T01:57:11.259473Z","end":"2026-02-26T01:57:11.400879Z","steps":["trace[1720821368] 'process raft request' (duration: 141.215163ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:57:11.401086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.403479ms","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-02-26T01:57:11.401131Z","caller":"traceutil/trace.go:171","msg":"trace[815834900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7858; }","duration":"135.4825ms","start":"2026-02-26T01:57:11.265639Z","end":"2026-02-26T01:57:11.401122Z","steps":["trace[815834900] 'agreement among raft nodes before linearized reading' (duration: 135.352367ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:57:11.401148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.550286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-26T01:57:11.401235Z","caller":"traceutil/trace.go:171","msg":"trace[1996776720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7858; }","duration":"103.676809ms","start":"2026-02-26T01:57:11.29754Z","end":"2026-02-26T01:57:11.401217Z","steps":["trace[1996776720] 'agreement among raft nodes before linearized reading' (duration: 103.408984ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:57:14.78549Z","caller":"traceutil/trace.go:171","msg":"trace[1620829143] linearizableReadLoop","detail":"{readStateIndex:8159; appliedIndex:8158; }","duration":"202.061753ms","start":"2026-02-26T01:57:14.58339Z","end":"2026-02-26T01:57:14.785452Z","steps":["trace[1620829143] 'read index received' (duration: 201.856919ms)","trace[1620829143] 'applied index is now lower than readState.Index' (duration: 201.964µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:57:14.785542Z","caller":"traceutil/trace.go:171","msg":"trace[216117519] transaction","detail":"{read_only:false; response_revision:7873; number_of_response:1; }","duration":"363.881755ms","start":"2026-02-26T01:57:14.421627Z","end":"2026-02-26T01:57:14.785509Z","steps":["trace[216117519] 'process raft request' (duration: 363.690311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:57:14.785701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:57:14.42161Z","time spent":"364.009748ms","remote":"127.0.0.1:53058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-26T01:57:14.785795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.3692ms","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-02-26T01:57:14.785879Z","caller":"traceutil/trace.go:171","msg":"trace[548244644] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7873; }","duration":"202.509062ms","start":"2026-02-26T01:57:14.58335Z","end":"2026-02-26T01:57:14.785859Z","steps":["trace[548244644] 'agreement among raft nodes before linearized reading' (duration: 202.249277ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:57:18.181855Z","caller":"traceutil/trace.go:171","msg":"trace[2136998073] transaction","detail":"{read_only:false; response_revision:7891; number_of_response:1; }","duration":"208.740596ms","start":"2026-02-26T01:57:17.973096Z","end":"2026-02-26T01:57:18.181837Z","steps":["trace[2136998073] 'process raft request' (duration: 208.624334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:57:27.185853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.820841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-26T01:57:27.186053Z","caller":"traceutil/trace.go:171","msg":"trace[2078384987] transaction","detail":"{read_only:false; response_revision:7977; number_of_response:1; }","duration":"269.300677ms","start":"2026-02-26T01:57:26.91671Z","end":"2026-02-26T01:57:27.186011Z","steps":["trace[2078384987] 'process raft request' (duration: 97.217111ms)","trace[2078384987] 'compare' (duration: 171.698249ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:58:31.970759Z","caller":"traceutil/trace.go:171","msg":"trace[140996674] transaction","detail":"{read_only:false; response_revision:8627; number_of_response:1; }","duration":"152.493117ms","start":"2026-02-26T01:58:31.81825Z","end":"2026-02-26T01:58:31.970743Z","steps":["trace[140996674] 'process raft request' (duration: 152.385135ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:58:31.981085Z","caller":"traceutil/trace.go:171","msg":"trace[682928434] transaction","detail":"{read_only:false; response_revision:8628; number_of_response:1; }","duration":"159.291054ms","start":"2026-02-26T01:58:31.82177Z","end":"2026-02-26T01:58:31.981061Z","steps":["trace[682928434] 'process raft request' (duration: 159.133981ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:58:31.981222Z","caller":"traceutil/trace.go:171","msg":"trace[682648870] transaction","detail":"{read_only:false; response_revision:8629; number_of_response:1; }","duration":"139.057091ms","start":"2026-02-26T01:58:31.842135Z","end":"2026-02-26T01:58:31.981192Z","steps":["trace[682648870] 'process raft request' (duration: 138.887358ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:58:44.018493Z","caller":"traceutil/trace.go:171","msg":"trace[1936193398] linearizableReadLoop","detail":"{readStateIndex:9004; appliedIndex:9003; }","duration":"241.010616ms","start":"2026-02-26T01:58:43.777456Z","end":"2026-02-26T01:58:44.018467Z","steps":["trace[1936193398] 'read index received' (duration: 240.846392ms)","trace[1936193398] 'applied index is now lower than readState.Index' (duration: 163.054µs)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:58:44.018543Z","caller":"traceutil/trace.go:171","msg":"trace[1471687670] transaction","detail":"{read_only:false; response_revision:8690; number_of_response:1; }","duration":"304.936656ms","start":"2026-02-26T01:58:43.71358Z","end":"2026-02-26T01:58:44.018517Z","steps":["trace[1471687670] 'process raft request' (duration: 304.733352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:58:44.018705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.22769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-26T01:58:44.018797Z","caller":"traceutil/trace.go:171","msg":"trace[989569599] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:8690; }","duration":"241.332232ms","start":"2026-02-26T01:58:43.777441Z","end":"2026-02-26T01:58:44.018773Z","steps":["trace[989569599] 'agreement among raft nodes before linearized reading' (duration: 241.154588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:58:44.018814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-26T01:58:43.713552Z","time spent":"305.151309ms","remote":"127.0.0.1:52960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19300,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-26T01:58:44.179992Z","caller":"traceutil/trace.go:171","msg":"trace[1261957133] transaction","detail":"{read_only:false; response_revision:8692; number_of_response:1; }","duration":"168.6896ms","start":"2026-02-26T01:58:44.011276Z","end":"2026-02-26T01:58:44.179966Z","steps":["trace[1261957133] 'process raft request' (duration: 168.609498ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:58:44.180123Z","caller":"traceutil/trace.go:171","msg":"trace[1166043989] linearizableReadLoop","detail":"{readStateIndex:9007; appliedIndex:9004; }","duration":"148.218343ms","start":"2026-02-26T01:58:44.031868Z","end":"2026-02-26T01:58:44.180086Z","steps":["trace[1166043989] 'read index received' (duration: 103.137057ms)","trace[1166043989] 'applied index is now lower than readState.Index' (duration: 45.079166ms)"],"step_count":2} {"level":"info","ts":"2026-02-26T01:58:44.180144Z","caller":"traceutil/trace.go:171","msg":"trace[1159925690] transaction","detail":"{read_only:false; response_revision:8693; number_of_response:1; }","duration":"166.335844ms","start":"2026-02-26T01:58:44.013778Z","end":"2026-02-26T01:58:44.180113Z","steps":["trace[1159925690] 'process raft request' (duration: 166.14889ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T01:58:44.180004Z","caller":"traceutil/trace.go:171","msg":"trace[721339940] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"173.431734ms","start":"2026-02-26T01:58:44.006538Z","end":"2026-02-26T01:58:44.179969Z","steps":["trace[721339940] 'process raft request' (duration: 128.482131ms)","trace[721339940] 'compare' (duration: 44.753298ms)"],"step_count":2} {"level":"warn","ts":"2026-02-26T01:58:44.18031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.442108ms","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-02-26T01:58:44.18037Z","caller":"traceutil/trace.go:171","msg":"trace[21518109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8693; }","duration":"148.544319ms","start":"2026-02-26T01:58:44.031812Z","end":"2026-02-26T01:58:44.180357Z","steps":["trace[21518109] 'agreement among raft nodes before linearized reading' (duration: 148.356426ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:58:44.180387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.706604ms","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-02-26T01:58:44.180429Z","caller":"traceutil/trace.go:171","msg":"trace[701680380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8693; }","duration":"142.780816ms","start":"2026-02-26T01:58:44.037636Z","end":"2026-02-26T01:58:44.180416Z","steps":["trace[701680380] 'agreement among raft nodes before linearized reading' (duration: 142.612362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-26T01:58:44.180439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.16232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-02-26T01:58:44.180499Z","caller":"traceutil/trace.go:171","msg":"trace[1534442342] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8693; }","duration":"100.272161ms","start":"2026-02-26T01:58:44.080214Z","end":"2026-02-26T01:58:44.180486Z","steps":["trace[1534442342] 'agreement among raft nodes before linearized reading' (duration: 100.100538ms)"],"step_count":1} {"level":"info","ts":"2026-02-26T02:01:16.774543Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"1c917701ce165bac","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-26T02:01:16.780036Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-26T02:01:16.780187Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-26T02:02:07.106365Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7787} {"level":"info","ts":"2026-02-26T02:02:07.176097Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7787,"took":"66.49702ms","hash":3428943618,"current-db-size-bytes":44105728,"current-db-size":"44 MB","current-db-size-in-use-bytes":25608192,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-02-26T02:02:07.176169Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3428943618,"revision":7787,"compact-revision":5765}