{"level":"warn","ts":"2026-02-25T03:05:20.240316Z","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-25T03:05:20.240399Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.25: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.25:2380","--initial-cluster=instance=https://199.204.45.25:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.25:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.25: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-25T03:05:20.240467Z","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-25T03:05:20.240479Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-02-25T03:05:20.240499Z","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-25T03:05:20.241135Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"]} {"level":"info","ts":"2026-02-25T03:05:20.241207Z","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.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.25: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-25T03:05:20.245786Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.364819ms"} {"level":"info","ts":"2026-02-25T03:05:20.255541Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ba52c55cf4193752","cluster-id":"4468ed52294bc271"} {"level":"info","ts":"2026-02-25T03:05:20.255639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=()"} {"level":"info","ts":"2026-02-25T03:05:20.255665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 0"} {"level":"info","ts":"2026-02-25T03:05:20.255678Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ba52c55cf4193752 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-25T03:05:20.255687Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became follower at term 1"} {"level":"info","ts":"2026-02-25T03:05:20.25573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"warn","ts":"2026-02-25T03:05:20.263429Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-25T03:05:20.264833Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-25T03:05:20.266905Z","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-25T03:05:20.269215Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ba52c55cf4193752","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-25T03:05:20.269379Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ba52c55cf4193752","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-25T03:05:20.26946Z","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-25T03:05:20.269618Z","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-25T03:05:20.269648Z","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-25T03:05:20.270579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 switched to configuration voters=(13426010442167826258)"} {"level":"info","ts":"2026-02-25T03:05:20.27118Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","added-peer-id":"ba52c55cf4193752","added-peer-peer-urls":["https://199.204.45.25:2380"]} {"level":"info","ts":"2026-02-25T03:05:20.273135Z","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-25T03:05:20.273251Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-02-25T03:05:20.273291Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.25:2380"} {"level":"info","ts":"2026-02-25T03:05:20.273421Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ba52c55cf4193752","initial-advertise-peer-urls":["https://199.204.45.25:2380"],"listen-peer-urls":["https://199.204.45.25:2380"],"advertise-client-urls":["https://199.204.45.25:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.25:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-25T03:05:20.273476Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-25T03:05:20.456786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 is starting a new election at term 1"} {"level":"info","ts":"2026-02-25T03:05:20.456849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-25T03:05:20.456884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgPreVoteResp from ba52c55cf4193752 at term 1"} {"level":"info","ts":"2026-02-25T03:05:20.456905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became candidate at term 2"} {"level":"info","ts":"2026-02-25T03:05:20.456916Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 received MsgVoteResp from ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-02-25T03:05:20.456938Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ba52c55cf4193752 became leader at term 2"} {"level":"info","ts":"2026-02-25T03:05:20.456951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ba52c55cf4193752 elected leader ba52c55cf4193752 at term 2"} {"level":"info","ts":"2026-02-25T03:05:20.458381Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ba52c55cf4193752","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.25:2379]}","request-path":"/0/members/ba52c55cf4193752/attributes","cluster-id":"4468ed52294bc271","publish-timeout":"7s"} {"level":"info","ts":"2026-02-25T03:05:20.458425Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T03:05:20.458504Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T03:05:20.45855Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-25T03:05:20.458713Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-25T03:05:20.458764Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-25T03:05:20.459463Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4468ed52294bc271","local-member-id":"ba52c55cf4193752","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T03:05:20.459558Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T03:05:20.459599Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-25T03:05:20.461985Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-25T03:05:20.462218Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.25:2379"} {"level":"info","ts":"2026-02-25T03:05:36.124194Z","caller":"traceutil/trace.go:171","msg":"trace[2015496058] linearizableReadLoop","detail":"{readStateIndex:422; appliedIndex:421; }","duration":"352.008022ms","start":"2026-02-25T03:05:35.77216Z","end":"2026-02-25T03:05:36.124168Z","steps":["trace[2015496058] 'read index received' (duration: 351.630126ms)","trace[2015496058] 'applied index is now lower than readState.Index' (duration: 377.026µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:05:36.124279Z","caller":"traceutil/trace.go:171","msg":"trace[602638934] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"437.547688ms","start":"2026-02-25T03:05:35.686709Z","end":"2026-02-25T03:05:36.124257Z","steps":["trace[602638934] 'process raft request' (duration: 437.226992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:36.124375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.196295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4024"} {"level":"info","ts":"2026-02-25T03:05:36.124493Z","caller":"traceutil/trace.go:171","msg":"trace[1233760720] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:410; }","duration":"352.353969ms","start":"2026-02-25T03:05:35.772117Z","end":"2026-02-25T03:05:36.124471Z","steps":["trace[1233760720] 'agreement among raft nodes before linearized reading' (duration: 352.160295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:36.12454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:35.772095Z","time spent":"352.43486ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4047,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-25T03:05:36.124867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:35.686682Z","time spent":"437.65032ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T03:05:36.724665Z","caller":"traceutil/trace.go:171","msg":"trace[1110143140] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"338.641468ms","start":"2026-02-25T03:05:36.385996Z","end":"2026-02-25T03:05:36.724637Z","steps":["trace[1110143140] 'read index received' (duration: 338.482435ms)","trace[1110143140] 'applied index is now lower than readState.Index' (duration: 157.393µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:05:36.724821Z","caller":"traceutil/trace.go:171","msg":"trace[501786575] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"548.334497ms","start":"2026-02-25T03:05:36.176403Z","end":"2026-02-25T03:05:36.724738Z","steps":["trace[501786575] 'process raft request' (duration: 548.097813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:36.724828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.812381ms","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-25T03:05:36.724929Z","caller":"traceutil/trace.go:171","msg":"trace[449047497] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:411; }","duration":"338.966684ms","start":"2026-02-25T03:05:36.38595Z","end":"2026-02-25T03:05:36.724917Z","steps":["trace[449047497] 'agreement among raft nodes before linearized reading' (duration: 338.811481ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:36.724977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:36.385932Z","time spent":"339.034155ms","remote":"127.0.0.1:36162","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-25T03:05:36.72513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:36.176381Z","time spent":"548.5209ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:05:47.810377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.872825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-02-25T03:05:47.810513Z","caller":"traceutil/trace.go:171","msg":"trace[1376333422] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:468; }","duration":"343.032879ms","start":"2026-02-25T03:05:47.467437Z","end":"2026-02-25T03:05:47.81047Z","steps":["trace[1376333422] 'range keys from in-memory index tree' (duration: 342.752892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:47.810593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:47.467423Z","time spent":"343.154021ms","remote":"127.0.0.1:35976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":3,"response size":12692,"request content":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" "} {"level":"info","ts":"2026-02-25T03:05:50.968838Z","caller":"traceutil/trace.go:171","msg":"trace[385541468] linearizableReadLoop","detail":"{readStateIndex:525; appliedIndex:524; }","duration":"171.550759ms","start":"2026-02-25T03:05:50.797263Z","end":"2026-02-25T03:05:50.968813Z","steps":["trace[385541468] 'read index received' (duration: 171.393427ms)","trace[385541468] 'applied index is now lower than readState.Index' (duration: 156.382µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:05:50.968891Z","caller":"traceutil/trace.go:171","msg":"trace[146431742] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"304.160156ms","start":"2026-02-25T03:05:50.664696Z","end":"2026-02-25T03:05:50.968856Z","steps":["trace[146431742] 'process raft request' (duration: 303.954843ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:50.969042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:50.66467Z","time spent":"304.292729ms","remote":"127.0.0.1:36050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4216,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:05:50.969051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.755913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T03:05:50.969115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.788804ms","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-25T03:05:50.969183Z","caller":"traceutil/trace.go:171","msg":"trace[848383770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:508; }","duration":"171.864666ms","start":"2026-02-25T03:05:50.797255Z","end":"2026-02-25T03:05:50.96912Z","steps":["trace[848383770] 'agreement among raft nodes before linearized reading' (duration: 171.685962ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:05:50.969215Z","caller":"traceutil/trace.go:171","msg":"trace[1533523196] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"171.931377ms","start":"2026-02-25T03:05:50.797257Z","end":"2026-02-25T03:05:50.969189Z","steps":["trace[1533523196] 'agreement among raft nodes before linearized reading' (duration: 171.724042ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:05:56.879Z","caller":"traceutil/trace.go:171","msg":"trace[798805210] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"176.022341ms","start":"2026-02-25T03:05:56.702955Z","end":"2026-02-25T03:05:56.878977Z","steps":["trace[798805210] 'read index received' (duration: 175.841748ms)","trace[798805210] 'applied index is now lower than readState.Index' (duration: 179.683µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:05:56.879092Z","caller":"traceutil/trace.go:171","msg":"trace[1117331659] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"178.366755ms","start":"2026-02-25T03:05:56.700692Z","end":"2026-02-25T03:05:56.879059Z","steps":["trace[1117331659] 'process raft request' (duration: 178.062858ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:56.879198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.228736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dg4rh\" ","response":"range_response_count:1 size:16652"} {"level":"info","ts":"2026-02-25T03:05:56.879271Z","caller":"traceutil/trace.go:171","msg":"trace[1550403111] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dg4rh; range_end:; response_count:1; response_revision:548; }","duration":"176.348218ms","start":"2026-02-25T03:05:56.702901Z","end":"2026-02-25T03:05:56.879249Z","steps":["trace[1550403111] 'agreement among raft nodes before linearized reading' (duration: 176.209116ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:05:57.218187Z","caller":"traceutil/trace.go:171","msg":"trace[1242669288] linearizableReadLoop","detail":"{readStateIndex:567; appliedIndex:566; }","duration":"212.340726ms","start":"2026-02-25T03:05:57.005826Z","end":"2026-02-25T03:05:57.218167Z","steps":["trace[1242669288] 'read index received' (duration: 212.156953ms)","trace[1242669288] 'applied index is now lower than readState.Index' (duration: 182.923µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:05:57.218235Z","caller":"traceutil/trace.go:171","msg":"trace[1667603638] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"325.913292ms","start":"2026-02-25T03:05:56.892301Z","end":"2026-02-25T03:05:57.218215Z","steps":["trace[1667603638] 'process raft request' (duration: 325.745199ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:57.218356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.79337ms","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-25T03:05:57.218385Z","caller":"traceutil/trace.go:171","msg":"trace[1981634465] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"209.868491ms","start":"2026-02-25T03:05:57.008509Z","end":"2026-02-25T03:05:57.218377Z","steps":["trace[1981634465] 'agreement among raft nodes before linearized reading' (duration: 209.758039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:57.218379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:56.892274Z","time spent":"326.007893ms","remote":"127.0.0.1:36058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:05:57.218401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.232257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T03:05:57.218436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.589121ms","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-25T03:05:57.218506Z","caller":"traceutil/trace.go:171","msg":"trace[1165835026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"135.38926ms","start":"2026-02-25T03:05:57.083092Z","end":"2026-02-25T03:05:57.218482Z","steps":["trace[1165835026] 'agreement among raft nodes before linearized reading' (duration: 135.228497ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:05:57.218509Z","caller":"traceutil/trace.go:171","msg":"trace[1504123181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:549; }","duration":"212.714473ms","start":"2026-02-25T03:05:57.005777Z","end":"2026-02-25T03:05:57.218491Z","steps":["trace[1504123181] 'agreement among raft nodes before linearized reading' (duration: 212.489039ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:57.566642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.166784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T03:05:57.566727Z","caller":"traceutil/trace.go:171","msg":"trace[722084388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:551; }","duration":"237.299177ms","start":"2026-02-25T03:05:57.329409Z","end":"2026-02-25T03:05:57.566708Z","steps":["trace[722084388] 'range keys from in-memory index tree' (duration: 236.971031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:57.566672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.863435ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T03:05:57.566739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.986382ms","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-25T03:05:57.56679Z","caller":"traceutil/trace.go:171","msg":"trace[1634299296] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:551; }","duration":"238.992607ms","start":"2026-02-25T03:05:57.327778Z","end":"2026-02-25T03:05:57.566771Z","steps":["trace[1634299296] 'range keys from in-memory index tree' (duration: 238.847344ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:05:57.566812Z","caller":"traceutil/trace.go:171","msg":"trace[1231436383] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:551; }","duration":"115.117984ms","start":"2026-02-25T03:05:57.451675Z","end":"2026-02-25T03:05:57.566793Z","steps":["trace[1231436383] 'range keys from in-memory index tree' (duration: 114.781419ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:05:57.839707Z","caller":"traceutil/trace.go:171","msg":"trace[1328992187] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"268.711872ms","start":"2026-02-25T03:05:57.570957Z","end":"2026-02-25T03:05:57.839668Z","steps":["trace[1328992187] 'process raft request' (duration: 268.474697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:58.352912Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420774311986099,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T03:05:58.649434Z","caller":"traceutil/trace.go:171","msg":"trace[217943923] linearizableReadLoop","detail":"{readStateIndex:571; appliedIndex:570; }","duration":"797.172603ms","start":"2026-02-25T03:05:57.852233Z","end":"2026-02-25T03:05:58.649405Z","steps":["trace[217943923] 'read index received' (duration: 797.044461ms)","trace[217943923] 'applied index is now lower than readState.Index' (duration: 126.912µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:05:58.649532Z","caller":"traceutil/trace.go:171","msg":"trace[2100071320] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"1.078469502s","start":"2026-02-25T03:05:57.571024Z","end":"2026-02-25T03:05:58.649494Z","steps":["trace[2100071320] 'process raft request' (duration: 1.078237858s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:58.649603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.349756ms","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-25T03:05:58.649747Z","caller":"traceutil/trace.go:171","msg":"trace[1439594269] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:553; }","duration":"797.530739ms","start":"2026-02-25T03:05:57.8522Z","end":"2026-02-25T03:05:58.649731Z","steps":["trace[1439594269] 'agreement among raft nodes before linearized reading' (duration: 797.346396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:58.649717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.798063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T03:05:58.649788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:57.852187Z","time spent":"797.590421ms","remote":"127.0.0.1:35976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" "} {"level":"info","ts":"2026-02-25T03:05:58.649825Z","caller":"traceutil/trace.go:171","msg":"trace[893595252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"566.943275ms","start":"2026-02-25T03:05:58.082853Z","end":"2026-02-25T03:05:58.649796Z","steps":["trace[893595252] 'agreement among raft nodes before linearized reading' (duration: 566.782532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:05:58.649893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:58.082834Z","time spent":"567.044267ms","remote":"127.0.0.1:35876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-25T03:05:58.649931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:57.570992Z","time spent":"1.078660854s","remote":"127.0.0.1:36162","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-25T03:06:00.181971Z","caller":"traceutil/trace.go:171","msg":"trace[1977736511] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"334.899924ms","start":"2026-02-25T03:05:59.84704Z","end":"2026-02-25T03:06:00.18194Z","steps":["trace[1977736511] 'process raft request' (duration: 331.090335ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:00.182154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:05:59.847021Z","time spent":"335.041477ms","remote":"127.0.0.1:36162","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-25T03:06:00.189381Z","caller":"traceutil/trace.go:171","msg":"trace[518921081] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:693; }","duration":"251.686335ms","start":"2026-02-25T03:05:59.937671Z","end":"2026-02-25T03:06:00.189358Z","steps":["trace[518921081] 'read index received' (duration: 240.550314ms)","trace[518921081] 'applied index is now lower than readState.Index' (duration: 11.135171ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:06:00.189482Z","caller":"traceutil/trace.go:171","msg":"trace[246370022] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"256.814498ms","start":"2026-02-25T03:05:59.932658Z","end":"2026-02-25T03:06:00.189472Z","steps":["trace[246370022] 'process raft request' (duration: 256.433731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:00.189589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.811487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T03:06:00.189621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.865796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:06:00.189677Z","caller":"traceutil/trace.go:171","msg":"trace[657992286] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:676; }","duration":"251.921439ms","start":"2026-02-25T03:05:59.937736Z","end":"2026-02-25T03:06:00.189657Z","steps":["trace[657992286] 'agreement among raft nodes before linearized reading' (duration: 251.777466ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:06:00.1897Z","caller":"traceutil/trace.go:171","msg":"trace[1179313868] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:676; }","duration":"232.984857ms","start":"2026-02-25T03:05:59.956694Z","end":"2026-02-25T03:06:00.189679Z","steps":["trace[1179313868] 'agreement among raft nodes before linearized reading' (duration: 232.841415ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:00.189707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.663941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T03:06:00.189691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.98597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:06:00.189758Z","caller":"traceutil/trace.go:171","msg":"trace[1661159697] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:676; }","duration":"106.733823ms","start":"2026-02-25T03:06:00.083009Z","end":"2026-02-25T03:06:00.189742Z","steps":["trace[1661159697] 'agreement among raft nodes before linearized reading' (duration: 106.662022ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:00.189738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.294123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:06:00.189781Z","caller":"traceutil/trace.go:171","msg":"trace[457957659] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:676; }","duration":"252.122242ms","start":"2026-02-25T03:05:59.937633Z","end":"2026-02-25T03:06:00.189755Z","steps":["trace[457957659] 'agreement among raft nodes before linearized reading' (duration: 251.952229ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:06:00.189844Z","caller":"traceutil/trace.go:171","msg":"trace[2120482264] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:676; }","duration":"233.429366ms","start":"2026-02-25T03:05:59.956381Z","end":"2026-02-25T03:06:00.189811Z","steps":["trace[2120482264] 'agreement among raft nodes before linearized reading' (duration: 233.224833ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:00.189826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.375189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-25T03:06:00.189949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.454847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:06:00.190038Z","caller":"traceutil/trace.go:171","msg":"trace[188850327] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:676; }","duration":"233.599369ms","start":"2026-02-25T03:05:59.956412Z","end":"2026-02-25T03:06:00.190011Z","steps":["trace[188850327] 'agreement among raft nodes before linearized reading' (duration: 233.409476ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:06:00.190049Z","caller":"traceutil/trace.go:171","msg":"trace[545012766] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:676; }","duration":"251.527941ms","start":"2026-02-25T03:05:59.93837Z","end":"2026-02-25T03:06:00.189898Z","steps":["trace[545012766] 'agreement among raft nodes before linearized reading' (duration: 251.324257ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:06:18.080234Z","caller":"traceutil/trace.go:171","msg":"trace[1122006194] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"207.806985ms","start":"2026-02-25T03:06:17.872288Z","end":"2026-02-25T03:06:18.080095Z","steps":["trace[1122006194] 'process raft request' (duration: 183.619068ms)","trace[1122006194] 'compare' (duration: 24.070195ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:06:19.049316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.34526ms","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-25T03:06:19.049307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.603837ms","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-25T03:06:19.049412Z","caller":"traceutil/trace.go:171","msg":"trace[23258168] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:928; }","duration":"296.495083ms","start":"2026-02-25T03:06:18.752896Z","end":"2026-02-25T03:06:19.049391Z","steps":["trace[23258168] 'range keys from in-memory index tree' (duration: 296.154787ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:06:19.049423Z","caller":"traceutil/trace.go:171","msg":"trace[2060354797] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:928; }","duration":"562.79602ms","start":"2026-02-25T03:06:18.486607Z","end":"2026-02-25T03:06:19.049403Z","steps":["trace[2060354797] 'range keys from in-memory index tree' (duration: 562.415234ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:19.049427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.423517ms","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-25T03:06:19.049509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:06:18.486578Z","time spent":"562.879182ms","remote":"127.0.0.1:36162","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-25T03:06:19.049523Z","caller":"traceutil/trace.go:171","msg":"trace[685954673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:928; }","duration":"782.58064ms","start":"2026-02-25T03:06:18.266921Z","end":"2026-02-25T03:06:19.049502Z","steps":["trace[685954673] 'range keys from in-memory index tree' (duration: 782.164903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:06:19.049581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:06:18.266897Z","time spent":"782.670622ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-25T03:06:43.032153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.053338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:06:43.032242Z","caller":"traceutil/trace.go:171","msg":"trace[74935901] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1087; }","duration":"274.18692ms","start":"2026-02-25T03:06:42.75803Z","end":"2026-02-25T03:06:43.032217Z","steps":["trace[74935901] 'count revisions from in-memory index tree' (duration: 273.969817ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:13.262505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.855452ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T03:07:13.262856Z","caller":"traceutil/trace.go:171","msg":"trace[462836880] linearizableReadLoop","detail":"{readStateIndex:1657; appliedIndex:1655; }","duration":"1.1134745s","start":"2026-02-25T03:07:12.149355Z","end":"2026-02-25T03:07:13.262829Z","steps":["trace[462836880] 'read index received' (duration: 35.020277ms)","trace[462836880] 'applied index is now lower than readState.Index' (duration: 1.078451463s)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:07:13.262917Z","caller":"traceutil/trace.go:171","msg":"trace[1672335382] transaction","detail":"{read_only:false; response_revision:1595; number_of_response:1; }","duration":"1.149322334s","start":"2026-02-25T03:07:12.113539Z","end":"2026-02-25T03:07:13.262861Z","steps":["trace[1672335382] 'process raft request' (duration: 1.005715909s)","trace[1672335382] 'compare' (duration: 142.494264ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:07:13.263023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113647974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8591a0f2-4e3b-40e2-a663-88b27a7cfa32\" ","response":"range_response_count:1 size:3677"} {"level":"info","ts":"2026-02-25T03:07:13.26314Z","caller":"traceutil/trace.go:171","msg":"trace[1349917809] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8591a0f2-4e3b-40e2-a663-88b27a7cfa32; range_end:; response_count:1; response_revision:1595; }","duration":"1.113755616s","start":"2026-02-25T03:07:12.149337Z","end":"2026-02-25T03:07:13.263092Z","steps":["trace[1349917809] 'agreement among raft nodes before linearized reading' (duration: 1.113581043s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:13.263146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:12.113516Z","time spent":"1.149506817s","remote":"127.0.0.1:36058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3662,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:07:13.26322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:12.149272Z","time spent":"1.11393002s","remote":"127.0.0.1:36058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3700,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8591a0f2-4e3b-40e2-a663-88b27a7cfa32\" "} {"level":"warn","ts":"2026-02-25T03:07:13.263353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.217044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:07:13.263437Z","caller":"traceutil/trace.go:171","msg":"trace[21631850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1595; }","duration":"179.354147ms","start":"2026-02-25T03:07:13.084061Z","end":"2026-02-25T03:07:13.263415Z","steps":["trace[21631850] 'agreement among raft nodes before linearized reading' (duration: 179.201404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:13.264295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020724952s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5356"} {"level":"info","ts":"2026-02-25T03:07:13.264353Z","caller":"traceutil/trace.go:171","msg":"trace[1802580696] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1595; }","duration":"1.020834454s","start":"2026-02-25T03:07:12.243502Z","end":"2026-02-25T03:07:13.264336Z","steps":["trace[1802580696] 'agreement among raft nodes before linearized reading' (duration: 1.01955716s)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:13.264419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:12.24348Z","time spent":"1.020926705s","remote":"127.0.0.1:47402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5379,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-02-25T03:07:13.496635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.904621ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T03:07:13.496785Z","caller":"traceutil/trace.go:171","msg":"trace[466568423] linearizableReadLoop","detail":"{readStateIndex:1658; appliedIndex:1657; }","duration":"169.527448ms","start":"2026-02-25T03:07:13.32723Z","end":"2026-02-25T03:07:13.496757Z","steps":["trace[466568423] 'read index received' (duration: 53.304061ms)","trace[466568423] 'applied index is now lower than readState.Index' (duration: 116.217327ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:07:13.496858Z","caller":"traceutil/trace.go:171","msg":"trace[857273565] transaction","detail":"{read_only:false; response_revision:1596; number_of_response:1; }","duration":"219.895145ms","start":"2026-02-25T03:07:13.276903Z","end":"2026-02-25T03:07:13.496798Z","steps":["trace[857273565] 'process raft request' (duration: 103.701799ms)","trace[857273565] 'compare' (duration: 115.786639ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:07:13.497063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.004116ms","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-25T03:07:13.497128Z","caller":"traceutil/trace.go:171","msg":"trace[358688085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1596; }","duration":"165.132238ms","start":"2026-02-25T03:07:13.331979Z","end":"2026-02-25T03:07:13.497112Z","steps":["trace[358688085] 'agreement among raft nodes before linearized reading' (duration: 164.945454ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:13.497138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.028176ms","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-25T03:07:13.497157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.895165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T03:07:13.497219Z","caller":"traceutil/trace.go:171","msg":"trace[476311288] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1596; }","duration":"165.142339ms","start":"2026-02-25T03:07:13.332057Z","end":"2026-02-25T03:07:13.4972Z","steps":["trace[476311288] 'agreement among raft nodes before linearized reading' (duration: 164.931214ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:13.49729Z","caller":"traceutil/trace.go:171","msg":"trace[785350736] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1596; }","duration":"170.092179ms","start":"2026-02-25T03:07:13.327168Z","end":"2026-02-25T03:07:13.49726Z","steps":["trace[785350736] 'agreement among raft nodes before linearized reading' (duration: 169.730861ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:14.064815Z","caller":"traceutil/trace.go:171","msg":"trace[896843184] transaction","detail":"{read_only:false; response_revision:1597; number_of_response:1; }","duration":"563.181517ms","start":"2026-02-25T03:07:13.501606Z","end":"2026-02-25T03:07:14.064788Z","steps":["trace[896843184] 'process raft request' (duration: 563.014144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:14.064979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:13.501575Z","time spent":"563.31824ms","remote":"127.0.0.1:36162","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-25T03:07:14.2252Z","caller":"traceutil/trace.go:171","msg":"trace[1442291054] transaction","detail":"{read_only:false; response_revision:1598; number_of_response:1; }","duration":"722.390548ms","start":"2026-02-25T03:07:13.502762Z","end":"2026-02-25T03:07:14.225153Z","steps":["trace[1442291054] 'process raft request' (duration: 722.190533ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:14.2253Z","caller":"traceutil/trace.go:171","msg":"trace[1050896714] transaction","detail":"{read_only:false; response_revision:1599; number_of_response:1; }","duration":"722.289866ms","start":"2026-02-25T03:07:13.502954Z","end":"2026-02-25T03:07:14.225244Z","steps":["trace[1050896714] 'process raft request' (duration: 722.137943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:14.225398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:13.50273Z","time spent":"722.566771ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:07:14.225553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:13.502928Z","time spent":"722.504439ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T03:07:14.225594Z","caller":"traceutil/trace.go:171","msg":"trace[1817330369] transaction","detail":"{read_only:false; response_revision:1600; number_of_response:1; }","duration":"722.597332ms","start":"2026-02-25T03:07:13.502961Z","end":"2026-02-25T03:07:14.225558Z","steps":["trace[1817330369] 'process raft request' (duration: 722.178704ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:14.225624Z","caller":"traceutil/trace.go:171","msg":"trace[1854585003] transaction","detail":"{read_only:false; response_revision:1601; number_of_response:1; }","duration":"721.908489ms","start":"2026-02-25T03:07:13.50369Z","end":"2026-02-25T03:07:14.225599Z","steps":["trace[1854585003] 'process raft request' (duration: 721.731866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:14.22576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:13.502932Z","time spent":"722.739044ms","remote":"127.0.0.1:36166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:07:14.225786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:13.503675Z","time spent":"722.026641ms","remote":"127.0.0.1:36166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T03:07:14.225841Z","caller":"traceutil/trace.go:171","msg":"trace[646538921] transaction","detail":"{read_only:false; response_revision:1602; number_of_response:1; }","duration":"721.833258ms","start":"2026-02-25T03:07:13.503985Z","end":"2026-02-25T03:07:14.225818Z","steps":["trace[646538921] 'process raft request' (duration: 721.550483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:14.226019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:13.503975Z","time spent":"721.95264ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T03:07:14.226044Z","caller":"traceutil/trace.go:171","msg":"trace[1237905314] transaction","detail":"{read_only:false; response_revision:1603; number_of_response:1; }","duration":"721.598063ms","start":"2026-02-25T03:07:13.504432Z","end":"2026-02-25T03:07:14.22603Z","steps":["trace[1237905314] 'process raft request' (duration: 721.302148ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:14.226074Z","caller":"traceutil/trace.go:171","msg":"trace[938541621] linearizableReadLoop","detail":"{readStateIndex:1665; appliedIndex:1658; }","duration":"652.383232ms","start":"2026-02-25T03:07:13.573663Z","end":"2026-02-25T03:07:14.226046Z","steps":["trace[938541621] 'read index received' (duration: 491.046324ms)","trace[938541621] 'applied index is now lower than readState.Index' (duration: 161.335728ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:07:14.226126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:13.504425Z","time spent":"721.662554ms","remote":"127.0.0.1:36044","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T03:07:14.226144Z","caller":"traceutil/trace.go:171","msg":"trace[417423363] transaction","detail":"{read_only:false; response_revision:1604; number_of_response:1; }","duration":"649.217614ms","start":"2026-02-25T03:07:13.57689Z","end":"2026-02-25T03:07:14.226108Z","steps":["trace[417423363] 'process raft request' (duration: 649.025001ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:14.226217Z","caller":"traceutil/trace.go:171","msg":"trace[795343592] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"364.282695ms","start":"2026-02-25T03:07:13.86192Z","end":"2026-02-25T03:07:14.226202Z","steps":["trace[795343592] 'process raft request' (duration: 364.071981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:14.226327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:13.861885Z","time spent":"364.362037ms","remote":"127.0.0.1:36162","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-25T03:07:14.226327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:13.576864Z","time spent":"649.369817ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:07:14.226363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.679051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T03:07:14.226386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.727469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-02-25T03:07:14.226419Z","caller":"traceutil/trace.go:171","msg":"trace[156471377] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1605; }","duration":"650.772403ms","start":"2026-02-25T03:07:13.575632Z","end":"2026-02-25T03:07:14.226404Z","steps":["trace[156471377] 'agreement among raft nodes before linearized reading' (duration: 650.583219ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:14.226431Z","caller":"traceutil/trace.go:171","msg":"trace[94634659] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1605; }","duration":"652.79866ms","start":"2026-02-25T03:07:13.573618Z","end":"2026-02-25T03:07:14.226417Z","steps":["trace[94634659] 'agreement among raft nodes before linearized reading' (duration: 652.642427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:14.22646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:13.575611Z","time spent":"650.841983ms","remote":"127.0.0.1:36162","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-25T03:07:14.226464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:13.573604Z","time spent":"652.853181ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-02-25T03:07:14.226524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.435564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-25T03:07:14.22657Z","caller":"traceutil/trace.go:171","msg":"trace[708945838] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:1605; }","duration":"422.508776ms","start":"2026-02-25T03:07:13.804051Z","end":"2026-02-25T03:07:14.226559Z","steps":["trace[708945838] 'agreement among raft nodes before linearized reading' (duration: 422.374063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:14.226585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.186667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-25T03:07:14.226607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:13.804036Z","time spent":"422.564327ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"info","ts":"2026-02-25T03:07:14.226633Z","caller":"traceutil/trace.go:171","msg":"trace[142365950] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1605; }","duration":"642.251498ms","start":"2026-02-25T03:07:13.58437Z","end":"2026-02-25T03:07:14.226621Z","steps":["trace[142365950] 'agreement among raft nodes before linearized reading' (duration: 642.068004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:14.22667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:13.58436Z","time spent":"642.302039ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T03:07:14.37582Z","caller":"traceutil/trace.go:171","msg":"trace[1898199938] transaction","detail":"{read_only:false; response_revision:1606; number_of_response:1; }","duration":"143.671986ms","start":"2026-02-25T03:07:14.232123Z","end":"2026-02-25T03:07:14.375795Z","steps":["trace[1898199938] 'process raft request' (duration: 85.507557ms)","trace[1898199938] 'compare' (duration: 57.867903ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:07:14.376344Z","caller":"traceutil/trace.go:171","msg":"trace[896868236] transaction","detail":"{read_only:false; response_revision:1608; number_of_response:1; }","duration":"143.829098ms","start":"2026-02-25T03:07:14.232473Z","end":"2026-02-25T03:07:14.376302Z","steps":["trace[896868236] 'process raft request' (duration: 143.711146ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:14.376404Z","caller":"traceutil/trace.go:171","msg":"trace[380570323] linearizableReadLoop","detail":"{readStateIndex:1671; appliedIndex:1667; }","duration":"128.926409ms","start":"2026-02-25T03:07:14.247463Z","end":"2026-02-25T03:07:14.37639Z","steps":["trace[380570323] 'read index received' (duration: 56.268236ms)","trace[380570323] 'applied index is now lower than readState.Index' (duration: 72.655633ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:07:14.376398Z","caller":"traceutil/trace.go:171","msg":"trace[1312259620] transaction","detail":"{read_only:false; response_revision:1607; number_of_response:1; }","duration":"143.872829ms","start":"2026-02-25T03:07:14.232423Z","end":"2026-02-25T03:07:14.376295Z","steps":["trace[1312259620] 'process raft request' (duration: 143.503343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:14.376538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.056801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-25T03:07:14.376569Z","caller":"traceutil/trace.go:171","msg":"trace[1591285360] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1608; }","duration":"129.147563ms","start":"2026-02-25T03:07:14.247414Z","end":"2026-02-25T03:07:14.376561Z","steps":["trace[1591285360] 'agreement among raft nodes before linearized reading' (duration: 129.045101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:14.377058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.317087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5356"} {"level":"info","ts":"2026-02-25T03:07:14.377149Z","caller":"traceutil/trace.go:171","msg":"trace[1767991311] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1609; }","duration":"105.406509ms","start":"2026-02-25T03:07:14.271694Z","end":"2026-02-25T03:07:14.377101Z","steps":["trace[1767991311] 'agreement among raft nodes before linearized reading' (duration: 105.232306ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:14.377103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.235599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8591a0f2-4e3b-40e2-a663-88b27a7cfa32\" ","response":"range_response_count:1 size:3677"} {"level":"info","ts":"2026-02-25T03:07:14.377293Z","caller":"traceutil/trace.go:171","msg":"trace[1809561046] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8591a0f2-4e3b-40e2-a663-88b27a7cfa32; range_end:; response_count:1; response_revision:1609; }","duration":"109.481854ms","start":"2026-02-25T03:07:14.267795Z","end":"2026-02-25T03:07:14.377277Z","steps":["trace[1809561046] 'agreement among raft nodes before linearized reading' (duration: 109.060536ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:14.926129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.576443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18975e73dcd95016\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-02-25T03:07:14.926213Z","caller":"traceutil/trace.go:171","msg":"trace[1861384014] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18975e73dcd95016; range_end:; response_count:1; response_revision:1609; }","duration":"340.676204ms","start":"2026-02-25T03:07:14.585517Z","end":"2026-02-25T03:07:14.926194Z","steps":["trace[1861384014] 'range keys from in-memory index tree' (duration: 340.39541ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:14.926256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:14.585458Z","time spent":"340.788136ms","remote":"127.0.0.1:35968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":1005,"request content":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18975e73dcd95016\" "} {"level":"info","ts":"2026-02-25T03:07:14.957466Z","caller":"traceutil/trace.go:171","msg":"trace[2092636870] linearizableReadLoop","detail":"{readStateIndex:1673; appliedIndex:1672; }","duration":"102.264132ms","start":"2026-02-25T03:07:14.855168Z","end":"2026-02-25T03:07:14.957432Z","steps":["trace[2092636870] 'read index received' (duration: 102.127849ms)","trace[2092636870] 'applied index is now lower than readState.Index' (duration: 134.163µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:07:14.957581Z","caller":"traceutil/trace.go:171","msg":"trace[270391681] transaction","detail":"{read_only:false; response_revision:1610; number_of_response:1; }","duration":"319.648492ms","start":"2026-02-25T03:07:14.637907Z","end":"2026-02-25T03:07:14.957556Z","steps":["trace[270391681] 'process raft request' (duration: 319.394507ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:14.957719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:07:14.637888Z","time spent":"319.745465ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:07:14.95776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.439576ms","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-25T03:07:14.95781Z","caller":"traceutil/trace.go:171","msg":"trace[1726825032] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:1610; }","duration":"102.67692ms","start":"2026-02-25T03:07:14.855118Z","end":"2026-02-25T03:07:14.957795Z","steps":["trace[1726825032] 'agreement among raft nodes before linearized reading' (duration: 102.435696ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:26.311479Z","caller":"traceutil/trace.go:171","msg":"trace[2089341867] transaction","detail":"{read_only:false; response_revision:1698; number_of_response:1; }","duration":"194.067785ms","start":"2026-02-25T03:07:26.117391Z","end":"2026-02-25T03:07:26.311459Z","steps":["trace[2089341867] 'process raft request' (duration: 193.916182ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:26.564794Z","caller":"traceutil/trace.go:171","msg":"trace[84577034] transaction","detail":"{read_only:false; response_revision:1699; number_of_response:1; }","duration":"293.312373ms","start":"2026-02-25T03:07:26.271454Z","end":"2026-02-25T03:07:26.564766Z","steps":["trace[84577034] 'process raft request' (duration: 239.42181ms)","trace[84577034] 'compare' (duration: 53.599867ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:07:26.565017Z","caller":"traceutil/trace.go:171","msg":"trace[88336567] linearizableReadLoop","detail":"{readStateIndex:1766; appliedIndex:1763; }","duration":"281.935605ms","start":"2026-02-25T03:07:26.283057Z","end":"2026-02-25T03:07:26.564992Z","steps":["trace[88336567] 'read index received' (duration: 28.305135ms)","trace[88336567] 'applied index is now lower than readState.Index' (duration: 253.62951ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:07:26.565269Z","caller":"traceutil/trace.go:171","msg":"trace[129924950] transaction","detail":"{read_only:false; response_revision:1700; number_of_response:1; }","duration":"292.971506ms","start":"2026-02-25T03:07:26.272175Z","end":"2026-02-25T03:07:26.565147Z","steps":["trace[129924950] 'process raft request' (duration: 292.507038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:26.565388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.793614ms","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-25T03:07:26.565391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.316831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T03:07:26.565448Z","caller":"traceutil/trace.go:171","msg":"trace[1513025991] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1700; }","duration":"280.906155ms","start":"2026-02-25T03:07:26.284527Z","end":"2026-02-25T03:07:26.565434Z","steps":["trace[1513025991] 'agreement among raft nodes before linearized reading' (duration: 280.639121ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:26.565462Z","caller":"traceutil/trace.go:171","msg":"trace[634708404] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1700; }","duration":"282.422424ms","start":"2026-02-25T03:07:26.283024Z","end":"2026-02-25T03:07:26.565446Z","steps":["trace[634708404] 'agreement among raft nodes before linearized reading' (duration: 282.114889ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:26.565457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.622982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5356"} {"level":"warn","ts":"2026-02-25T03:07:26.565496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.655471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-02-25T03:07:26.565487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.393924ms","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-25T03:07:26.565519Z","caller":"traceutil/trace.go:171","msg":"trace[1736539409] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1700; }","duration":"106.737293ms","start":"2026-02-25T03:07:26.458765Z","end":"2026-02-25T03:07:26.565502Z","steps":["trace[1736539409] 'agreement among raft nodes before linearized reading' (duration: 106.55285ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:07:26.565463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.741545ms","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-25T03:07:26.565568Z","caller":"traceutil/trace.go:171","msg":"trace[2116695566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1700; }","duration":"131.515525ms","start":"2026-02-25T03:07:26.434034Z","end":"2026-02-25T03:07:26.565549Z","steps":["trace[2116695566] 'agreement among raft nodes before linearized reading' (duration: 131.149279ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:26.565552Z","caller":"traceutil/trace.go:171","msg":"trace[1474348893] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1700; }","duration":"140.740853ms","start":"2026-02-25T03:07:26.424797Z","end":"2026-02-25T03:07:26.565538Z","steps":["trace[1474348893] 'agreement among raft nodes before linearized reading' (duration: 140.618371ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:26.565665Z","caller":"traceutil/trace.go:171","msg":"trace[1157969207] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1700; }","duration":"128.930098ms","start":"2026-02-25T03:07:26.436661Z","end":"2026-02-25T03:07:26.565591Z","steps":["trace[1157969207] 'agreement among raft nodes before linearized reading' (duration: 128.517141ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:26.772322Z","caller":"traceutil/trace.go:171","msg":"trace[1120174069] transaction","detail":"{read_only:false; response_revision:1701; number_of_response:1; }","duration":"203.286302ms","start":"2026-02-25T03:07:26.56901Z","end":"2026-02-25T03:07:26.772297Z","steps":["trace[1120174069] 'process raft request' (duration: 168.826204ms)","trace[1120174069] 'compare' (duration: 34.156623ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:07:26.772808Z","caller":"traceutil/trace.go:171","msg":"trace[2050817231] transaction","detail":"{read_only:false; response_revision:1702; number_of_response:1; }","duration":"203.75354ms","start":"2026-02-25T03:07:26.569023Z","end":"2026-02-25T03:07:26.772776Z","steps":["trace[2050817231] 'process raft request' (duration: 203.205021ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:26.772886Z","caller":"traceutil/trace.go:171","msg":"trace[861146130] transaction","detail":"{read_only:false; response_revision:1703; number_of_response:1; }","duration":"203.475116ms","start":"2026-02-25T03:07:26.569387Z","end":"2026-02-25T03:07:26.772862Z","steps":["trace[861146130] 'process raft request' (duration: 203.346134ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:26.773029Z","caller":"traceutil/trace.go:171","msg":"trace[1398439436] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"203.314772ms","start":"2026-02-25T03:07:26.569693Z","end":"2026-02-25T03:07:26.773008Z","steps":["trace[1398439436] 'process raft request' (duration: 203.244101ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:07:26.773835Z","caller":"traceutil/trace.go:171","msg":"trace[1694314164] transaction","detail":"{read_only:false; response_revision:1704; number_of_response:1; }","duration":"204.381042ms","start":"2026-02-25T03:07:26.569421Z","end":"2026-02-25T03:07:26.773802Z","steps":["trace[1694314164] 'process raft request' (duration: 203.393074ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:15:20.798643Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2550} {"level":"info","ts":"2026-02-25T03:15:20.859345Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2550,"took":"59.847042ms","hash":1477996281,"current-db-size-bytes":16257024,"current-db-size":"16 MB","current-db-size-in-use-bytes":5398528,"current-db-size-in-use":"5.4 MB"} {"level":"info","ts":"2026-02-25T03:15:20.859412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1477996281,"revision":2550,"compact-revision":-1} {"level":"info","ts":"2026-02-25T03:18:41.061701Z","caller":"traceutil/trace.go:171","msg":"trace[1674509315] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"142.964529ms","start":"2026-02-25T03:18:40.918705Z","end":"2026-02-25T03:18:41.061669Z","steps":["trace[1674509315] 'process raft request' (duration: 142.796846ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:19:14.337235Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3986420774311997422,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-25T03:19:14.424468Z","caller":"traceutil/trace.go:171","msg":"trace[2103030939] linearizableReadLoop","detail":"{readStateIndex:5204; appliedIndex:5203; }","duration":"587.349978ms","start":"2026-02-25T03:19:13.837087Z","end":"2026-02-25T03:19:14.424437Z","steps":["trace[2103030939] 'read index received' (duration: 587.070443ms)","trace[2103030939] 'applied index is now lower than readState.Index' (duration: 278.355µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:19:14.424519Z","caller":"traceutil/trace.go:171","msg":"trace[218162907] transaction","detail":"{read_only:false; response_revision:4993; number_of_response:1; }","duration":"595.830375ms","start":"2026-02-25T03:19:13.828664Z","end":"2026-02-25T03:19:14.424494Z","steps":["trace[218162907] 'process raft request' (duration: 595.602171ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:19:14.424677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:19:13.828632Z","time spent":"595.948627ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:19:14.424791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.690254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5356"} {"level":"info","ts":"2026-02-25T03:19:14.424849Z","caller":"traceutil/trace.go:171","msg":"trace[1794302902] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:4993; }","duration":"587.763176ms","start":"2026-02-25T03:19:13.837067Z","end":"2026-02-25T03:19:14.42483Z","steps":["trace[1794302902] 'agreement among raft nodes before linearized reading' (duration: 587.540372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:19:14.42482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.48973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.25\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-02-25T03:19:14.424894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:19:13.837057Z","time spent":"587.828697ms","remote":"127.0.0.1:47402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5379,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-02-25T03:19:14.424923Z","caller":"traceutil/trace.go:171","msg":"trace[1027768158] range","detail":"{range_begin:/registry/masterleases/199.204.45.25; range_end:; response_count:1; response_revision:4993; }","duration":"581.650653ms","start":"2026-02-25T03:19:13.843248Z","end":"2026-02-25T03:19:14.424898Z","steps":["trace[1027768158] 'agreement among raft nodes before linearized reading' (duration: 581.382568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:19:14.424981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:19:13.843224Z","time spent":"581.746544ms","remote":"127.0.0.1:35902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.204.45.25\" "} {"level":"info","ts":"2026-02-25T03:20:20.803821Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3928} {"level":"info","ts":"2026-02-25T03:20:20.83919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3928,"took":"34.501028ms","hash":2252577838,"current-db-size-bytes":16257024,"current-db-size":"16 MB","current-db-size-in-use-bytes":6643712,"current-db-size-in-use":"6.6 MB"} {"level":"info","ts":"2026-02-25T03:20:20.839263Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2252577838,"revision":3928,"compact-revision":2550} {"level":"info","ts":"2026-02-25T03:20:39.573215Z","caller":"traceutil/trace.go:171","msg":"trace[1016109140] transaction","detail":"{read_only:false; response_revision:5613; number_of_response:1; }","duration":"118.721813ms","start":"2026-02-25T03:20:39.454471Z","end":"2026-02-25T03:20:39.573192Z","steps":["trace[1016109140] 'process raft request' (duration: 118.57482ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:20:45.28779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.669026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T03:20:45.287932Z","caller":"traceutil/trace.go:171","msg":"trace[1750075171] linearizableReadLoop","detail":"{readStateIndex:5876; appliedIndex:5874; }","duration":"397.559814ms","start":"2026-02-25T03:20:44.890353Z","end":"2026-02-25T03:20:45.287913Z","steps":["trace[1750075171] 'read index received' (duration: 21.146141ms)","trace[1750075171] 'applied index is now lower than readState.Index' (duration: 376.412233ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:20:45.288023Z","caller":"traceutil/trace.go:171","msg":"trace[960552540] transaction","detail":"{read_only:false; response_revision:5637; number_of_response:1; }","duration":"401.69627ms","start":"2026-02-25T03:20:44.886275Z","end":"2026-02-25T03:20:45.287971Z","steps":["trace[960552540] 'process raft request' (duration: 154.748539ms)","trace[960552540] 'compare' (duration: 246.516534ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:20:45.288168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:20:44.886255Z","time spent":"401.838924ms","remote":"127.0.0.1:36162","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-25T03:20:45.288254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.340916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-25T03:20:45.288294Z","caller":"traceutil/trace.go:171","msg":"trace[420315572] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5637; }","duration":"380.415898ms","start":"2026-02-25T03:20:44.907867Z","end":"2026-02-25T03:20:45.288283Z","steps":["trace[420315572] 'agreement among raft nodes before linearized reading' (duration: 380.329125ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:20:45.288176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.627327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-02-25T03:20:45.288336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:20:44.907849Z","time spent":"380.477529ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-02-25T03:20:45.288357Z","caller":"traceutil/trace.go:171","msg":"trace[1528993447] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5637; }","duration":"396.821221ms","start":"2026-02-25T03:20:44.891521Z","end":"2026-02-25T03:20:45.288342Z","steps":["trace[1528993447] 'agreement among raft nodes before linearized reading' (duration: 396.528875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:20:45.288394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:20:44.89151Z","time spent":"396.873192ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-25T03:20:45.288568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.217292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-02-25T03:20:45.288603Z","caller":"traceutil/trace.go:171","msg":"trace[677587051] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:5637; }","duration":"303.321635ms","start":"2026-02-25T03:20:44.985273Z","end":"2026-02-25T03:20:45.288595Z","steps":["trace[677587051] 'agreement among raft nodes before linearized reading' (duration: 303.200992ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:20:45.288628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:20:44.985239Z","time spent":"303.383305ms","remote":"127.0.0.1:36064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-02-25T03:20:45.28854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.532435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-25T03:20:45.288736Z","caller":"traceutil/trace.go:171","msg":"trace[1989766331] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5637; }","duration":"377.737539ms","start":"2026-02-25T03:20:44.910974Z","end":"2026-02-25T03:20:45.288712Z","steps":["trace[1989766331] 'agreement among raft nodes before linearized reading' (duration: 377.158877ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:20:45.288849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:20:44.910937Z","time spent":"377.894141ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-25T03:20:45.290077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.678294ms","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-25T03:20:45.290202Z","caller":"traceutil/trace.go:171","msg":"trace[811295682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5637; }","duration":"399.845628ms","start":"2026-02-25T03:20:44.890324Z","end":"2026-02-25T03:20:45.29017Z","steps":["trace[811295682] 'agreement among raft nodes before linearized reading' (duration: 397.667577ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:20:45.290275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:20:44.890315Z","time spent":"399.933959ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-25T03:23:11.817318Z","caller":"traceutil/trace.go:171","msg":"trace[1103621367] linearizableReadLoop","detail":"{readStateIndex:6808; appliedIndex:6807; }","duration":"124.962571ms","start":"2026-02-25T03:23:11.692333Z","end":"2026-02-25T03:23:11.817296Z","steps":["trace[1103621367] 'read index received' (duration: 124.757077ms)","trace[1103621367] 'applied index is now lower than readState.Index' (duration: 204.584µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:23:11.817554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.202995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1633"} {"level":"info","ts":"2026-02-25T03:23:11.817597Z","caller":"traceutil/trace.go:171","msg":"trace[1114806910] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:6533; }","duration":"125.283296ms","start":"2026-02-25T03:23:11.6923Z","end":"2026-02-25T03:23:11.817584Z","steps":["trace[1114806910] 'agreement among raft nodes before linearized reading' (duration: 125.091163ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:23:11.817673Z","caller":"traceutil/trace.go:171","msg":"trace[1667387022] transaction","detail":"{read_only:false; response_revision:6533; number_of_response:1; }","duration":"190.414887ms","start":"2026-02-25T03:23:11.627218Z","end":"2026-02-25T03:23:11.817633Z","steps":["trace[1667387022] 'process raft request' (duration: 189.934568ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:23:18.000368Z","caller":"traceutil/trace.go:171","msg":"trace[39459029] transaction","detail":"{read_only:false; response_revision:6561; number_of_response:1; }","duration":"605.409637ms","start":"2026-02-25T03:23:17.394939Z","end":"2026-02-25T03:23:18.000349Z","steps":["trace[39459029] 'process raft request' (duration: 605.290575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:23:18.000494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:23:17.394904Z","time spent":"605.5273ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T03:23:18.000609Z","caller":"traceutil/trace.go:171","msg":"trace[1554241355] linearizableReadLoop","detail":"{readStateIndex:6837; appliedIndex:6837; }","duration":"462.932114ms","start":"2026-02-25T03:23:17.537666Z","end":"2026-02-25T03:23:18.000598Z","steps":["trace[1554241355] 'read index received' (duration: 462.489026ms)","trace[1554241355] 'applied index is now lower than readState.Index' (duration: 442.268µs)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:23:18.000696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.029015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9hssb\" ","response":"range_response_count:1 size:6388"} {"level":"info","ts":"2026-02-25T03:23:18.000722Z","caller":"traceutil/trace.go:171","msg":"trace[182796015] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9hssb; range_end:; response_count:1; response_revision:6561; }","duration":"463.089558ms","start":"2026-02-25T03:23:17.537626Z","end":"2026-02-25T03:23:18.000716Z","steps":["trace[182796015] 'agreement among raft nodes before linearized reading' (duration: 463.031966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:23:18.000748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:23:17.537606Z","time spent":"463.137378ms","remote":"127.0.0.1:36058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6411,"request content":"key:\"/registry/pods/openstack/keepalived-9hssb\" "} {"level":"warn","ts":"2026-02-25T03:23:18.001104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.310682ms","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-25T03:23:18.001134Z","caller":"traceutil/trace.go:171","msg":"trace[450278845] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6561; }","duration":"160.374464ms","start":"2026-02-25T03:23:17.840753Z","end":"2026-02-25T03:23:18.001127Z","steps":["trace[450278845] 'agreement among raft nodes before linearized reading' (duration: 160.280402ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:31.7248Z","caller":"traceutil/trace.go:171","msg":"trace[1852986033] transaction","detail":"{read_only:false; response_revision:7416; number_of_response:1; }","duration":"105.271159ms","start":"2026-02-25T03:24:31.619493Z","end":"2026-02-25T03:24:31.724764Z","steps":["trace[1852986033] 'process raft request' (duration: 94.363258ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:38.342696Z","caller":"traceutil/trace.go:171","msg":"trace[1835851250] transaction","detail":"{read_only:false; response_revision:7547; number_of_response:1; }","duration":"185.800992ms","start":"2026-02-25T03:24:38.156873Z","end":"2026-02-25T03:24:38.342675Z","steps":["trace[1835851250] 'process raft request' (duration: 185.656269ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:38.709871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.520591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T03:24:38.710101Z","caller":"traceutil/trace.go:171","msg":"trace[774891292] transaction","detail":"{read_only:false; response_revision:7549; number_of_response:1; }","duration":"360.909046ms","start":"2026-02-25T03:24:38.349165Z","end":"2026-02-25T03:24:38.710074Z","steps":["trace[774891292] 'process raft request' (duration: 122.090288ms)","trace[774891292] 'compare' (duration: 238.362789ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:24:38.710213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:38.349159Z","time spent":"361.000957ms","remote":"127.0.0.1:36166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1853,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T03:24:38.710232Z","caller":"traceutil/trace.go:171","msg":"trace[1771698393] transaction","detail":"{read_only:false; response_revision:7550; number_of_response:1; }","duration":"360.377876ms","start":"2026-02-25T03:24:38.34984Z","end":"2026-02-25T03:24:38.710218Z","steps":["trace[1771698393] 'process raft request' (duration: 360.163433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:38.710319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:38.349833Z","time spent":"360.439037ms","remote":"127.0.0.1:36386","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4861,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T03:24:38.712015Z","caller":"traceutil/trace.go:171","msg":"trace[1202321860] transaction","detail":"{read_only:false; response_revision:7551; number_of_response:1; }","duration":"340.967879ms","start":"2026-02-25T03:24:38.371021Z","end":"2026-02-25T03:24:38.711989Z","steps":["trace[1202321860] 'process raft request' (duration: 339.152756ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:38.712063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:38.371003Z","time spent":"341.03836ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T03:24:38.712077Z","caller":"traceutil/trace.go:171","msg":"trace[1187151570] transaction","detail":"{read_only:false; response_revision:7552; number_of_response:1; }","duration":"208.889216ms","start":"2026-02-25T03:24:38.503163Z","end":"2026-02-25T03:24:38.712052Z","steps":["trace[1187151570] 'process raft request' (duration: 208.770574ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:38.712082Z","caller":"traceutil/trace.go:171","msg":"trace[1916375061] linearizableReadLoop","detail":"{readStateIndex:7867; appliedIndex:7864; }","duration":"362.197499ms","start":"2026-02-25T03:24:38.349856Z","end":"2026-02-25T03:24:38.712053Z","steps":["trace[1916375061] 'read index received' (duration: 57.509928ms)","trace[1916375061] 'applied index is now lower than readState.Index' (duration: 304.684201ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:24:38.712192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.334961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/monitoring/loki-memcached-results-cache\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:24:38.71222Z","caller":"traceutil/trace.go:171","msg":"trace[371344137] range","detail":"{range_begin:/registry/poddisruptionbudgets/monitoring/loki-memcached-results-cache; range_end:; response_count:0; response_revision:7552; }","duration":"362.389603ms","start":"2026-02-25T03:24:38.349823Z","end":"2026-02-25T03:24:38.712212Z","steps":["trace[371344137] 'agreement among raft nodes before linearized reading' (duration: 362.347882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:38.712237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:38.349809Z","time spent":"362.424533ms","remote":"127.0.0.1:36224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/poddisruptionbudgets/monitoring/loki-memcached-results-cache\" "} {"level":"warn","ts":"2026-02-25T03:24:38.725805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.821963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-25T03:24:38.725857Z","caller":"traceutil/trace.go:171","msg":"trace[39755882] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7553; }","duration":"190.905315ms","start":"2026-02-25T03:24:38.534939Z","end":"2026-02-25T03:24:38.725844Z","steps":["trace[39755882] 'agreement among raft nodes before linearized reading' (duration: 190.729872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:38.725926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.535192ms","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-25T03:24:38.726014Z","caller":"traceutil/trace.go:171","msg":"trace[1865749794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7553; }","duration":"276.709066ms","start":"2026-02-25T03:24:38.449287Z","end":"2026-02-25T03:24:38.725996Z","steps":["trace[1865749794] 'agreement among raft nodes before linearized reading' (duration: 276.450321ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:38.726038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.966021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9hssb\" ","response":"range_response_count:1 size:6388"} {"level":"info","ts":"2026-02-25T03:24:38.726069Z","caller":"traceutil/trace.go:171","msg":"trace[1572884310] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9hssb; range_end:; response_count:1; response_revision:7553; }","duration":"333.049243ms","start":"2026-02-25T03:24:38.39301Z","end":"2026-02-25T03:24:38.726059Z","steps":["trace[1572884310] 'agreement among raft nodes before linearized reading' (duration: 332.990292ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:38.726105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:38.392993Z","time spent":"333.104074ms","remote":"127.0.0.1:36058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6411,"request content":"key:\"/registry/pods/openstack/keepalived-9hssb\" "} {"level":"warn","ts":"2026-02-25T03:24:38.726174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.292187ms","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-25T03:24:38.726203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.178069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-02-25T03:24:38.725796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.777153ms","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-25T03:24:38.726229Z","caller":"traceutil/trace.go:171","msg":"trace[1579341686] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7553; }","duration":"191.218351ms","start":"2026-02-25T03:24:38.535004Z","end":"2026-02-25T03:24:38.726223Z","steps":["trace[1579341686] 'agreement among raft nodes before linearized reading' (duration: 191.14763ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:38.726234Z","caller":"traceutil/trace.go:171","msg":"trace[2044132080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7553; }","duration":"200.364299ms","start":"2026-02-25T03:24:38.525857Z","end":"2026-02-25T03:24:38.726221Z","steps":["trace[2044132080] 'agreement among raft nodes before linearized reading' (duration: 200.129265ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:38.72625Z","caller":"traceutil/trace.go:171","msg":"trace[135691768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7553; }","duration":"191.242001ms","start":"2026-02-25T03:24:38.534998Z","end":"2026-02-25T03:24:38.72624Z","steps":["trace[135691768] 'agreement among raft nodes before linearized reading' (duration: 190.669281ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:38.726223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.382727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-25T03:24:38.726316Z","caller":"traceutil/trace.go:171","msg":"trace[776916326] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7553; }","duration":"183.494048ms","start":"2026-02-25T03:24:38.542816Z","end":"2026-02-25T03:24:38.72631Z","steps":["trace[776916326] 'agreement among raft nodes before linearized reading' (duration: 183.334195ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:44.8787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.209668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12794"} {"level":"warn","ts":"2026-02-25T03:24:44.878691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.254772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-747467bd7-28cw8.18975f692b4cd640\" ","response":"range_response_count:1 size:895"} {"level":"info","ts":"2026-02-25T03:24:44.878772Z","caller":"traceutil/trace.go:171","msg":"trace[357063373] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:7757; }","duration":"173.331301ms","start":"2026-02-25T03:24:44.705426Z","end":"2026-02-25T03:24:44.878757Z","steps":["trace[357063373] 'range keys from in-memory index tree' (duration: 173.059856ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:44.878796Z","caller":"traceutil/trace.go:171","msg":"trace[423175545] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-747467bd7-28cw8.18975f692b4cd640; range_end:; response_count:1; response_revision:7757; }","duration":"172.425764ms","start":"2026-02-25T03:24:44.706349Z","end":"2026-02-25T03:24:44.878775Z","steps":["trace[423175545] 'range keys from in-memory index tree' (duration: 172.115089ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:44.893162Z","caller":"traceutil/trace.go:171","msg":"trace[1630032942] transaction","detail":"{read_only:false; response_revision:7758; number_of_response:1; }","duration":"163.607013ms","start":"2026-02-25T03:24:44.729535Z","end":"2026-02-25T03:24:44.893142Z","steps":["trace[1630032942] 'process raft request' (duration: 163.491351ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:44.953164Z","caller":"traceutil/trace.go:171","msg":"trace[1355892791] linearizableReadLoop","detail":"{readStateIndex:8082; appliedIndex:8080; }","duration":"197.408744ms","start":"2026-02-25T03:24:44.755723Z","end":"2026-02-25T03:24:44.953132Z","steps":["trace[1355892791] 'read index received' (duration: 137.381639ms)","trace[1355892791] 'applied index is now lower than readState.Index' (duration: 60.025535ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:24:44.953796Z","caller":"traceutil/trace.go:171","msg":"trace[1542932244] transaction","detail":"{read_only:false; response_revision:7759; number_of_response:1; }","duration":"219.98454ms","start":"2026-02-25T03:24:44.73377Z","end":"2026-02-25T03:24:44.953755Z","steps":["trace[1542932244] 'process raft request' (duration: 219.002352ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:44.953892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.146558ms","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-25T03:24:44.953965Z","caller":"traceutil/trace.go:171","msg":"trace[1750038057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7761; }","duration":"198.22459ms","start":"2026-02-25T03:24:44.755719Z","end":"2026-02-25T03:24:44.953944Z","steps":["trace[1750038057] 'agreement among raft nodes before linearized reading' (duration: 198.041246ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:44.954276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.390252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-02-25T03:24:44.954334Z","caller":"traceutil/trace.go:171","msg":"trace[2133057338] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:7761; }","duration":"198.461933ms","start":"2026-02-25T03:24:44.755857Z","end":"2026-02-25T03:24:44.954319Z","steps":["trace[2133057338] 'agreement among raft nodes before linearized reading' (duration: 198.2742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:44.954619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.872953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9hssb\" ","response":"range_response_count:1 size:6388"} {"level":"info","ts":"2026-02-25T03:24:44.954663Z","caller":"traceutil/trace.go:171","msg":"trace[423827484] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9hssb; range_end:; response_count:1; response_revision:7761; }","duration":"178.944825ms","start":"2026-02-25T03:24:44.775708Z","end":"2026-02-25T03:24:44.954653Z","steps":["trace[423827484] 'agreement among raft nodes before linearized reading' (duration: 178.849894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:44.955224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.975255ms","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-25T03:24:44.955276Z","caller":"traceutil/trace.go:171","msg":"trace[1095836983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7761; }","duration":"198.058446ms","start":"2026-02-25T03:24:44.757204Z","end":"2026-02-25T03:24:44.955263Z","steps":["trace[1095836983] 'agreement among raft nodes before linearized reading' (duration: 197.849562ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:44.955357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.043714ms","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-25T03:24:44.955412Z","caller":"traceutil/trace.go:171","msg":"trace[1630880086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7761; }","duration":"199.114726ms","start":"2026-02-25T03:24:44.756284Z","end":"2026-02-25T03:24:44.955399Z","steps":["trace[1630880086] 'agreement among raft nodes before linearized reading' (duration: 198.991824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:44.955488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.141538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-25T03:24:44.95553Z","caller":"traceutil/trace.go:171","msg":"trace[2078056620] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7761; }","duration":"198.22073ms","start":"2026-02-25T03:24:44.757301Z","end":"2026-02-25T03:24:44.955521Z","steps":["trace[2078056620] 'agreement among raft nodes before linearized reading' (duration: 198.114848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:44.955558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.931955ms","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-25T03:24:44.955586Z","caller":"traceutil/trace.go:171","msg":"trace[398249245] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7761; }","duration":"190.982536ms","start":"2026-02-25T03:24:44.764596Z","end":"2026-02-25T03:24:44.955578Z","steps":["trace[398249245] 'agreement among raft nodes before linearized reading' (duration: 190.903924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:44.955736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.63521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-02-25T03:24:44.955829Z","caller":"traceutil/trace.go:171","msg":"trace[1415550070] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7761; }","duration":"196.883275ms","start":"2026-02-25T03:24:44.758922Z","end":"2026-02-25T03:24:44.955805Z","steps":["trace[1415550070] 'agreement among raft nodes before linearized reading' (duration: 196.425966ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:45.09427Z","caller":"traceutil/trace.go:171","msg":"trace[962871908] transaction","detail":"{read_only:false; response_revision:7763; number_of_response:1; }","duration":"134.426045ms","start":"2026-02-25T03:24:44.95982Z","end":"2026-02-25T03:24:45.094246Z","steps":["trace[962871908] 'process raft request' (duration: 84.290292ms)","trace[962871908] 'compare' (duration: 49.754086ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:24:45.094481Z","caller":"traceutil/trace.go:171","msg":"trace[692776945] transaction","detail":"{read_only:false; response_revision:7768; number_of_response:1; }","duration":"130.506963ms","start":"2026-02-25T03:24:44.963944Z","end":"2026-02-25T03:24:45.094451Z","steps":["trace[692776945] 'process raft request' (duration: 130.36801ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:45.094551Z","caller":"traceutil/trace.go:171","msg":"trace[1492398574] transaction","detail":"{read_only:false; response_revision:7770; number_of_response:1; }","duration":"130.498813ms","start":"2026-02-25T03:24:44.964026Z","end":"2026-02-25T03:24:45.094525Z","steps":["trace[1492398574] 'process raft request' (duration: 130.404141ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:45.094582Z","caller":"traceutil/trace.go:171","msg":"trace[1237960650] transaction","detail":"{read_only:false; response_revision:7769; number_of_response:1; }","duration":"130.494233ms","start":"2026-02-25T03:24:44.964029Z","end":"2026-02-25T03:24:45.094523Z","steps":["trace[1237960650] 'process raft request' (duration: 130.31903ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:45.094727Z","caller":"traceutil/trace.go:171","msg":"trace[542578763] transaction","detail":"{read_only:false; response_revision:7767; number_of_response:1; }","duration":"130.799059ms","start":"2026-02-25T03:24:44.963887Z","end":"2026-02-25T03:24:45.094686Z","steps":["trace[542578763] 'process raft request' (duration: 130.397531ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:45.094742Z","caller":"traceutil/trace.go:171","msg":"trace[671191261] transaction","detail":"{read_only:false; response_revision:7771; number_of_response:1; }","duration":"130.610745ms","start":"2026-02-25T03:24:44.964115Z","end":"2026-02-25T03:24:45.094726Z","steps":["trace[671191261] 'process raft request' (duration: 130.35254ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:45.094861Z","caller":"traceutil/trace.go:171","msg":"trace[1863398186] transaction","detail":"{read_only:false; response_revision:7764; number_of_response:1; }","duration":"135.006777ms","start":"2026-02-25T03:24:44.959843Z","end":"2026-02-25T03:24:45.09485Z","steps":["trace[1863398186] 'process raft request' (duration: 134.275713ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:45.094889Z","caller":"traceutil/trace.go:171","msg":"trace[872254060] transaction","detail":"{read_only:false; response_revision:7765; number_of_response:1; }","duration":"134.594038ms","start":"2026-02-25T03:24:44.960282Z","end":"2026-02-25T03:24:45.094876Z","steps":["trace[872254060] 'process raft request' (duration: 133.927915ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:45.095009Z","caller":"traceutil/trace.go:171","msg":"trace[1340601671] transaction","detail":"{read_only:false; response_revision:7772; number_of_response:1; }","duration":"130.84892ms","start":"2026-02-25T03:24:44.96415Z","end":"2026-02-25T03:24:45.094999Z","steps":["trace[1340601671] 'process raft request' (duration: 130.485054ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:45.095005Z","caller":"traceutil/trace.go:171","msg":"trace[1880528427] linearizableReadLoop","detail":"{readStateIndex:8087; appliedIndex:8085; }","duration":"134.921865ms","start":"2026-02-25T03:24:44.960069Z","end":"2026-02-25T03:24:45.094991Z","steps":["trace[1880528427] 'read index received' (duration: 5.027313ms)","trace[1880528427] 'applied index is now lower than readState.Index' (duration: 129.893202ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:24:45.095277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.141149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-6b69821d-67ee-439e-a30a-0d0018ef02ff\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-02-25T03:24:45.095351Z","caller":"traceutil/trace.go:171","msg":"trace[883457984] range","detail":"{range_begin:/registry/persistentvolumes/pvc-6b69821d-67ee-439e-a30a-0d0018ef02ff; range_end:; response_count:1; response_revision:7772; }","duration":"135.24819ms","start":"2026-02-25T03:24:44.960092Z","end":"2026-02-25T03:24:45.09534Z","steps":["trace[883457984] 'agreement among raft nodes before linearized reading' (duration: 135.046127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:45.095285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.19435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:7 size:61843"} {"level":"info","ts":"2026-02-25T03:24:45.095256Z","caller":"traceutil/trace.go:171","msg":"trace[653804505] transaction","detail":"{read_only:false; response_revision:7766; number_of_response:1; }","duration":"134.112798ms","start":"2026-02-25T03:24:44.960323Z","end":"2026-02-25T03:24:45.094436Z","steps":["trace[653804505] 'process raft request' (duration: 133.925705ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:45.095456Z","caller":"traceutil/trace.go:171","msg":"trace[1222552921] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:7; response_revision:7772; }","duration":"135.390193ms","start":"2026-02-25T03:24:44.960053Z","end":"2026-02-25T03:24:45.095443Z","steps":["trace[1222552921] 'agreement among raft nodes before linearized reading' (duration: 135.023687ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:50.808275Z","caller":"traceutil/trace.go:171","msg":"trace[1951165888] transaction","detail":"{read_only:false; response_revision:7890; number_of_response:1; }","duration":"143.837058ms","start":"2026-02-25T03:24:50.664403Z","end":"2026-02-25T03:24:50.80824Z","steps":["trace[1951165888] 'process raft request' (duration: 143.506162ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:59.107508Z","caller":"traceutil/trace.go:171","msg":"trace[1533042966] transaction","detail":"{read_only:false; response_revision:8080; number_of_response:1; }","duration":"148.532804ms","start":"2026-02-25T03:24:58.95895Z","end":"2026-02-25T03:24:59.107482Z","steps":["trace[1533042966] 'process raft request' (duration: 141.750209ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:59.117757Z","caller":"traceutil/trace.go:171","msg":"trace[909295227] transaction","detail":"{read_only:false; response_revision:8081; number_of_response:1; }","duration":"109.068868ms","start":"2026-02-25T03:24:59.008655Z","end":"2026-02-25T03:24:59.117724Z","steps":["trace[909295227] 'process raft request' (duration: 108.910096ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:59.559613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.128798ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-25T03:24:59.559734Z","caller":"traceutil/trace.go:171","msg":"trace[1562788919] linearizableReadLoop","detail":"{readStateIndex:8413; appliedIndex:8412; }","duration":"400.137308ms","start":"2026-02-25T03:24:59.159568Z","end":"2026-02-25T03:24:59.559705Z","steps":["trace[1562788919] 'read index received' (duration: 68.912579ms)","trace[1562788919] 'applied index is now lower than readState.Index' (duration: 331.222869ms)"],"step_count":2} {"level":"warn","ts":"2026-02-25T03:24:59.559903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.318271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c4e07984-e934-4905-87f1-251284bcfe3e\" ","response":"range_response_count:1 size:3601"} {"level":"info","ts":"2026-02-25T03:24:59.559968Z","caller":"traceutil/trace.go:171","msg":"trace[225109280] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c4e07984-e934-4905-87f1-251284bcfe3e; range_end:; response_count:1; response_revision:8081; }","duration":"400.425293ms","start":"2026-02-25T03:24:59.159527Z","end":"2026-02-25T03:24:59.559952Z","steps":["trace[225109280] 'agreement among raft nodes before linearized reading' (duration: 400.23312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:59.560018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:59.159512Z","time spent":"400.483194ms","remote":"127.0.0.1:36058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3624,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c4e07984-e934-4905-87f1-251284bcfe3e\" "} {"level":"warn","ts":"2026-02-25T03:24:59.559986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.709517ms","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-25T03:24:59.560102Z","caller":"traceutil/trace.go:171","msg":"trace[460360847] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:8081; }","duration":"256.87306ms","start":"2026-02-25T03:24:59.303212Z","end":"2026-02-25T03:24:59.560085Z","steps":["trace[460360847] 'agreement among raft nodes before linearized reading' (duration: 256.674596ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:59.560091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.790271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-25T03:24:59.560309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.085707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9hssb\" ","response":"range_response_count:1 size:6388"} {"level":"info","ts":"2026-02-25T03:24:59.560394Z","caller":"traceutil/trace.go:171","msg":"trace[1957956141] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9hssb; range_end:; response_count:1; response_revision:8081; }","duration":"387.207799ms","start":"2026-02-25T03:24:59.173167Z","end":"2026-02-25T03:24:59.560374Z","steps":["trace[1957956141] 'agreement among raft nodes before linearized reading' (duration: 387.018196ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:59.560446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:59.173155Z","time spent":"387.282101ms","remote":"127.0.0.1:36058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6411,"request content":"key:\"/registry/pods/openstack/keepalived-9hssb\" "} {"level":"warn","ts":"2026-02-25T03:24:59.560462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.744266ms","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-25T03:24:59.560464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.828236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-25T03:24:59.5605Z","caller":"traceutil/trace.go:171","msg":"trace[47369618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8081; }","duration":"383.791047ms","start":"2026-02-25T03:24:59.176699Z","end":"2026-02-25T03:24:59.56049Z","steps":["trace[47369618] 'agreement among raft nodes before linearized reading' (duration: 383.641684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:59.560493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.104504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-02-25T03:24:59.560433Z","caller":"traceutil/trace.go:171","msg":"trace[2101342317] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8081; }","duration":"381.104138ms","start":"2026-02-25T03:24:59.179262Z","end":"2026-02-25T03:24:59.560366Z","steps":["trace[2101342317] 'agreement among raft nodes before linearized reading' (duration: 380.602939ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:59.560495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.359252ms","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-25T03:24:59.560533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:59.176653Z","time spent":"383.870469ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-25T03:24:59.560534Z","caller":"traceutil/trace.go:171","msg":"trace[873237778] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8081; }","duration":"396.188864ms","start":"2026-02-25T03:24:59.164332Z","end":"2026-02-25T03:24:59.560521Z","steps":["trace[873237778] 'agreement among raft nodes before linearized reading' (duration: 395.482412ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:59.560559Z","caller":"traceutil/trace.go:171","msg":"trace[658275957] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8081; }","duration":"387.505565ms","start":"2026-02-25T03:24:59.173039Z","end":"2026-02-25T03:24:59.560544Z","steps":["trace[658275957] 'agreement among raft nodes before linearized reading' (duration: 387.194479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:59.560569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:59.164307Z","time spent":"396.252246ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-02-25T03:24:59.560601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:59.173026Z","time spent":"387.567626ms","remote":"127.0.0.1:36162","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-25T03:24:59.560507Z","caller":"traceutil/trace.go:171","msg":"trace[1284216262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8081; }","duration":"390.921068ms","start":"2026-02-25T03:24:59.169575Z","end":"2026-02-25T03:24:59.560496Z","steps":["trace[1284216262] 'agreement among raft nodes before linearized reading' (duration: 390.725505ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:59.560619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:59.179256Z","time spent":"381.302611ms","remote":"127.0.0.1:36162","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-25T03:24:59.560656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:59.169554Z","time spent":"391.091291ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-25T03:24:59.560469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.607191ms","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-25T03:24:59.560716Z","caller":"traceutil/trace.go:171","msg":"trace[680220854] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8081; }","duration":"378.853727ms","start":"2026-02-25T03:24:59.181852Z","end":"2026-02-25T03:24:59.560706Z","steps":["trace[680220854] 'agreement among raft nodes before linearized reading' (duration: 378.284646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:59.560753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:59.181807Z","time spent":"378.934047ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-25T03:24:59.926736Z","caller":"traceutil/trace.go:171","msg":"trace[568865807] transaction","detail":"{read_only:false; response_revision:8083; number_of_response:1; }","duration":"362.064247ms","start":"2026-02-25T03:24:59.564645Z","end":"2026-02-25T03:24:59.926709Z","steps":["trace[568865807] 'process raft request' (duration: 352.051403ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:59.926904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:59.564628Z","time spent":"362.191309ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-25T03:24:59.92703Z","caller":"traceutil/trace.go:171","msg":"trace[540950442] transaction","detail":"{read_only:false; response_revision:8084; number_of_response:1; }","duration":"362.315432ms","start":"2026-02-25T03:24:59.564686Z","end":"2026-02-25T03:24:59.927002Z","steps":["trace[540950442] 'process raft request' (duration: 361.929695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:59.927255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:59.56466Z","time spent":"362.470794ms","remote":"127.0.0.1:36162","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-25T03:24:59.927323Z","caller":"traceutil/trace.go:171","msg":"trace[1664434686] transaction","detail":"{read_only:false; response_revision:8086; number_of_response:1; }","duration":"362.209539ms","start":"2026-02-25T03:24:59.56509Z","end":"2026-02-25T03:24:59.927299Z","steps":["trace[1664434686] 'process raft request' (duration: 362.087497ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:59.927387Z","caller":"traceutil/trace.go:171","msg":"trace[319814891] linearizableReadLoop","detail":"{readStateIndex:8419; appliedIndex:8413; }","duration":"361.526636ms","start":"2026-02-25T03:24:59.565846Z","end":"2026-02-25T03:24:59.927373Z","steps":["trace[319814891] 'read index received' (duration: 82.114252ms)","trace[319814891] 'applied index is now lower than readState.Index' (duration: 279.411354ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:24:59.927418Z","caller":"traceutil/trace.go:171","msg":"trace[298633840] transaction","detail":"{read_only:false; response_revision:8087; number_of_response:1; }","duration":"362.047616ms","start":"2026-02-25T03:24:59.565354Z","end":"2026-02-25T03:24:59.927402Z","steps":["trace[298633840] 'process raft request' (duration: 361.907603ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:59.927306Z","caller":"traceutil/trace.go:171","msg":"trace[1347661161] transaction","detail":"{read_only:false; response_revision:8085; number_of_response:1; }","duration":"362.474474ms","start":"2026-02-25T03:24:59.564792Z","end":"2026-02-25T03:24:59.927266Z","steps":["trace[1347661161] 'process raft request' (duration: 362.080087ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:59.927471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:59.565065Z","time spent":"362.331061ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:24:59.927576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:59.56457Z","time spent":"362.924182ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:24:59.927589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:59.565338Z","time spent":"362.140627ms","remote":"127.0.0.1:36162","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-25T03:24:59.927649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.221595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18975f6c877b5199\" ","response":"range_response_count:1 size:1012"} {"level":"warn","ts":"2026-02-25T03:24:59.927643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.321182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-gateway-6b458d8465-bkf4l\" ","response":"range_response_count:1 size:4951"} {"level":"warn","ts":"2026-02-25T03:24:59.927681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.825022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:78886"} {"level":"info","ts":"2026-02-25T03:24:59.927708Z","caller":"traceutil/trace.go:171","msg":"trace[2112719045] range","detail":"{range_begin:/registry/pods/monitoring/loki-gateway-6b458d8465-bkf4l; range_end:; response_count:1; response_revision:8087; }","duration":"317.439685ms","start":"2026-02-25T03:24:59.610252Z","end":"2026-02-25T03:24:59.927692Z","steps":["trace[2112719045] 'agreement among raft nodes before linearized reading' (duration: 317.279791ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:24:59.927721Z","caller":"traceutil/trace.go:171","msg":"trace[1335790684] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18975f6c877b5199; range_end:; response_count:1; response_revision:8087; }","duration":"296.294536ms","start":"2026-02-25T03:24:59.631385Z","end":"2026-02-25T03:24:59.927679Z","steps":["trace[1335790684] 'agreement among raft nodes before linearized reading' (duration: 296.151873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:59.927749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:59.610233Z","time spent":"317.508377ms","remote":"127.0.0.1:36058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":4974,"request content":"key:\"/registry/pods/monitoring/loki-gateway-6b458d8465-bkf4l\" "} {"level":"warn","ts":"2026-02-25T03:24:59.927766Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.1692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/loki-gateway-6b458d8465-bkf4l.18975f6840d5e750\" ","response":"range_response_count:1 size:788"} {"level":"info","ts":"2026-02-25T03:24:59.927814Z","caller":"traceutil/trace.go:171","msg":"trace[560495751] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:8087; }","duration":"361.882653ms","start":"2026-02-25T03:24:59.56584Z","end":"2026-02-25T03:24:59.927723Z","steps":["trace[560495751] 'agreement among raft nodes before linearized reading' (duration: 361.619638ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:59.927862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:59.565811Z","time spent":"362.039477ms","remote":"127.0.0.1:36058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":9,"response size":78909,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-02-25T03:24:59.927866Z","caller":"traceutil/trace.go:171","msg":"trace[579683825] range","detail":"{range_begin:/registry/events/monitoring/loki-gateway-6b458d8465-bkf4l.18975f6840d5e750; range_end:; response_count:1; response_revision:8087; }","duration":"317.315612ms","start":"2026-02-25T03:24:59.610527Z","end":"2026-02-25T03:24:59.927843Z","steps":["trace[579683825] 'agreement among raft nodes before linearized reading' (duration: 317.032457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:24:59.927914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:24:59.61051Z","time spent":"317.393525ms","remote":"127.0.0.1:35968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":1,"response size":811,"request content":"key:\"/registry/events/monitoring/loki-gateway-6b458d8465-bkf4l.18975f6840d5e750\" "} {"level":"info","ts":"2026-02-25T03:25:02.04905Z","caller":"traceutil/trace.go:171","msg":"trace[2104948226] transaction","detail":"{read_only:false; response_revision:8125; number_of_response:1; }","duration":"110.768059ms","start":"2026-02-25T03:25:01.938257Z","end":"2026-02-25T03:25:02.049025Z","steps":["trace[2104948226] 'process raft request' (duration: 110.675548ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:25:02.049084Z","caller":"traceutil/trace.go:171","msg":"trace[1312017237] transaction","detail":"{read_only:false; response_revision:8123; number_of_response:1; }","duration":"113.203675ms","start":"2026-02-25T03:25:01.93584Z","end":"2026-02-25T03:25:02.049043Z","steps":["trace[1312017237] 'process raft request' (duration: 107.617052ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:25:02.049076Z","caller":"traceutil/trace.go:171","msg":"trace[1685008103] transaction","detail":"{read_only:false; response_revision:8124; number_of_response:1; }","duration":"113.117493ms","start":"2026-02-25T03:25:01.935927Z","end":"2026-02-25T03:25:02.049045Z","steps":["trace[1685008103] 'process raft request' (duration: 112.9698ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:25:02.049452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.651918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:79087"} {"level":"info","ts":"2026-02-25T03:25:02.0495Z","caller":"traceutil/trace.go:171","msg":"trace[494326435] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:8127; }","duration":"110.726529ms","start":"2026-02-25T03:25:01.938767Z","end":"2026-02-25T03:25:02.049493Z","steps":["trace[494326435] 'agreement among raft nodes before linearized reading' (duration: 110.499235ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:25:02.049246Z","caller":"traceutil/trace.go:171","msg":"trace[287814950] linearizableReadLoop","detail":"{readStateIndex:8457; appliedIndex:8453; }","duration":"110.447734ms","start":"2026-02-25T03:25:01.938786Z","end":"2026-02-25T03:25:02.049234Z","steps":["trace[287814950] 'read index received' (duration: 5.462401ms)","trace[287814950] 'applied index is now lower than readState.Index' (duration: 104.984153ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:25:02.049261Z","caller":"traceutil/trace.go:171","msg":"trace[1357435393] transaction","detail":"{read_only:false; response_revision:8127; number_of_response:1; }","duration":"101.405657ms","start":"2026-02-25T03:25:01.947842Z","end":"2026-02-25T03:25:02.049248Z","steps":["trace[1357435393] 'process raft request' (duration: 101.340396ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:25:02.049273Z","caller":"traceutil/trace.go:171","msg":"trace[970391312] transaction","detail":"{read_only:false; response_revision:8126; number_of_response:1; }","duration":"109.15602ms","start":"2026-02-25T03:25:01.940077Z","end":"2026-02-25T03:25:02.049233Z","steps":["trace[970391312] 'process raft request' (duration: 108.917396ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:25:20.859519Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5515} {"level":"info","ts":"2026-02-25T03:25:21.235479Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5515,"took":"374.446347ms","hash":4025721738,"current-db-size-bytes":29126656,"current-db-size":"29 MB","current-db-size-in-use-bytes":28229632,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-02-25T03:25:21.235555Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4025721738,"revision":5515,"compact-revision":3928} {"level":"info","ts":"2026-02-25T03:25:22.41316Z","caller":"traceutil/trace.go:171","msg":"trace[304479038] transaction","detail":"{read_only:false; response_revision:8299; number_of_response:1; }","duration":"114.055963ms","start":"2026-02-25T03:25:22.29908Z","end":"2026-02-25T03:25:22.413136Z","steps":["trace[304479038] 'process raft request' (duration: 113.664946ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:27:29.963325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.114938ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-25T03:27:29.963552Z","caller":"traceutil/trace.go:171","msg":"trace[1891567900] transaction","detail":"{read_only:false; response_revision:9029; number_of_response:1; }","duration":"200.597623ms","start":"2026-02-25T03:27:29.762926Z","end":"2026-02-25T03:27:29.963523Z","steps":["trace[1891567900] 'process raft request' (duration: 67.18546ms)","trace[1891567900] 'compare' (duration: 132.984006ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:27:29.982455Z","caller":"traceutil/trace.go:171","msg":"trace[1019967259] linearizableReadLoop","detail":"{readStateIndex:9396; appliedIndex:9395; }","duration":"114.599135ms","start":"2026-02-25T03:27:29.867835Z","end":"2026-02-25T03:27:29.982434Z","steps":["trace[1019967259] 'read index received' (duration: 114.445852ms)","trace[1019967259] 'applied index is now lower than readState.Index' (duration: 152.153µs)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:27:29.982512Z","caller":"traceutil/trace.go:171","msg":"trace[1966012443] transaction","detail":"{read_only:false; response_revision:9030; number_of_response:1; }","duration":"208.897637ms","start":"2026-02-25T03:27:29.773587Z","end":"2026-02-25T03:27:29.982485Z","steps":["trace[1966012443] 'process raft request' (duration: 208.741304ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:27:29.982595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.741698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterbackups/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterbackups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:27:29.982654Z","caller":"traceutil/trace.go:171","msg":"trace[2092671624] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterbackups/; range_end:/registry/pxc.percona.com/perconaxtradbclusterbackups0; response_count:0; response_revision:9030; }","duration":"114.86442ms","start":"2026-02-25T03:27:29.867774Z","end":"2026-02-25T03:27:29.982638Z","steps":["trace[2092671624] 'agreement among raft nodes before linearized reading' (duration: 114.752427ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:27:31.520898Z","caller":"traceutil/trace.go:171","msg":"trace[2095878820] linearizableReadLoop","detail":"{readStateIndex:9402; appliedIndex:9399; }","duration":"318.783825ms","start":"2026-02-25T03:27:31.202088Z","end":"2026-02-25T03:27:31.520872Z","steps":["trace[2095878820] 'read index received' (duration: 62.443452ms)","trace[2095878820] 'applied index is now lower than readState.Index' (duration: 256.339112ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:27:31.520978Z","caller":"traceutil/trace.go:171","msg":"trace[1901200866] transaction","detail":"{read_only:false; response_revision:9035; number_of_response:1; }","duration":"319.780274ms","start":"2026-02-25T03:27:31.201162Z","end":"2026-02-25T03:27:31.520942Z","steps":["trace[1901200866] 'process raft request' (duration: 319.524049ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:27:31.521029Z","caller":"traceutil/trace.go:171","msg":"trace[2142075260] transaction","detail":"{read_only:false; response_revision:9036; number_of_response:1; }","duration":"318.948138ms","start":"2026-02-25T03:27:31.202057Z","end":"2026-02-25T03:27:31.521005Z","steps":["trace[2142075260] 'process raft request' (duration: 318.779315ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:27:31.521158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:27:31.202045Z","time spent":"319.03519ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:27:31.5212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:27:31.201136Z","time spent":"319.926886ms","remote":"127.0.0.1:36162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-25T03:27:31.521208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.11354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:9 size:80429"} {"level":"info","ts":"2026-02-25T03:27:31.521246Z","caller":"traceutil/trace.go:171","msg":"trace[1625776613] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:9; response_revision:9036; }","duration":"319.174382ms","start":"2026-02-25T03:27:31.202059Z","end":"2026-02-25T03:27:31.521234Z","steps":["trace[1625776613] 'agreement among raft nodes before linearized reading' (duration: 318.947568ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:27:31.521265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.861291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12014"} {"level":"warn","ts":"2026-02-25T03:27:31.521276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-25T03:27:31.202048Z","time spent":"319.219044ms","remote":"127.0.0.1:36058","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":9,"response size":80452,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-02-25T03:27:31.521303Z","caller":"traceutil/trace.go:171","msg":"trace[1042775134] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9036; }","duration":"172.960374ms","start":"2026-02-25T03:27:31.348331Z","end":"2026-02-25T03:27:31.521292Z","steps":["trace[1042775134] 'agreement among raft nodes before linearized reading' (duration: 172.851931ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:28:12.253822Z","caller":"traceutil/trace.go:171","msg":"trace[332859688] transaction","detail":"{read_only:false; response_revision:9227; number_of_response:1; }","duration":"112.033768ms","start":"2026-02-25T03:28:12.141747Z","end":"2026-02-25T03:28:12.253781Z","steps":["trace[332859688] 'process raft request' (duration: 47.522387ms)","trace[332859688] 'compare' (duration: 64.320858ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:28:13.957567Z","caller":"traceutil/trace.go:171","msg":"trace[1863721882] linearizableReadLoop","detail":"{readStateIndex:9608; appliedIndex:9606; }","duration":"147.993321ms","start":"2026-02-25T03:28:13.809539Z","end":"2026-02-25T03:28:13.957533Z","steps":["trace[1863721882] 'read index received' (duration: 63.820257ms)","trace[1863721882] 'applied index is now lower than readState.Index' (duration: 84.171084ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:28:13.957665Z","caller":"traceutil/trace.go:171","msg":"trace[937414265] transaction","detail":"{read_only:false; response_revision:9234; number_of_response:1; }","duration":"149.104573ms","start":"2026-02-25T03:28:13.808505Z","end":"2026-02-25T03:28:13.95761Z","steps":["trace[937414265] 'process raft request' (duration: 148.841038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:28:13.957985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.416821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-9hssb\" ","response":"range_response_count:1 size:6388"} {"level":"info","ts":"2026-02-25T03:28:13.95818Z","caller":"traceutil/trace.go:171","msg":"trace[509021943] range","detail":"{range_begin:/registry/pods/openstack/keepalived-9hssb; range_end:; response_count:1; response_revision:9234; }","duration":"148.544293ms","start":"2026-02-25T03:28:13.809508Z","end":"2026-02-25T03:28:13.958052Z","steps":["trace[509021943] 'agreement among raft nodes before linearized reading' (duration: 148.205986ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:28:15.393511Z","caller":"traceutil/trace.go:171","msg":"trace[511994946] transaction","detail":"{read_only:false; response_revision:9241; number_of_response:1; }","duration":"135.857008ms","start":"2026-02-25T03:28:15.257613Z","end":"2026-02-25T03:28:15.39347Z","steps":["trace[511994946] 'process raft request' (duration: 103.608853ms)","trace[511994946] 'compare' (duration: 32.068182ms)"],"step_count":2} {"level":"info","ts":"2026-02-25T03:28:16.313576Z","caller":"traceutil/trace.go:171","msg":"trace[22288520] transaction","detail":"{read_only:false; response_revision:9246; number_of_response:1; }","duration":"122.772297ms","start":"2026-02-25T03:28:16.190778Z","end":"2026-02-25T03:28:16.31355Z","steps":["trace[22288520] 'process raft request' (duration: 122.607844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-25T03:28:23.225763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.05983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:28:23.225903Z","caller":"traceutil/trace.go:171","msg":"trace[977368479] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9278; }","duration":"144.278234ms","start":"2026-02-25T03:28:23.081593Z","end":"2026-02-25T03:28:23.225872Z","steps":["trace[977368479] 'range keys from in-memory index tree' (duration: 143.943438ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:29:34.629732Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"ba52c55cf4193752","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-02-25T03:29:34.635673Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-02-25T03:29:34.635751Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-02-25T03:30:20.866207Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8292} {"level":"info","ts":"2026-02-25T03:30:20.952969Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8292,"took":"85.026358ms","hash":1101133704,"current-db-size-bytes":33017856,"current-db-size":"33 MB","current-db-size-in-use-bytes":16420864,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-02-25T03:30:20.953104Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1101133704,"revision":8292,"compact-revision":5515} {"level":"warn","ts":"2026-02-25T03:32:29.514956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.239012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-25T03:32:29.515079Z","caller":"traceutil/trace.go:171","msg":"trace[287047791] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:10431; }","duration":"115.413156ms","start":"2026-02-25T03:32:29.399645Z","end":"2026-02-25T03:32:29.515058Z","steps":["trace[287047791] 'count revisions from in-memory index tree' (duration: 115.172011ms)"],"step_count":1} {"level":"info","ts":"2026-02-25T03:35:20.871893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9830} {"level":"info","ts":"2026-02-25T03:35:20.925515Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9830,"took":"52.192691ms","hash":693080013,"current-db-size-bytes":33017856,"current-db-size":"33 MB","current-db-size-in-use-bytes":12894208,"current-db-size-in-use":"13 MB"} {"level":"info","ts":"2026-02-25T03:35:20.925593Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":693080013,"revision":9830,"compact-revision":8292}