{"level":"warn","ts":"2026-04-14T05:42:46.57949Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-14T05:42:46.579602Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.53: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.53:2380","--initial-cluster=instance=https://199.204.45.53:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.53:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.53: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-04-14T05:42:46.579681Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-14T05:42:46.579698Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-14T05:42:46.579716Z","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-04-14T05:42:46.580442Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"]} {"level":"info","ts":"2026-04-14T05:42:46.580596Z","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.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.53: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-04-14T05:42:46.588541Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.644976ms"} {"level":"info","ts":"2026-04-14T05:42:46.603739Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c47ca1a7b1fe506b","cluster-id":"95bd21e158fabf07"} {"level":"info","ts":"2026-04-14T05:42:46.603848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=()"} {"level":"info","ts":"2026-04-14T05:42:46.603928Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 0"} {"level":"info","ts":"2026-04-14T05:42:46.603964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c47ca1a7b1fe506b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-14T05:42:46.604011Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became follower at term 1"} {"level":"info","ts":"2026-04-14T05:42:46.604139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"warn","ts":"2026-04-14T05:42:46.610453Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-14T05:42:46.612223Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-14T05:42:46.613753Z","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-04-14T05:42:46.615872Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c47ca1a7b1fe506b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-14T05:42:46.615992Z","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-04-14T05:42:46.616098Z","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-04-14T05:42:46.616137Z","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-04-14T05:42:46.616065Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c47ca1a7b1fe506b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-14T05:42:46.616924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b switched to configuration voters=(14158369070163841131)"} {"level":"info","ts":"2026-04-14T05:42:46.617086Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","added-peer-id":"c47ca1a7b1fe506b","added-peer-peer-urls":["https://199.204.45.53:2380"]} {"level":"info","ts":"2026-04-14T05:42:46.618682Z","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-04-14T05:42:46.618889Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c47ca1a7b1fe506b","initial-advertise-peer-urls":["https://199.204.45.53:2380"],"listen-peer-urls":["https://199.204.45.53:2380"],"advertise-client-urls":["https://199.204.45.53:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.53:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-14T05:42:46.618933Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-14T05:42:46.619078Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-14T05:42:46.619094Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.53:2380"} {"level":"info","ts":"2026-04-14T05:42:46.805045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b is starting a new election at term 1"} {"level":"info","ts":"2026-04-14T05:42:46.805097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became pre-candidate at term 1"} {"level":"info","ts":"2026-04-14T05:42:46.805125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgPreVoteResp from c47ca1a7b1fe506b at term 1"} {"level":"info","ts":"2026-04-14T05:42:46.805207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became candidate at term 2"} {"level":"info","ts":"2026-04-14T05:42:46.805227Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b received MsgVoteResp from c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-14T05:42:46.805242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c47ca1a7b1fe506b became leader at term 2"} {"level":"info","ts":"2026-04-14T05:42:46.805259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c47ca1a7b1fe506b elected leader c47ca1a7b1fe506b at term 2"} {"level":"info","ts":"2026-04-14T05:42:46.903358Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T05:42:46.906579Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c47ca1a7b1fe506b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.53:2379]}","request-path":"/0/members/c47ca1a7b1fe506b/attributes","cluster-id":"95bd21e158fabf07","publish-timeout":"7s"} {"level":"info","ts":"2026-04-14T05:42:46.906602Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T05:42:46.906648Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-14T05:42:46.906998Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-14T05:42:46.907046Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-14T05:42:46.907294Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"95bd21e158fabf07","local-member-id":"c47ca1a7b1fe506b","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T05:42:46.907431Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T05:42:46.907513Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-14T05:42:46.912581Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.53:2379"} {"level":"info","ts":"2026-04-14T05:42:46.913088Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-04-14T05:43:17.638941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.248852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T05:43:17.639056Z","caller":"traceutil/trace.go:171","msg":"trace[171875786] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"294.046814ms","start":"2026-04-14T05:43:17.344993Z","end":"2026-04-14T05:43:17.63904Z","steps":["trace[171875786] 'process raft request' (duration: 123.270651ms)","trace[171875786] 'compare' (duration: 170.10894ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:43:17.693245Z","caller":"traceutil/trace.go:171","msg":"trace[809707907] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"340.719339ms","start":"2026-04-14T05:43:17.352496Z","end":"2026-04-14T05:43:17.693216Z","steps":["trace[809707907] 'process raft request' (duration: 340.456294ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:43:17.693323Z","caller":"traceutil/trace.go:171","msg":"trace[1210411021] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"339.482315ms","start":"2026-04-14T05:43:17.353814Z","end":"2026-04-14T05:43:17.693296Z","steps":["trace[1210411021] 'process raft request' (duration: 339.339192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:43:17.693433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:43:17.353783Z","time spent":"339.595737ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T05:43:17.693467Z","caller":"traceutil/trace.go:171","msg":"trace[589537362] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"337.092766ms","start":"2026-04-14T05:43:17.356328Z","end":"2026-04-14T05:43:17.69342Z","steps":["trace[589537362] 'process raft request' (duration: 336.894472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:43:17.693606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:43:17.356309Z","time spent":"337.226008ms","remote":"127.0.0.1:45910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:43:17.693559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:43:17.352475Z","time spent":"340.837072ms","remote":"127.0.0.1:45910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:43:17.69369Z","caller":"traceutil/trace.go:171","msg":"trace[1339307652] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"335.610944ms","start":"2026-04-14T05:43:17.358026Z","end":"2026-04-14T05:43:17.693637Z","steps":["trace[1339307652] 'process raft request' (duration: 335.251527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:43:17.693951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:43:17.358018Z","time spent":"335.835539ms","remote":"127.0.0.1:44540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T05:43:17.694292Z","caller":"traceutil/trace.go:171","msg":"trace[1490552236] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"335.90091ms","start":"2026-04-14T05:43:17.358367Z","end":"2026-04-14T05:43:17.694268Z","steps":["trace[1490552236] 'process raft request' (duration: 334.946861ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:43:17.69455Z","caller":"traceutil/trace.go:171","msg":"trace[165192269] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"332.46463ms","start":"2026-04-14T05:43:17.362065Z","end":"2026-04-14T05:43:17.69453Z","steps":["trace[165192269] 'process raft request' (duration: 332.349748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:43:17.694655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:43:17.362055Z","time spent":"332.540382ms","remote":"127.0.0.1:45910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:43:17.694667Z","caller":"traceutil/trace.go:171","msg":"trace[1652934324] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"320.119148ms","start":"2026-04-14T05:43:17.374525Z","end":"2026-04-14T05:43:17.694644Z","steps":["trace[1652934324] 'process raft request' (duration: 319.961434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:43:17.694729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:43:17.374497Z","time spent":"320.204569ms","remote":"127.0.0.1:45910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T05:43:17.694785Z","caller":"traceutil/trace.go:171","msg":"trace[801071479] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"333.685824ms","start":"2026-04-14T05:43:17.361052Z","end":"2026-04-14T05:43:17.694738Z","steps":["trace[801071479] 'process raft request' (duration: 333.089842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:43:17.694931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:43:17.361037Z","time spent":"333.799327ms","remote":"127.0.0.1:45910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:43:17.695066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:43:17.358349Z","time spent":"335.989442ms","remote":"127.0.0.1:45910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:43:17.699551Z","caller":"traceutil/trace.go:171","msg":"trace[1487982935] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"208.142517ms","start":"2026-04-14T05:43:17.491379Z","end":"2026-04-14T05:43:17.699521Z","steps":["trace[1487982935] 'process raft request' (duration: 207.81665ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:43:23.267335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.639476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-04-14T05:43:23.267375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.807625ms","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-04-14T05:43:23.26742Z","caller":"traceutil/trace.go:171","msg":"trace[304395656] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"211.648009ms","start":"2026-04-14T05:43:23.05576Z","end":"2026-04-14T05:43:23.267408Z","steps":["trace[304395656] 'read index received' (duration: 93.88669ms)","trace[304395656] 'applied index is now lower than readState.Index' (duration: 117.760469ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:43:23.267443Z","caller":"traceutil/trace.go:171","msg":"trace[832603318] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:566; }","duration":"265.917618ms","start":"2026-04-14T05:43:23.001508Z","end":"2026-04-14T05:43:23.267425Z","steps":["trace[832603318] 'range keys from in-memory index tree' (duration: 265.659152ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:43:23.267487Z","caller":"traceutil/trace.go:171","msg":"trace[2047940469] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"246.552493ms","start":"2026-04-14T05:43:23.020906Z","end":"2026-04-14T05:43:23.267459Z","steps":["trace[2047940469] 'process raft request' (duration: 128.730363ms)","trace[2047940469] 'compare' (duration: 117.524144ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:43:23.267565Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.810141ms","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-04-14T05:43:23.267601Z","caller":"traceutil/trace.go:171","msg":"trace[290119863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:567; }","duration":"211.886063ms","start":"2026-04-14T05:43:23.055707Z","end":"2026-04-14T05:43:23.267593Z","steps":["trace[290119863] 'agreement among raft nodes before linearized reading' (duration: 211.73807ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:43:23.775227Z","caller":"traceutil/trace.go:171","msg":"trace[973471915] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"114.3905ms","start":"2026-04-14T05:43:23.660809Z","end":"2026-04-14T05:43:23.775199Z","steps":["trace[973471915] 'process raft request' (duration: 113.828127ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:43:24.075903Z","caller":"traceutil/trace.go:171","msg":"trace[270716776] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"188.70163ms","start":"2026-04-14T05:43:23.887184Z","end":"2026-04-14T05:43:24.075886Z","steps":["trace[270716776] 'read index received' (duration: 141.677968ms)","trace[270716776] 'applied index is now lower than readState.Index' (duration: 47.022612ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:43:24.07597Z","caller":"traceutil/trace.go:171","msg":"trace[653636583] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"238.326594ms","start":"2026-04-14T05:43:23.837591Z","end":"2026-04-14T05:43:24.075918Z","steps":["trace[653636583] 'process raft request' (duration: 191.268002ms)","trace[653636583] 'compare' (duration: 46.94702ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:43:24.076021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.838362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:43:24.07605Z","caller":"traceutil/trace.go:171","msg":"trace[1458804961] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"188.881043ms","start":"2026-04-14T05:43:23.887161Z","end":"2026-04-14T05:43:24.076042Z","steps":["trace[1458804961] 'agreement among raft nodes before linearized reading' (duration: 188.794271ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:43:25.143635Z","caller":"traceutil/trace.go:171","msg":"trace[562798586] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"733.034472ms","start":"2026-04-14T05:43:24.410577Z","end":"2026-04-14T05:43:25.143611Z","steps":["trace[562798586] 'process raft request' (duration: 732.869269ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:43:25.143796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:43:24.410554Z","time spent":"733.168997ms","remote":"127.0.0.1:46032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:43:25.172698Z","caller":"traceutil/trace.go:171","msg":"trace[418914671] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"285.57444ms","start":"2026-04-14T05:43:24.887102Z","end":"2026-04-14T05:43:25.172677Z","steps":["trace[418914671] 'read index received' (duration: 256.573907ms)","trace[418914671] 'applied index is now lower than readState.Index' (duration: 28.999423ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:43:25.172741Z","caller":"traceutil/trace.go:171","msg":"trace[459893485] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"705.662343ms","start":"2026-04-14T05:43:24.467057Z","end":"2026-04-14T05:43:25.17272Z","steps":["trace[459893485] 'process raft request' (duration: 704.631572ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:43:25.172813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.691724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:43:25.172843Z","caller":"traceutil/trace.go:171","msg":"trace[1450700292] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"285.771465ms","start":"2026-04-14T05:43:24.887062Z","end":"2026-04-14T05:43:25.172834Z","steps":["trace[1450700292] 'agreement among raft nodes before linearized reading' (duration: 285.701593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:43:25.172877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:43:24.467025Z","time spent":"705.769376ms","remote":"127.0.0.1:45956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:43:40.530659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.21559ms","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-04-14T05:43:40.530829Z","caller":"traceutil/trace.go:171","msg":"trace[1669366423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:734; }","duration":"107.437435ms","start":"2026-04-14T05:43:40.423367Z","end":"2026-04-14T05:43:40.530804Z","steps":["trace[1669366423] 'range keys from in-memory index tree' (duration: 106.999426ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:44:07.667028Z","caller":"traceutil/trace.go:171","msg":"trace[76291097] linearizableReadLoop","detail":"{readStateIndex:1162; appliedIndex:1161; }","duration":"100.976192ms","start":"2026-04-14T05:44:07.566022Z","end":"2026-04-14T05:44:07.666998Z","steps":["trace[76291097] 'read index received' (duration: 100.794979ms)","trace[76291097] 'applied index is now lower than readState.Index' (duration: 179.853µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:44:07.667142Z","caller":"traceutil/trace.go:171","msg":"trace[713311770] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"176.734057ms","start":"2026-04-14T05:44:07.490396Z","end":"2026-04-14T05:44:07.66713Z","steps":["trace[713311770] 'process raft request' (duration: 176.436191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:44:07.667367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.33389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T05:44:07.667418Z","caller":"traceutil/trace.go:171","msg":"trace[2106666723] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1128; }","duration":"101.457673ms","start":"2026-04-14T05:44:07.565949Z","end":"2026-04-14T05:44:07.667407Z","steps":["trace[2106666723] 'agreement among raft nodes before linearized reading' (duration: 101.295419ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:44:29.917161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.873647ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T05:44:29.917333Z","caller":"traceutil/trace.go:171","msg":"trace[341322159] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"137.943171ms","start":"2026-04-14T05:44:29.779373Z","end":"2026-04-14T05:44:29.917316Z","steps":["trace[341322159] 'compare' (duration: 132.760775ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:44:29.917542Z","caller":"traceutil/trace.go:171","msg":"trace[107463528] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"135.610384ms","start":"2026-04-14T05:44:29.78189Z","end":"2026-04-14T05:44:29.917501Z","steps":["trace[107463528] 'process raft request' (duration: 135.376939ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:44:29.923402Z","caller":"traceutil/trace.go:171","msg":"trace[662414089] linearizableReadLoop","detail":"{readStateIndex:1441; appliedIndex:1438; }","duration":"128.157561ms","start":"2026-04-14T05:44:29.795226Z","end":"2026-04-14T05:44:29.923384Z","steps":["trace[662414089] 'read index received' (duration: 1.863448ms)","trace[662414089] 'applied index is now lower than readState.Index' (duration: 126.293183ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:44:29.923487Z","caller":"traceutil/trace.go:171","msg":"trace[1510790166] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"137.848681ms","start":"2026-04-14T05:44:29.785602Z","end":"2026-04-14T05:44:29.923451Z","steps":["trace[1510790166] 'process raft request' (duration: 137.693896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:44:29.923571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.340594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T05:44:29.923609Z","caller":"traceutil/trace.go:171","msg":"trace[530074274] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1396; }","duration":"128.414845ms","start":"2026-04-14T05:44:29.795181Z","end":"2026-04-14T05:44:29.923596Z","steps":["trace[530074274] 'agreement among raft nodes before linearized reading' (duration: 128.294593ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:44:54.827123Z","caller":"traceutil/trace.go:171","msg":"trace[2144328870] linearizableReadLoop","detail":"{readStateIndex:1860; appliedIndex:1859; }","duration":"178.575992ms","start":"2026-04-14T05:44:54.648501Z","end":"2026-04-14T05:44:54.827077Z","steps":["trace[2144328870] 'read index received' (duration: 178.047911ms)","trace[2144328870] 'applied index is now lower than readState.Index' (duration: 526.311µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:44:54.827201Z","caller":"traceutil/trace.go:171","msg":"trace[86196554] transaction","detail":"{read_only:false; response_revision:1787; number_of_response:1; }","duration":"271.498028ms","start":"2026-04-14T05:44:54.555652Z","end":"2026-04-14T05:44:54.82715Z","steps":["trace[86196554] 'process raft request' (duration: 271.000398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:44:54.827448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.933099ms","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-04-14T05:44:54.827541Z","caller":"traceutil/trace.go:171","msg":"trace[2100345916] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1787; }","duration":"179.090313ms","start":"2026-04-14T05:44:54.648427Z","end":"2026-04-14T05:44:54.827517Z","steps":["trace[2100345916] 'agreement among raft nodes before linearized reading' (duration: 178.845227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:44:55.252689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.004743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a622ea5b84c83f\" ","response":"range_response_count:1 size:982"} {"level":"info","ts":"2026-04-14T05:44:55.252826Z","caller":"traceutil/trace.go:171","msg":"trace[714725605] range","detail":"{range_begin:/registry/events/openstack/datadir-percona-xtradb-pxc-0.18a622ea5b84c83f; range_end:; response_count:1; response_revision:1790; }","duration":"164.208607ms","start":"2026-04-14T05:44:55.088589Z","end":"2026-04-14T05:44:55.252798Z","steps":["trace[714725605] 'range keys from in-memory index tree' (duration: 163.779678ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:45:08.545469Z","caller":"traceutil/trace.go:171","msg":"trace[230759632] transaction","detail":"{read_only:false; response_revision:1904; number_of_response:1; }","duration":"155.777834ms","start":"2026-04-14T05:45:08.389665Z","end":"2026-04-14T05:45:08.545443Z","steps":["trace[230759632] 'process raft request' (duration: 155.635311ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:45:08.547571Z","caller":"traceutil/trace.go:171","msg":"trace[384033485] transaction","detail":"{read_only:false; response_revision:1905; number_of_response:1; }","duration":"144.026753ms","start":"2026-04-14T05:45:08.403514Z","end":"2026-04-14T05:45:08.547541Z","steps":["trace[384033485] 'process raft request' (duration: 143.88045ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:45:46.216661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.678596ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-04-14T05:45:46.21693Z","caller":"traceutil/trace.go:171","msg":"trace[1276133826] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2202; }","duration":"329.623745ms","start":"2026-04-14T05:45:45.887283Z","end":"2026-04-14T05:45:46.216907Z","steps":["trace[1276133826] 'read index received' (duration: 38.623293ms)","trace[1276133826] 'applied index is now lower than readState.Index' (duration: 290.999212ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:45:46.217065Z","caller":"traceutil/trace.go:171","msg":"trace[812607580] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"193.300657ms","start":"2026-04-14T05:45:46.023728Z","end":"2026-04-14T05:45:46.217029Z","steps":["trace[812607580] 'process raft request' (duration: 193.064592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:45:46.217183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.87552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-14T05:45:46.217226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.589907ms","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-04-14T05:45:46.217278Z","caller":"traceutil/trace.go:171","msg":"trace[537770608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2120; }","duration":"181.689389ms","start":"2026-04-14T05:45:46.035572Z","end":"2026-04-14T05:45:46.217261Z","steps":["trace[537770608] 'agreement among raft nodes before linearized reading' (duration: 181.491625ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:45:46.217278Z","caller":"traceutil/trace.go:171","msg":"trace[663232044] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2120; }","duration":"330.014454ms","start":"2026-04-14T05:45:45.887242Z","end":"2026-04-14T05:45:46.217256Z","steps":["trace[663232044] 'agreement among raft nodes before linearized reading' (duration: 329.765248ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:45:46.217523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:45:45.887223Z","time spent":"330.259728ms","remote":"127.0.0.1:45882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-14T05:45:46.471884Z","caller":"traceutil/trace.go:171","msg":"trace[666363982] linearizableReadLoop","detail":"{readStateIndex:2205; appliedIndex:2204; }","duration":"182.2ms","start":"2026-04-14T05:45:46.289661Z","end":"2026-04-14T05:45:46.471861Z","steps":["trace[666363982] 'read index received' (duration: 181.932824ms)","trace[666363982] 'applied index is now lower than readState.Index' (duration: 266.456µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:45:46.471918Z","caller":"traceutil/trace.go:171","msg":"trace[1091157994] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"250.719996ms","start":"2026-04-14T05:45:46.221179Z","end":"2026-04-14T05:45:46.471899Z","steps":["trace[1091157994] 'process raft request' (duration: 250.524242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:45:46.472174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.280475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-14T05:45:46.472168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.899837ms","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-04-14T05:45:46.472216Z","caller":"traceutil/trace.go:171","msg":"trace[1411813996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2121; }","duration":"171.366507ms","start":"2026-04-14T05:45:46.300836Z","end":"2026-04-14T05:45:46.472203Z","steps":["trace[1411813996] 'agreement among raft nodes before linearized reading' (duration: 171.263545ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:45:46.472235Z","caller":"traceutil/trace.go:171","msg":"trace[60538803] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2121; }","duration":"172.0033ms","start":"2026-04-14T05:45:46.300216Z","end":"2026-04-14T05:45:46.472219Z","steps":["trace[60538803] 'agreement among raft nodes before linearized reading' (duration: 171.839447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:45:46.472368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.760144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T05:45:46.472603Z","caller":"traceutil/trace.go:171","msg":"trace[947964192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2121; }","duration":"171.01919ms","start":"2026-04-14T05:45:46.301552Z","end":"2026-04-14T05:45:46.472571Z","steps":["trace[947964192] 'agreement among raft nodes before linearized reading' (duration: 170.546131ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:45:46.472415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.755991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-14T05:45:46.472832Z","caller":"traceutil/trace.go:171","msg":"trace[590345288] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2121; }","duration":"183.179199ms","start":"2026-04-14T05:45:46.289611Z","end":"2026-04-14T05:45:46.472791Z","steps":["trace[590345288] 'agreement among raft nodes before linearized reading' (duration: 182.374463ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:46:41.366283Z","caller":"traceutil/trace.go:171","msg":"trace[998792231] transaction","detail":"{read_only:false; response_revision:2589; number_of_response:1; }","duration":"161.396342ms","start":"2026-04-14T05:46:41.204862Z","end":"2026-04-14T05:46:41.366258Z","steps":["trace[998792231] 'process raft request' (duration: 161.182037ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:49:41.709834Z","caller":"traceutil/trace.go:171","msg":"trace[294878782] transaction","detail":"{read_only:false; response_revision:3765; number_of_response:1; }","duration":"186.391744ms","start":"2026-04-14T05:49:41.523413Z","end":"2026-04-14T05:49:41.709804Z","steps":["trace[294878782] 'process raft request' (duration: 186.23187ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:49:41.867673Z","caller":"traceutil/trace.go:171","msg":"trace[1162917011] transaction","detail":"{read_only:false; response_revision:3766; number_of_response:1; }","duration":"343.608962ms","start":"2026-04-14T05:49:41.524038Z","end":"2026-04-14T05:49:41.867647Z","steps":["trace[1162917011] 'process raft request' (duration: 303.34028ms)","trace[1162917011] 'compare' (duration: 39.925615ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:49:41.867682Z","caller":"traceutil/trace.go:171","msg":"trace[1406279793] linearizableReadLoop","detail":"{readStateIndex:3913; appliedIndex:3910; }","duration":"229.066104ms","start":"2026-04-14T05:49:41.63859Z","end":"2026-04-14T05:49:41.867656Z","steps":["trace[1406279793] 'read index received' (duration: 71.06382ms)","trace[1406279793] 'applied index is now lower than readState.Index' (duration: 158.001254ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:49:41.867803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:49:41.524004Z","time spent":"343.729855ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:49:41.867957Z","caller":"traceutil/trace.go:171","msg":"trace[714041329] transaction","detail":"{read_only:false; response_revision:3767; number_of_response:1; }","duration":"322.253516ms","start":"2026-04-14T05:49:41.545503Z","end":"2026-04-14T05:49:41.867756Z","steps":["trace[714041329] 'process raft request' (duration: 322.048692ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:49:41.86809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.459463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-14T05:49:41.868221Z","caller":"traceutil/trace.go:171","msg":"trace[1550128005] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3767; }","duration":"229.632576ms","start":"2026-04-14T05:49:41.638532Z","end":"2026-04-14T05:49:41.868165Z","steps":["trace[1550128005] 'agreement among raft nodes before linearized reading' (duration: 229.272388ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:49:41.86853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.142889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6h84q\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-14T05:49:41.868615Z","caller":"traceutil/trace.go:171","msg":"trace[1205121641] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6h84q; range_end:; response_count:1; response_revision:3767; }","duration":"209.261751ms","start":"2026-04-14T05:49:41.65934Z","end":"2026-04-14T05:49:41.868602Z","steps":["trace[1205121641] 'agreement among raft nodes before linearized reading' (duration: 208.896153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:49:41.868664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.147459ms","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-04-14T05:49:41.868751Z","caller":"traceutil/trace.go:171","msg":"trace[368361721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3767; }","duration":"213.271402ms","start":"2026-04-14T05:49:41.655449Z","end":"2026-04-14T05:49:41.86872Z","steps":["trace[368361721] 'agreement among raft nodes before linearized reading' (duration: 213.045797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:49:41.868796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:49:41.545484Z","time spent":"322.546012ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T05:49:41.868844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.050481ms","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-04-14T05:49:41.868868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.410495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-14T05:49:41.868914Z","caller":"traceutil/trace.go:171","msg":"trace[164687493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3767; }","duration":"221.161223ms","start":"2026-04-14T05:49:41.647735Z","end":"2026-04-14T05:49:41.868896Z","steps":["trace[164687493] 'agreement among raft nodes before linearized reading' (duration: 220.221825ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:49:41.868924Z","caller":"traceutil/trace.go:171","msg":"trace[994911186] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3767; }","duration":"213.512107ms","start":"2026-04-14T05:49:41.655398Z","end":"2026-04-14T05:49:41.868911Z","steps":["trace[994911186] 'agreement among raft nodes before linearized reading' (duration: 213.358704ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:50:59.786583Z","caller":"traceutil/trace.go:171","msg":"trace[459170177] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"102.501861ms","start":"2026-04-14T05:50:59.684053Z","end":"2026-04-14T05:50:59.786555Z","steps":["trace[459170177] 'process raft request' (duration: 102.363868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:51:05.94447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.147615ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-14T05:51:05.944835Z","caller":"traceutil/trace.go:171","msg":"trace[1226775172] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"306.939094ms","start":"2026-04-14T05:51:05.637849Z","end":"2026-04-14T05:51:05.944788Z","steps":["trace[1226775172] 'process raft request' (duration: 85.393932ms)","trace[1226775172] 'compare' (duration: 220.996521ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:51:05.944853Z","caller":"traceutil/trace.go:171","msg":"trace[276459964] transaction","detail":"{read_only:false; response_revision:4962; number_of_response:1; }","duration":"303.75312ms","start":"2026-04-14T05:51:05.641054Z","end":"2026-04-14T05:51:05.944807Z","steps":["trace[276459964] 'process raft request' (duration: 303.541536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:51:05.945004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:51:05.63784Z","time spent":"307.094018ms","remote":"127.0.0.1:46124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1401,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-14T05:51:05.94497Z","caller":"traceutil/trace.go:171","msg":"trace[1552492034] linearizableReadLoop","detail":"{readStateIndex:5150; appliedIndex:5148; }","duration":"282.628831ms","start":"2026-04-14T05:51:05.66231Z","end":"2026-04-14T05:51:05.944939Z","steps":["trace[1552492034] 'read index received' (duration: 60.947676ms)","trace[1552492034] 'applied index is now lower than readState.Index' (duration: 221.678325ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:51:05.945051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:51:05.641035Z","time spent":"303.945533ms","remote":"127.0.0.1:44486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4833,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-14T05:51:05.999043Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.498971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:51:05.999127Z","caller":"traceutil/trace.go:171","msg":"trace[487939970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4962; }","duration":"112.625324ms","start":"2026-04-14T05:51:05.886481Z","end":"2026-04-14T05:51:05.999106Z","steps":["trace[487939970] 'agreement among raft nodes before linearized reading' (duration: 112.510911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:51:05.999135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.798819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-tjmjl-tls\" ","response":"range_response_count:1 size:2183"} {"level":"info","ts":"2026-04-14T05:51:05.99926Z","caller":"traceutil/trace.go:171","msg":"trace[369833062] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-tjmjl-tls; range_end:; response_count:1; response_revision:4962; }","duration":"336.942022ms","start":"2026-04-14T05:51:05.662273Z","end":"2026-04-14T05:51:05.999215Z","steps":["trace[369833062] 'agreement among raft nodes before linearized reading' (duration: 282.744853ms)","trace[369833062] 'range keys from in-memory index tree' (duration: 53.944114ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:51:05.999256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.145682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc593f99-703a-4490-9f71-8540ae5edb22\" ","response":"range_response_count:1 size:3600"} {"level":"warn","ts":"2026-04-14T05:51:05.999327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:51:05.662256Z","time spent":"337.056954ms","remote":"127.0.0.1:51124","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":2207,"request content":"key:\"/registry/cert-manager.io/certificates/monitoring/kube-prometheus-stack-prometheus-node-exporter-tjmjl-tls\" "} {"level":"info","ts":"2026-04-14T05:51:05.999343Z","caller":"traceutil/trace.go:171","msg":"trace[830927917] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bc593f99-703a-4490-9f71-8540ae5edb22; range_end:; response_count:1; response_revision:4962; }","duration":"250.275124ms","start":"2026-04-14T05:51:05.749044Z","end":"2026-04-14T05:51:05.999319Z","steps":["trace[830927917] 'agreement among raft nodes before linearized reading' (duration: 249.909997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:51:06.333756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.489728ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:51:06.333886Z","caller":"traceutil/trace.go:171","msg":"trace[293925993] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4973; }","duration":"100.639461ms","start":"2026-04-14T05:51:06.233227Z","end":"2026-04-14T05:51:06.333866Z","steps":["trace[293925993] 'range keys from in-memory index tree' (duration: 100.456697ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:51:06.333962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.059125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T05:51:06.334104Z","caller":"traceutil/trace.go:171","msg":"trace[1282378621] transaction","detail":"{read_only:false; response_revision:4974; number_of_response:1; }","duration":"156.82937ms","start":"2026-04-14T05:51:06.177256Z","end":"2026-04-14T05:51:06.334085Z","steps":["trace[1282378621] 'process raft request' (duration: 33.560781ms)","trace[1282378621] 'compare' (duration: 122.969933ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:51:16.114777Z","caller":"traceutil/trace.go:171","msg":"trace[1670842458] transaction","detail":"{read_only:false; response_revision:5172; number_of_response:1; }","duration":"160.209598ms","start":"2026-04-14T05:51:15.954543Z","end":"2026-04-14T05:51:16.114752Z","steps":["trace[1670842458] 'process raft request' (duration: 128.914264ms)","trace[1670842458] 'compare' (duration: 31.170632ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:51:52.182405Z","caller":"traceutil/trace.go:171","msg":"trace[352291959] transaction","detail":"{read_only:false; response_revision:5525; number_of_response:1; }","duration":"109.602332ms","start":"2026-04-14T05:51:52.072775Z","end":"2026-04-14T05:51:52.182377Z","steps":["trace[352291959] 'process raft request' (duration: 109.429999ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:51:57.653578Z","caller":"traceutil/trace.go:171","msg":"trace[1325372231] linearizableReadLoop","detail":"{readStateIndex:5759; appliedIndex:5758; }","duration":"115.878369ms","start":"2026-04-14T05:51:57.53767Z","end":"2026-04-14T05:51:57.653549Z","steps":["trace[1325372231] 'read index received' (duration: 27.713312ms)","trace[1325372231] 'applied index is now lower than readState.Index' (duration: 88.163797ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:51:57.653717Z","caller":"traceutil/trace.go:171","msg":"trace[1512880725] transaction","detail":"{read_only:false; response_revision:5554; number_of_response:1; }","duration":"143.993599ms","start":"2026-04-14T05:51:57.509711Z","end":"2026-04-14T05:51:57.653705Z","steps":["trace[1512880725] 'process raft request' (duration: 55.634498ms)","trace[1512880725] 'compare' (duration: 88.069335ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:51:57.654208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.514021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T05:51:57.654289Z","caller":"traceutil/trace.go:171","msg":"trace[906375983] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:5554; }","duration":"116.657654ms","start":"2026-04-14T05:51:57.537614Z","end":"2026-04-14T05:51:57.654272Z","steps":["trace[906375983] 'agreement among raft nodes before linearized reading' (duration: 116.475611ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:52:31.680537Z","caller":"traceutil/trace.go:171","msg":"trace[120664913] linearizableReadLoop","detail":"{readStateIndex:6034; appliedIndex:6033; }","duration":"135.740006ms","start":"2026-04-14T05:52:31.544771Z","end":"2026-04-14T05:52:31.680511Z","steps":["trace[120664913] 'read index received' (duration: 118.629358ms)","trace[120664913] 'applied index is now lower than readState.Index' (duration: 17.108948ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:52:31.680806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.755326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-14T05:52:31.680845Z","caller":"traceutil/trace.go:171","msg":"trace[2027747067] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5821; }","duration":"135.831818ms","start":"2026-04-14T05:52:31.545003Z","end":"2026-04-14T05:52:31.680835Z","steps":["trace[2027747067] 'agreement among raft nodes before linearized reading' (duration: 135.672305ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:52:31.680829Z","caller":"traceutil/trace.go:171","msg":"trace[1991369500] transaction","detail":"{read_only:false; response_revision:5821; number_of_response:1; }","duration":"137.827547ms","start":"2026-04-14T05:52:31.542972Z","end":"2026-04-14T05:52:31.6808Z","steps":["trace[1991369500] 'process raft request' (duration: 120.466985ms)","trace[1991369500] 'compare' (duration: 16.867293ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:52:31.680849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.050972ms","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-04-14T05:52:31.680948Z","caller":"traceutil/trace.go:171","msg":"trace[1261022682] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5821; }","duration":"136.207725ms","start":"2026-04-14T05:52:31.544721Z","end":"2026-04-14T05:52:31.680929Z","steps":["trace[1261022682] 'agreement among raft nodes before linearized reading' (duration: 135.95188ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:52:47.488411Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2974} {"level":"info","ts":"2026-04-14T05:52:47.559539Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2974,"took":"69.456631ms","hash":889736538,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":32083968,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T05:52:47.559695Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":889736538,"revision":2974,"compact-revision":-1} {"level":"info","ts":"2026-04-14T05:53:26.082139Z","caller":"traceutil/trace.go:171","msg":"trace[1067328890] transaction","detail":"{read_only:false; response_revision:6254; number_of_response:1; }","duration":"100.96939ms","start":"2026-04-14T05:53:25.981144Z","end":"2026-04-14T05:53:26.082113Z","steps":["trace[1067328890] 'process raft request' (duration: 100.768826ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:53:26.087094Z","caller":"traceutil/trace.go:171","msg":"trace[1747900311] transaction","detail":"{read_only:false; response_revision:6255; number_of_response:1; }","duration":"103.92099ms","start":"2026-04-14T05:53:25.982851Z","end":"2026-04-14T05:53:26.086772Z","steps":["trace[1747900311] 'process raft request' (duration: 103.784367ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:57:47.495506Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5922} {"level":"info","ts":"2026-04-14T05:57:47.585115Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5922,"took":"86.670974ms","hash":2528764482,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":24014848,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-14T05:57:47.585209Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2528764482,"revision":5922,"compact-revision":2974} {"level":"warn","ts":"2026-04-14T05:58:16.063586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.46277ms","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-04-14T05:58:16.063684Z","caller":"traceutil/trace.go:171","msg":"trace[554358747] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8415; }","duration":"396.608453ms","start":"2026-04-14T05:58:15.667054Z","end":"2026-04-14T05:58:16.063662Z","steps":["trace[554358747] 'range keys from in-memory index tree' (duration: 396.268026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:58:16.063744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:58:15.667031Z","time spent":"396.703215ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-14T05:58:16.063838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.941461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-14T05:58:16.063924Z","caller":"traceutil/trace.go:171","msg":"trace[907365867] transaction","detail":"{read_only:false; response_revision:8416; number_of_response:1; }","duration":"394.862797ms","start":"2026-04-14T05:58:15.669048Z","end":"2026-04-14T05:58:16.063911Z","steps":["trace[907365867] 'process raft request' (duration: 255.779184ms)","trace[907365867] 'compare' (duration: 138.559393ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:58:16.063959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T05:58:15.669022Z","time spent":"394.920279ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T05:58:16.066341Z","caller":"traceutil/trace.go:171","msg":"trace[87967649] linearizableReadLoop","detail":"{readStateIndex:8714; appliedIndex:8712; }","duration":"272.701737ms","start":"2026-04-14T05:58:15.793605Z","end":"2026-04-14T05:58:16.066306Z","steps":["trace[87967649] 'read index received' (duration: 131.373318ms)","trace[87967649] 'applied index is now lower than readState.Index' (duration: 141.327539ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:58:16.066474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.85762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:58:16.066499Z","caller":"traceutil/trace.go:171","msg":"trace[704318741] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:8417; }","duration":"272.941022ms","start":"2026-04-14T05:58:15.79355Z","end":"2026-04-14T05:58:16.066491Z","steps":["trace[704318741] 'agreement among raft nodes before linearized reading' (duration: 272.86482ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:58:16.066483Z","caller":"traceutil/trace.go:171","msg":"trace[1424736091] transaction","detail":"{read_only:false; response_revision:8417; number_of_response:1; }","duration":"225.913501ms","start":"2026-04-14T05:58:15.840522Z","end":"2026-04-14T05:58:16.066436Z","steps":["trace[1424736091] 'process raft request' (duration: 225.692806ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:58:16.066646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.923108ms","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-04-14T05:58:16.066759Z","caller":"traceutil/trace.go:171","msg":"trace[2111350086] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:8417; }","duration":"219.087242ms","start":"2026-04-14T05:58:15.847649Z","end":"2026-04-14T05:58:16.066737Z","steps":["trace[2111350086] 'agreement among raft nodes before linearized reading' (duration: 218.823226ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:58:16.066786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.315527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T05:58:16.066828Z","caller":"traceutil/trace.go:171","msg":"trace[33868874] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8417; }","duration":"178.400689ms","start":"2026-04-14T05:58:15.888415Z","end":"2026-04-14T05:58:16.066816Z","steps":["trace[33868874] 'agreement among raft nodes before linearized reading' (duration: 178.334458ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:58:28.225758Z","caller":"traceutil/trace.go:171","msg":"trace[1067173542] transaction","detail":"{read_only:false; response_revision:8478; number_of_response:1; }","duration":"118.791582ms","start":"2026-04-14T05:58:28.106945Z","end":"2026-04-14T05:58:28.225736Z","steps":["trace[1067173542] 'process raft request' (duration: 118.637879ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:58:28.363184Z","caller":"traceutil/trace.go:171","msg":"trace[952492307] linearizableReadLoop","detail":"{readStateIndex:8780; appliedIndex:8778; }","duration":"230.89711ms","start":"2026-04-14T05:58:28.132267Z","end":"2026-04-14T05:58:28.363164Z","steps":["trace[952492307] 'read index received' (duration: 93.308887ms)","trace[952492307] 'applied index is now lower than readState.Index' (duration: 137.587453ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:58:28.363284Z","caller":"traceutil/trace.go:171","msg":"trace[1644491069] transaction","detail":"{read_only:false; response_revision:8479; number_of_response:1; }","duration":"254.638351ms","start":"2026-04-14T05:58:28.108637Z","end":"2026-04-14T05:58:28.363275Z","steps":["trace[1644491069] 'process raft request' (duration: 243.587468ms)","trace[1644491069] 'compare' (duration: 10.836589ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T05:58:28.363419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.409752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-14T05:58:28.363482Z","caller":"traceutil/trace.go:171","msg":"trace[1460542370] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:8479; }","duration":"150.506794ms","start":"2026-04-14T05:58:28.212958Z","end":"2026-04-14T05:58:28.363465Z","steps":["trace[1460542370] 'agreement among raft nodes before linearized reading' (duration: 150.395722ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:58:28.363672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.38635ms","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-04-14T05:58:28.363746Z","caller":"traceutil/trace.go:171","msg":"trace[385515369] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8479; }","duration":"231.496842ms","start":"2026-04-14T05:58:28.132225Z","end":"2026-04-14T05:58:28.363722Z","steps":["trace[385515369] 'agreement among raft nodes before linearized reading' (duration: 231.270198ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:58:29.208551Z","caller":"traceutil/trace.go:171","msg":"trace[1194591498] transaction","detail":"{read_only:false; response_revision:8485; number_of_response:1; }","duration":"103.880022ms","start":"2026-04-14T05:58:29.104631Z","end":"2026-04-14T05:58:29.208511Z","steps":["trace[1194591498] 'process raft request' (duration: 103.627346ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:58:30.391793Z","caller":"traceutil/trace.go:171","msg":"trace[1050417814] transaction","detail":"{read_only:false; response_revision:8489; number_of_response:1; }","duration":"159.080237ms","start":"2026-04-14T05:58:30.232691Z","end":"2026-04-14T05:58:30.391771Z","steps":["trace[1050417814] 'process raft request' (duration: 158.907654ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:58:57.478891Z","caller":"traceutil/trace.go:171","msg":"trace[1387925684] linearizableReadLoop","detail":"{readStateIndex:9000; appliedIndex:8999; }","duration":"131.946249ms","start":"2026-04-14T05:58:57.346928Z","end":"2026-04-14T05:58:57.478874Z","steps":["trace[1387925684] 'read index received' (duration: 131.853018ms)","trace[1387925684] 'applied index is now lower than readState.Index' (duration: 92.461µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:58:57.479027Z","caller":"traceutil/trace.go:171","msg":"trace[1676597474] transaction","detail":"{read_only:false; response_revision:8691; number_of_response:1; }","duration":"172.736225ms","start":"2026-04-14T05:58:57.306255Z","end":"2026-04-14T05:58:57.478991Z","steps":["trace[1676597474] 'process raft request' (duration: 172.4929ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:58:57.479103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.172213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-04-14T05:58:57.479134Z","caller":"traceutil/trace.go:171","msg":"trace[151860307] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:8691; }","duration":"132.265485ms","start":"2026-04-14T05:58:57.346858Z","end":"2026-04-14T05:58:57.479124Z","steps":["trace[151860307] 'agreement among raft nodes before linearized reading' (duration: 132.120713ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:59:09.679726Z","caller":"traceutil/trace.go:171","msg":"trace[1990616245] transaction","detail":"{read_only:false; number_of_response:1; response_revision:8798; }","duration":"117.033417ms","start":"2026-04-14T05:59:09.562675Z","end":"2026-04-14T05:59:09.679708Z","steps":["trace[1990616245] 'process raft request' (duration: 99.656526ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:59:09.686803Z","caller":"traceutil/trace.go:171","msg":"trace[523392105] transaction","detail":"{read_only:false; response_revision:8799; number_of_response:1; }","duration":"123.63851ms","start":"2026-04-14T05:59:09.563124Z","end":"2026-04-14T05:59:09.686763Z","steps":["trace[523392105] 'process raft request' (duration: 123.482317ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:59:10.661352Z","caller":"traceutil/trace.go:171","msg":"trace[977182056] transaction","detail":"{read_only:false; response_revision:8803; number_of_response:1; }","duration":"151.824541ms","start":"2026-04-14T05:59:10.509501Z","end":"2026-04-14T05:59:10.661326Z","steps":["trace[977182056] 'process raft request' (duration: 151.660247ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:59:10.676535Z","caller":"traceutil/trace.go:171","msg":"trace[706410221] transaction","detail":"{read_only:false; response_revision:8804; number_of_response:1; }","duration":"154.844972ms","start":"2026-04-14T05:59:10.521671Z","end":"2026-04-14T05:59:10.676516Z","steps":["trace[706410221] 'process raft request' (duration: 154.664258ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:59:10.676565Z","caller":"traceutil/trace.go:171","msg":"trace[78789900] transaction","detail":"{read_only:false; response_revision:8805; number_of_response:1; }","duration":"130.613912ms","start":"2026-04-14T05:59:10.545933Z","end":"2026-04-14T05:59:10.676547Z","steps":["trace[78789900] 'process raft request' (duration: 130.52077ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:59:10.835906Z","caller":"traceutil/trace.go:171","msg":"trace[974531748] linearizableReadLoop","detail":"{readStateIndex:9122; appliedIndex:9120; }","duration":"118.623601ms","start":"2026-04-14T05:59:10.717263Z","end":"2026-04-14T05:59:10.835887Z","steps":["trace[974531748] 'read index received' (duration: 118.533519ms)","trace[974531748] 'applied index is now lower than readState.Index' (duration: 89.042µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T05:59:10.836009Z","caller":"traceutil/trace.go:171","msg":"trace[1234092926] transaction","detail":"{read_only:false; response_revision:8808; number_of_response:1; }","duration":"144.641686ms","start":"2026-04-14T05:59:10.691357Z","end":"2026-04-14T05:59:10.835998Z","steps":["trace[1234092926] 'process raft request' (duration: 144.467722ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:59:10.836191Z","caller":"traceutil/trace.go:171","msg":"trace[472048985] transaction","detail":"{read_only:false; response_revision:8807; number_of_response:1; }","duration":"149.906353ms","start":"2026-04-14T05:59:10.686263Z","end":"2026-04-14T05:59:10.836169Z","steps":["trace[472048985] 'process raft request' (duration: 149.433954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:59:10.836231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.948737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-04-14T05:59:10.836273Z","caller":"traceutil/trace.go:171","msg":"trace[243186296] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8808; }","duration":"119.033328ms","start":"2026-04-14T05:59:10.717228Z","end":"2026-04-14T05:59:10.836261Z","steps":["trace[243186296] 'agreement among raft nodes before linearized reading' (duration: 118.881405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:59:10.885439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.035985ms","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-04-14T05:59:10.885496Z","caller":"traceutil/trace.go:171","msg":"trace[496442819] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8809; }","duration":"153.119556ms","start":"2026-04-14T05:59:10.732362Z","end":"2026-04-14T05:59:10.885482Z","steps":["trace[496442819] 'agreement among raft nodes before linearized reading' (duration: 152.975113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:59:10.88556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.916112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-14T05:59:10.885641Z","caller":"traceutil/trace.go:171","msg":"trace[1039320719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8809; }","duration":"153.022285ms","start":"2026-04-14T05:59:10.7326Z","end":"2026-04-14T05:59:10.885622Z","steps":["trace[1039320719] 'agreement among raft nodes before linearized reading' (duration: 152.828701ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T05:59:10.885662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.169491ms","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-04-14T05:59:10.885741Z","caller":"traceutil/trace.go:171","msg":"trace[1873459020] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8809; }","duration":"167.282593ms","start":"2026-04-14T05:59:10.718438Z","end":"2026-04-14T05:59:10.88572Z","steps":["trace[1873459020] 'agreement among raft nodes before linearized reading' (duration: 167.089439ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T05:59:51.148732Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000252c.wal"} {"level":"info","ts":"2026-04-14T06:00:39.130654Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T06:00:39.136049Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-14T06:00:39.136155Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-14T06:02:47.503646Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8091} {"level":"info","ts":"2026-04-14T06:02:47.565814Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8091,"took":"60.311179ms","hash":542699935,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":29167616,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-14T06:02:47.565876Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":542699935,"revision":8091,"compact-revision":5922} {"level":"info","ts":"2026-04-14T06:07:47.511768Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10656} {"level":"info","ts":"2026-04-14T06:07:47.59561Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10656,"took":"80.046335ms","hash":3322917735,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":27561984,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-14T06:07:47.595686Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3322917735,"revision":10656,"compact-revision":8091} {"level":"info","ts":"2026-04-14T06:09:46.269199Z","caller":"traceutil/trace.go:171","msg":"trace[1904097695] linearizableReadLoop","detail":"{readStateIndex:14221; appliedIndex:14220; }","duration":"120.286877ms","start":"2026-04-14T06:09:46.148857Z","end":"2026-04-14T06:09:46.269144Z","steps":["trace[1904097695] 'read index received' (duration: 120.054391ms)","trace[1904097695] 'applied index is now lower than readState.Index' (duration: 231.256µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:09:46.269295Z","caller":"traceutil/trace.go:171","msg":"trace[1451398968] transaction","detail":"{read_only:false; response_revision:13753; number_of_response:1; }","duration":"120.414959ms","start":"2026-04-14T06:09:46.148837Z","end":"2026-04-14T06:09:46.269252Z","steps":["trace[1451398968] 'process raft request' (duration: 120.122282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:09:46.269402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.527651ms","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-04-14T06:09:46.269466Z","caller":"traceutil/trace.go:171","msg":"trace[1602766562] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13753; }","duration":"120.622403ms","start":"2026-04-14T06:09:46.148827Z","end":"2026-04-14T06:09:46.269449Z","steps":["trace[1602766562] 'agreement among raft nodes before linearized reading' (duration: 120.446909ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:09:46.272664Z","caller":"traceutil/trace.go:171","msg":"trace[708246126] transaction","detail":"{read_only:false; response_revision:13754; number_of_response:1; }","duration":"121.009451ms","start":"2026-04-14T06:09:46.151632Z","end":"2026-04-14T06:09:46.272641Z","steps":["trace[708246126] 'process raft request' (duration: 120.838827ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:09:46.272953Z","caller":"traceutil/trace.go:171","msg":"trace[1298756095] transaction","detail":"{read_only:false; response_revision:13756; number_of_response:1; }","duration":"120.549012ms","start":"2026-04-14T06:09:46.152385Z","end":"2026-04-14T06:09:46.272934Z","steps":["trace[1298756095] 'process raft request' (duration: 120.44882ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:09:46.272953Z","caller":"traceutil/trace.go:171","msg":"trace[438736857] transaction","detail":"{read_only:false; response_revision:13755; number_of_response:1; }","duration":"120.596563ms","start":"2026-04-14T06:09:46.15234Z","end":"2026-04-14T06:09:46.272936Z","steps":["trace[438736857] 'process raft request' (duration: 120.268876ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:10:16.200893Z","caller":"traceutil/trace.go:171","msg":"trace[1233330217] transaction","detail":"{read_only:false; response_revision:13912; number_of_response:1; }","duration":"153.22469ms","start":"2026-04-14T06:10:16.04765Z","end":"2026-04-14T06:10:16.200875Z","steps":["trace[1233330217] 'process raft request' (duration: 153.119208ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:10:39.314745Z","caller":"traceutil/trace.go:171","msg":"trace[1597344494] transaction","detail":"{read_only:false; response_revision:14053; number_of_response:1; }","duration":"106.697552ms","start":"2026-04-14T06:10:39.208016Z","end":"2026-04-14T06:10:39.314714Z","steps":["trace[1597344494] 'process raft request' (duration: 105.860315ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:10:59.425214Z","caller":"traceutil/trace.go:171","msg":"trace[2079487857] transaction","detail":"{read_only:false; response_revision:14347; number_of_response:1; }","duration":"108.453907ms","start":"2026-04-14T06:10:59.316723Z","end":"2026-04-14T06:10:59.425176Z","steps":["trace[2079487857] 'process raft request' (duration: 108.216523ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:10:59.716118Z","caller":"traceutil/trace.go:171","msg":"trace[1644280194] linearizableReadLoop","detail":"{readStateIndex:14842; appliedIndex:14841; }","duration":"290.958919ms","start":"2026-04-14T06:10:59.425121Z","end":"2026-04-14T06:10:59.71608Z","steps":["trace[1644280194] 'read index received' (duration: 290.358517ms)","trace[1644280194] 'applied index is now lower than readState.Index' (duration: 598.872µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:10:59.71622Z","caller":"traceutil/trace.go:171","msg":"trace[650448610] transaction","detail":"{read_only:false; response_revision:14348; number_of_response:1; }","duration":"325.431614ms","start":"2026-04-14T06:10:59.39075Z","end":"2026-04-14T06:10:59.716181Z","steps":["trace[650448610] 'process raft request' (duration: 324.752911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:10:59.716301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.522868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:10:59.716371Z","caller":"traceutil/trace.go:171","msg":"trace[605469203] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:14348; }","duration":"336.605119ms","start":"2026-04-14T06:10:59.379742Z","end":"2026-04-14T06:10:59.716347Z","steps":["trace[605469203] 'agreement among raft nodes before linearized reading' (duration: 336.484117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:10:59.716413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:10:59.379714Z","time spent":"336.68915ms","remote":"127.0.0.1:53376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:10:59.716491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:10:59.390721Z","time spent":"325.636067ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:12:24.830058Z","caller":"traceutil/trace.go:171","msg":"trace[1279399709] transaction","detail":"{read_only:false; response_revision:15146; number_of_response:1; }","duration":"103.585459ms","start":"2026-04-14T06:12:24.726435Z","end":"2026-04-14T06:12:24.830021Z","steps":["trace[1279399709] 'process raft request' (duration: 103.241042ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:12:25.020518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.36312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-14T06:12:25.020643Z","caller":"traceutil/trace.go:171","msg":"trace[1029952348] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:15146; }","duration":"131.552274ms","start":"2026-04-14T06:12:24.889062Z","end":"2026-04-14T06:12:25.020614Z","steps":["trace[1029952348] 'range keys from in-memory index tree' (duration: 131.243977ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:12:25.368571Z","caller":"traceutil/trace.go:171","msg":"trace[58065819] transaction","detail":"{read_only:false; response_revision:15148; number_of_response:1; }","duration":"168.414647ms","start":"2026-04-14T06:12:25.200132Z","end":"2026-04-14T06:12:25.368547Z","steps":["trace[58065819] 'process raft request' (duration: 168.252753ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:12:25.381492Z","caller":"traceutil/trace.go:171","msg":"trace[1676085618] transaction","detail":"{read_only:false; response_revision:15149; number_of_response:1; }","duration":"179.730046ms","start":"2026-04-14T06:12:25.201742Z","end":"2026-04-14T06:12:25.381472Z","steps":["trace[1676085618] 'process raft request' (duration: 179.567363ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:12:25.381502Z","caller":"traceutil/trace.go:171","msg":"trace[1170265224] linearizableReadLoop","detail":"{readStateIndex:15668; appliedIndex:15664; }","duration":"178.141433ms","start":"2026-04-14T06:12:25.203345Z","end":"2026-04-14T06:12:25.381487Z","steps":["trace[1170265224] 'read index received' (duration: 165.10172ms)","trace[1170265224] 'applied index is now lower than readState.Index' (duration: 13.038933ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:12:25.381585Z","caller":"traceutil/trace.go:171","msg":"trace[1062089766] transaction","detail":"{read_only:false; response_revision:15151; number_of_response:1; }","duration":"178.48979ms","start":"2026-04-14T06:12:25.203085Z","end":"2026-04-14T06:12:25.381575Z","steps":["trace[1062089766] 'process raft request' (duration: 178.366497ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:12:25.381671Z","caller":"traceutil/trace.go:171","msg":"trace[198618906] transaction","detail":"{read_only:false; response_revision:15150; number_of_response:1; }","duration":"179.708326ms","start":"2026-04-14T06:12:25.201805Z","end":"2026-04-14T06:12:25.381513Z","steps":["trace[198618906] 'process raft request' (duration: 179.612404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:12:25.38265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.313416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:77 size:744397"} {"level":"info","ts":"2026-04-14T06:12:25.382765Z","caller":"traceutil/trace.go:171","msg":"trace[604873754] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:77; response_revision:15151; }","duration":"177.460619ms","start":"2026-04-14T06:12:25.205279Z","end":"2026-04-14T06:12:25.382739Z","steps":["trace[604873754] 'agreement among raft nodes before linearized reading' (duration: 176.319836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:12:25.383053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.694164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:77 size:744397"} {"level":"info","ts":"2026-04-14T06:12:25.38313Z","caller":"traceutil/trace.go:171","msg":"trace[1246447195] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:77; response_revision:15151; }","duration":"179.795326ms","start":"2026-04-14T06:12:25.203317Z","end":"2026-04-14T06:12:25.383112Z","steps":["trace[1246447195] 'agreement among raft nodes before linearized reading' (duration: 178.375558ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:12:47.516987Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12856} {"level":"info","ts":"2026-04-14T06:12:47.597723Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12856,"took":"77.613975ms","hash":4173742456,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":30253056,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-14T06:12:47.598276Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4173742456,"revision":12856,"compact-revision":10656} {"level":"info","ts":"2026-04-14T06:17:47.524269Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15266} {"level":"info","ts":"2026-04-14T06:17:47.620999Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15266,"took":"92.895871ms","hash":2748403055,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":31780864,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T06:17:47.621079Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2748403055,"revision":15266,"compact-revision":12856} {"level":"info","ts":"2026-04-14T06:18:06.742343Z","caller":"traceutil/trace.go:171","msg":"trace[1617855132] linearizableReadLoop","detail":"{readStateIndex:18307; appliedIndex:18305; }","duration":"508.187277ms","start":"2026-04-14T06:18:06.234116Z","end":"2026-04-14T06:18:06.742303Z","steps":["trace[1617855132] 'read index received' (duration: 4.912739ms)","trace[1617855132] 'applied index is now lower than readState.Index' (duration: 503.272968ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:18:06.742696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.550734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-77fgs\" ","response":"range_response_count:1 size:20869"} {"level":"info","ts":"2026-04-14T06:18:06.742821Z","caller":"traceutil/trace.go:171","msg":"trace[2121529809] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-77fgs; range_end:; response_count:1; response_revision:17704; }","duration":"508.715788ms","start":"2026-04-14T06:18:06.234072Z","end":"2026-04-14T06:18:06.742788Z","steps":["trace[2121529809] 'agreement among raft nodes before linearized reading' (duration: 508.353521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:06.74289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:18:06.234053Z","time spent":"508.82297ms","remote":"127.0.0.1:46032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20893,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-77fgs\" "} {"level":"info","ts":"2026-04-14T06:18:14.375775Z","caller":"traceutil/trace.go:171","msg":"trace[155385625] transaction","detail":"{read_only:false; response_revision:17856; number_of_response:1; }","duration":"153.795128ms","start":"2026-04-14T06:18:14.221948Z","end":"2026-04-14T06:18:14.375744Z","steps":["trace[155385625] 'process raft request' (duration: 153.457861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:14.375867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.308368ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:18:14.375956Z","caller":"traceutil/trace.go:171","msg":"trace[2085974332] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:17858; }","duration":"153.41169ms","start":"2026-04-14T06:18:14.222531Z","end":"2026-04-14T06:18:14.375943Z","steps":["trace[2085974332] 'agreement among raft nodes before linearized reading' (duration: 153.256827ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:14.375781Z","caller":"traceutil/trace.go:171","msg":"trace[851655859] linearizableReadLoop","detail":"{readStateIndex:18464; appliedIndex:18461; }","duration":"100.582355ms","start":"2026-04-14T06:18:14.275174Z","end":"2026-04-14T06:18:14.375757Z","steps":["trace[851655859] 'read index received' (duration: 100.229428ms)","trace[851655859] 'applied index is now lower than readState.Index' (duration: 352.067µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:18:14.376105Z","caller":"traceutil/trace.go:171","msg":"trace[1186429117] transaction","detail":"{read_only:false; response_revision:17857; number_of_response:1; }","duration":"153.142465ms","start":"2026-04-14T06:18:14.22294Z","end":"2026-04-14T06:18:14.376083Z","steps":["trace[1186429117] 'process raft request' (duration: 152.703116ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:18:14.376205Z","caller":"traceutil/trace.go:171","msg":"trace[1746135355] transaction","detail":"{read_only:false; response_revision:17858; number_of_response:1; }","duration":"111.68012ms","start":"2026-04-14T06:18:14.264394Z","end":"2026-04-14T06:18:14.376075Z","steps":["trace[1746135355] 'process raft request' (duration: 111.292212ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:18:14.377101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.466554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2210983"} {"level":"info","ts":"2026-04-14T06:18:14.377152Z","caller":"traceutil/trace.go:171","msg":"trace[1159995059] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:17858; }","duration":"104.544806ms","start":"2026-04-14T06:18:14.272595Z","end":"2026-04-14T06:18:14.37714Z","steps":["trace[1159995059] 'agreement among raft nodes before linearized reading' (duration: 103.575837ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:21:25.792888Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T06:21:25.798373Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-14T06:21:25.798493Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-14T06:22:47.536947Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17573} {"level":"info","ts":"2026-04-14T06:22:47.63017Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17573,"took":"89.799237ms","hash":4027714695,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":34574336,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-04-14T06:22:47.630278Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4027714695,"revision":17573,"compact-revision":15266} {"level":"info","ts":"2026-04-14T06:23:37.676974Z","caller":"traceutil/trace.go:171","msg":"trace[1631420255] transaction","detail":"{read_only:false; response_revision:20304; number_of_response:1; }","duration":"142.668033ms","start":"2026-04-14T06:23:37.534269Z","end":"2026-04-14T06:23:37.676937Z","steps":["trace[1631420255] 'process raft request' (duration: 142.445159ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:23:43.150311Z","caller":"traceutil/trace.go:171","msg":"trace[338980673] linearizableReadLoop","detail":"{readStateIndex:21020; appliedIndex:21019; }","duration":"148.581992ms","start":"2026-04-14T06:23:43.001702Z","end":"2026-04-14T06:23:43.150284Z","steps":["trace[338980673] 'read index received' (duration: 148.406958ms)","trace[338980673] 'applied index is now lower than readState.Index' (duration: 174.134µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:23:43.150624Z","caller":"traceutil/trace.go:171","msg":"trace[1868511909] transaction","detail":"{read_only:false; response_revision:20332; number_of_response:1; }","duration":"150.191794ms","start":"2026-04-14T06:23:43.000408Z","end":"2026-04-14T06:23:43.1506Z","steps":["trace[1868511909] 'process raft request' (duration: 149.718064ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:23:43.150948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.189494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-14T06:23:43.150996Z","caller":"traceutil/trace.go:171","msg":"trace[1228953551] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20332; }","duration":"149.304175ms","start":"2026-04-14T06:23:43.001676Z","end":"2026-04-14T06:23:43.15098Z","steps":["trace[1228953551] 'agreement among raft nodes before linearized reading' (duration: 149.125062ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:23:43.151323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.137311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-14T06:23:43.151422Z","caller":"traceutil/trace.go:171","msg":"trace[1603049136] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20332; }","duration":"144.258104ms","start":"2026-04-14T06:23:43.00714Z","end":"2026-04-14T06:23:43.151398Z","steps":["trace[1603049136] 'agreement among raft nodes before linearized reading' (duration: 143.950948ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:23:43.151405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.122711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T06:23:43.151493Z","caller":"traceutil/trace.go:171","msg":"trace[2068960564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:20332; }","duration":"141.246643ms","start":"2026-04-14T06:23:43.010227Z","end":"2026-04-14T06:23:43.151473Z","steps":["trace[2068960564] 'agreement among raft nodes before linearized reading' (duration: 140.901146ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:23:43.439433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.494869ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T06:23:43.439692Z","caller":"traceutil/trace.go:171","msg":"trace[1978243298] transaction","detail":"{read_only:false; response_revision:20333; number_of_response:1; }","duration":"284.16363ms","start":"2026-04-14T06:23:43.155495Z","end":"2026-04-14T06:23:43.439658Z","steps":["trace[1978243298] 'process raft request' (duration: 58.348975ms)","trace[1978243298] 'compare' (duration: 224.918198ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:23:43.439755Z","caller":"traceutil/trace.go:171","msg":"trace[1433200551] transaction","detail":"{read_only:false; response_revision:20334; number_of_response:1; }","duration":"283.230202ms","start":"2026-04-14T06:23:43.156501Z","end":"2026-04-14T06:23:43.439731Z","steps":["trace[1433200551] 'process raft request' (duration: 283.033958ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:23:43.440032Z","caller":"traceutil/trace.go:171","msg":"trace[1514510335] transaction","detail":"{read_only:false; response_revision:20335; number_of_response:1; }","duration":"283.298834ms","start":"2026-04-14T06:23:43.156646Z","end":"2026-04-14T06:23:43.439945Z","steps":["trace[1514510335] 'process raft request' (duration: 283.014899ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:23:43.823022Z","caller":"traceutil/trace.go:171","msg":"trace[898536450] linearizableReadLoop","detail":"{readStateIndex:21024; appliedIndex:21023; }","duration":"120.491976ms","start":"2026-04-14T06:23:43.7025Z","end":"2026-04-14T06:23:43.822992Z","steps":["trace[898536450] 'read index received' (duration: 120.20326ms)","trace[898536450] 'applied index is now lower than readState.Index' (duration: 287.626µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:23:43.823219Z","caller":"traceutil/trace.go:171","msg":"trace[1113668282] transaction","detail":"{read_only:false; response_revision:20336; number_of_response:1; }","duration":"126.987756ms","start":"2026-04-14T06:23:43.696171Z","end":"2026-04-14T06:23:43.823158Z","steps":["trace[1113668282] 'process raft request' (duration: 126.591058ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:23:43.823298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.772742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-14T06:23:43.823356Z","caller":"traceutil/trace.go:171","msg":"trace[1262650207] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20336; }","duration":"120.898583ms","start":"2026-04-14T06:23:43.702438Z","end":"2026-04-14T06:23:43.823337Z","steps":["trace[1262650207] 'agreement among raft nodes before linearized reading' (duration: 120.673329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:23:44.042138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.699639ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T06:23:44.04224Z","caller":"traceutil/trace.go:171","msg":"trace[2020400586] linearizableReadLoop","detail":"{readStateIndex:21025; appliedIndex:21024; }","duration":"153.819577ms","start":"2026-04-14T06:23:43.888402Z","end":"2026-04-14T06:23:44.042222Z","steps":["trace[2020400586] 'read index received' (duration: 44.950695ms)","trace[2020400586] 'applied index is now lower than readState.Index' (duration: 108.867782ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:23:44.042302Z","caller":"traceutil/trace.go:171","msg":"trace[87946768] transaction","detail":"{read_only:false; response_revision:20337; number_of_response:1; }","duration":"214.405076ms","start":"2026-04-14T06:23:43.827886Z","end":"2026-04-14T06:23:44.042291Z","steps":["trace[87946768] 'process raft request' (duration: 105.474083ms)","trace[87946768] 'compare' (duration: 108.608737ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:23:44.042406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.00611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:23:44.04247Z","caller":"traceutil/trace.go:171","msg":"trace[958297507] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20337; }","duration":"154.117143ms","start":"2026-04-14T06:23:43.888337Z","end":"2026-04-14T06:23:44.042454Z","steps":["trace[958297507] 'agreement among raft nodes before linearized reading' (duration: 154.017071ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:23:44.803096Z","caller":"traceutil/trace.go:171","msg":"trace[552465415] transaction","detail":"{read_only:false; response_revision:20341; number_of_response:1; }","duration":"114.928244ms","start":"2026-04-14T06:23:44.688141Z","end":"2026-04-14T06:23:44.803069Z","steps":["trace[552465415] 'process raft request' (duration: 114.75398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:27:28.779213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.161181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-14T06:27:28.779325Z","caller":"traceutil/trace.go:171","msg":"trace[1839282958] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22710; }","duration":"138.309755ms","start":"2026-04-14T06:27:28.640986Z","end":"2026-04-14T06:27:28.779296Z","steps":["trace[1839282958] 'range keys from in-memory index tree' (duration: 137.931377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:27:28.779417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.12455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T06:27:28.779483Z","caller":"traceutil/trace.go:171","msg":"trace[993248895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:22710; }","duration":"133.230772ms","start":"2026-04-14T06:27:28.646236Z","end":"2026-04-14T06:27:28.779467Z","steps":["trace[993248895] 'range keys from in-memory index tree' (duration: 132.956757ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:27:28.928512Z","caller":"traceutil/trace.go:171","msg":"trace[256958006] transaction","detail":"{read_only:false; response_revision:22713; number_of_response:1; }","duration":"143.846187ms","start":"2026-04-14T06:27:28.784625Z","end":"2026-04-14T06:27:28.928471Z","steps":["trace[256958006] 'process raft request' (duration: 115.872954ms)","trace[256958006] 'compare' (duration: 27.706087ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:27:28.928546Z","caller":"traceutil/trace.go:171","msg":"trace[1989212869] transaction","detail":"{read_only:false; response_revision:22714; number_of_response:1; }","duration":"143.567341ms","start":"2026-04-14T06:27:28.784951Z","end":"2026-04-14T06:27:28.928519Z","steps":["trace[1989212869] 'process raft request' (duration: 143.425448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:27:28.929957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.896691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi\" ","response":"range_response_count:1 size:625"} {"level":"info","ts":"2026-04-14T06:27:28.930042Z","caller":"traceutil/trace.go:171","msg":"trace[432455834] range","detail":"{range_begin:/registry/leases/capi-kubeadm-bootstrap-system/kubeadm-bootstrap-manager-leader-election-capi; range_end:; response_count:1; response_revision:22714; }","duration":"101.024313ms","start":"2026-04-14T06:27:28.828996Z","end":"2026-04-14T06:27:28.93002Z","steps":["trace[432455834] 'agreement among raft nodes before linearized reading' (duration: 99.700607ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:27:47.54762Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20044} {"level":"info","ts":"2026-04-14T06:27:47.658165Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20044,"took":"106.107026ms","hash":1048156779,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":40488960,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-04-14T06:27:47.658232Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1048156779,"revision":20044,"compact-revision":17573} {"level":"info","ts":"2026-04-14T06:28:51.930165Z","caller":"traceutil/trace.go:171","msg":"trace[785325576] transaction","detail":"{read_only:false; response_revision:23607; number_of_response:1; }","duration":"137.322904ms","start":"2026-04-14T06:28:51.792817Z","end":"2026-04-14T06:28:51.93014Z","steps":["trace[785325576] 'process raft request' (duration: 137.152721ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:29:30.602024Z","caller":"traceutil/trace.go:171","msg":"trace[2032352146] transaction","detail":"{read_only:false; response_revision:23964; number_of_response:1; }","duration":"130.076088ms","start":"2026-04-14T06:29:30.471922Z","end":"2026-04-14T06:29:30.601998Z","steps":["trace[2032352146] 'process raft request' (duration: 129.919635ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:47.678489Z","caller":"traceutil/trace.go:171","msg":"trace[1442078126] transaction","detail":"{read_only:false; response_revision:24750; number_of_response:1; }","duration":"131.6916ms","start":"2026-04-14T06:30:47.546751Z","end":"2026-04-14T06:30:47.678442Z","steps":["trace[1442078126] 'process raft request' (duration: 131.403244ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:47.933062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.711209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T06:30:47.93341Z","caller":"traceutil/trace.go:171","msg":"trace[1866324687] transaction","detail":"{read_only:false; response_revision:24751; number_of_response:1; }","duration":"249.678834ms","start":"2026-04-14T06:30:47.683644Z","end":"2026-04-14T06:30:47.933323Z","steps":["trace[1866324687] 'process raft request' (duration: 124.613138ms)","trace[1866324687] 'compare' (duration: 124.534626ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:30:48.118366Z","caller":"traceutil/trace.go:171","msg":"trace[615597420] transaction","detail":"{read_only:false; response_revision:24752; number_of_response:1; }","duration":"111.153526ms","start":"2026-04-14T06:30:48.007168Z","end":"2026-04-14T06:30:48.118321Z","steps":["trace[615597420] 'process raft request' (duration: 63.992237ms)","trace[615597420] 'compare' (duration: 46.440984ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:48.371141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.041637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T06:30:48.371522Z","caller":"traceutil/trace.go:171","msg":"trace[706250399] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:24755; }","duration":"130.494976ms","start":"2026-04-14T06:30:48.240994Z","end":"2026-04-14T06:30:48.371489Z","steps":["trace[706250399] 'count revisions from in-memory index tree' (duration: 129.923514ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:48.606076Z","caller":"traceutil/trace.go:171","msg":"trace[192142455] linearizableReadLoop","detail":"{readStateIndex:25567; appliedIndex:25566; }","duration":"113.760308ms","start":"2026-04-14T06:30:48.492285Z","end":"2026-04-14T06:30:48.606045Z","steps":["trace[192142455] 'read index received' (duration: 113.576465ms)","trace[192142455] 'applied index is now lower than readState.Index' (duration: 182.313µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:30:48.606162Z","caller":"traceutil/trace.go:171","msg":"trace[419563785] transaction","detail":"{read_only:false; response_revision:24756; number_of_response:1; }","duration":"337.271566ms","start":"2026-04-14T06:30:48.268815Z","end":"2026-04-14T06:30:48.606087Z","steps":["trace[419563785] 'process raft request' (duration: 337.055942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:48.606336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:48.268768Z","time spent":"337.47724ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:30:48.606405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.083295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-14T06:30:48.606512Z","caller":"traceutil/trace.go:171","msg":"trace[1900332377] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24756; }","duration":"114.263109ms","start":"2026-04-14T06:30:48.492219Z","end":"2026-04-14T06:30:48.606482Z","steps":["trace[1900332377] 'agreement among raft nodes before linearized reading' (duration: 113.904962ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:48.622476Z","caller":"traceutil/trace.go:171","msg":"trace[850105805] transaction","detail":"{read_only:false; response_revision:24757; number_of_response:1; }","duration":"109.820079ms","start":"2026-04-14T06:30:48.512629Z","end":"2026-04-14T06:30:48.622449Z","steps":["trace[850105805] 'process raft request' (duration: 109.462382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:48.96457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.174096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:30:48.964702Z","caller":"traceutil/trace.go:171","msg":"trace[467813884] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24759; }","duration":"110.36579ms","start":"2026-04-14T06:30:48.854312Z","end":"2026-04-14T06:30:48.964678Z","steps":["trace[467813884] 'count revisions from in-memory index tree' (duration: 110.101405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:48.965085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.428043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-04-14T06:30:48.965174Z","caller":"traceutil/trace.go:171","msg":"trace[327210893] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24759; }","duration":"204.563046ms","start":"2026-04-14T06:30:48.760587Z","end":"2026-04-14T06:30:48.96515Z","steps":["trace[327210893] 'range keys from in-memory index tree' (duration: 204.230188ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:49.080645Z","caller":"traceutil/trace.go:171","msg":"trace[1919858954] linearizableReadLoop","detail":"{readStateIndex:25571; appliedIndex:25570; }","duration":"107.428893ms","start":"2026-04-14T06:30:48.973189Z","end":"2026-04-14T06:30:49.080618Z","steps":["trace[1919858954] 'read index received' (duration: 107.087436ms)","trace[1919858954] 'applied index is now lower than readState.Index' (duration: 340.247µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:30:49.080739Z","caller":"traceutil/trace.go:171","msg":"trace[1732453176] transaction","detail":"{read_only:false; response_revision:24760; number_of_response:1; }","duration":"109.973253ms","start":"2026-04-14T06:30:48.970716Z","end":"2026-04-14T06:30:49.08069Z","steps":["trace[1732453176] 'process raft request' (duration: 109.587296ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:49.081056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.748199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-04-14T06:30:49.081107Z","caller":"traceutil/trace.go:171","msg":"trace[269778629] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:24760; }","duration":"107.932722ms","start":"2026-04-14T06:30:48.973163Z","end":"2026-04-14T06:30:49.081096Z","steps":["trace[269778629] 'agreement among raft nodes before linearized reading' (duration: 107.656517ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:49.325812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.876565ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T06:30:49.326032Z","caller":"traceutil/trace.go:171","msg":"trace[159992754] transaction","detail":"{read_only:false; response_revision:24762; number_of_response:1; }","duration":"133.1982ms","start":"2026-04-14T06:30:49.192816Z","end":"2026-04-14T06:30:49.326014Z","steps":["trace[159992754] 'process raft request' (duration: 133.108598ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:49.326093Z","caller":"traceutil/trace.go:171","msg":"trace[988705299] transaction","detail":"{read_only:false; response_revision:24761; number_of_response:1; }","duration":"305.745872ms","start":"2026-04-14T06:30:49.020311Z","end":"2026-04-14T06:30:49.326057Z","steps":["trace[988705299] 'process raft request' (duration: 161.52856ms)","trace[988705299] 'compare' (duration: 143.709742ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:49.326231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:49.020282Z","time spent":"305.880945ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:30:49.326261Z","caller":"traceutil/trace.go:171","msg":"trace[775864946] linearizableReadLoop","detail":"{readStateIndex:25572; appliedIndex:25571; }","duration":"245.511009ms","start":"2026-04-14T06:30:49.080739Z","end":"2026-04-14T06:30:49.32625Z","steps":["trace[775864946] 'read index received' (duration: 100.955432ms)","trace[775864946] 'applied index is now lower than readState.Index' (duration: 144.554307ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:49.326582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.998057ms","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-04-14T06:30:49.326617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.379941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129180"} {"level":"info","ts":"2026-04-14T06:30:49.326679Z","caller":"traceutil/trace.go:171","msg":"trace[841963359] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:24762; }","duration":"350.453942ms","start":"2026-04-14T06:30:48.976207Z","end":"2026-04-14T06:30:49.326661Z","steps":["trace[841963359] 'agreement among raft nodes before linearized reading' (duration: 350.122095ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:49.326675Z","caller":"traceutil/trace.go:171","msg":"trace[1096959973] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24762; }","duration":"304.122349ms","start":"2026-04-14T06:30:49.02253Z","end":"2026-04-14T06:30:49.326652Z","steps":["trace[1096959973] 'agreement among raft nodes before linearized reading' (duration: 303.769521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:49.326708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:48.9762Z","time spent":"350.500863ms","remote":"127.0.0.1:45922","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":129204,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-04-14T06:30:49.326722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:49.022519Z","time spent":"304.19117ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-14T06:30:49.339563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.188781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:30:49.33964Z","caller":"traceutil/trace.go:171","msg":"trace[635390585] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:24763; }","duration":"225.314643ms","start":"2026-04-14T06:30:49.11431Z","end":"2026-04-14T06:30:49.339625Z","steps":["trace[635390585] 'agreement among raft nodes before linearized reading' (duration: 225.128329ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:49.339768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.255261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-14T06:30:49.340201Z","caller":"traceutil/trace.go:171","msg":"trace[591402428] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:24763; }","duration":"126.768161ms","start":"2026-04-14T06:30:49.213408Z","end":"2026-04-14T06:30:49.340176Z","steps":["trace[591402428] 'agreement among raft nodes before linearized reading' (duration: 126.089147ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:49.589583Z","caller":"traceutil/trace.go:171","msg":"trace[1404564601] transaction","detail":"{read_only:false; response_revision:24764; number_of_response:1; }","duration":"257.467659ms","start":"2026-04-14T06:30:49.332087Z","end":"2026-04-14T06:30:49.589555Z","steps":["trace[1404564601] 'process raft request' (duration: 249.584282ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:49.589751Z","caller":"traceutil/trace.go:171","msg":"trace[1370328807] linearizableReadLoop","detail":"{readStateIndex:25576; appliedIndex:25574; }","duration":"112.154317ms","start":"2026-04-14T06:30:49.47756Z","end":"2026-04-14T06:30:49.589714Z","steps":["trace[1370328807] 'read index received' (duration: 103.915522ms)","trace[1370328807] 'applied index is now lower than readState.Index' (duration: 8.237175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:49.589955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.362411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T06:30:49.589998Z","caller":"traceutil/trace.go:171","msg":"trace[1870058687] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24765; }","duration":"112.478544ms","start":"2026-04-14T06:30:49.477504Z","end":"2026-04-14T06:30:49.589983Z","steps":["trace[1870058687] 'agreement among raft nodes before linearized reading' (duration: 112.339731ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:49.590122Z","caller":"traceutil/trace.go:171","msg":"trace[900246257] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"161.050781ms","start":"2026-04-14T06:30:49.429044Z","end":"2026-04-14T06:30:49.590095Z","steps":["trace[900246257] 'process raft request' (duration: 160.405247ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:55.875859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.863128ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T06:30:55.875925Z","caller":"traceutil/trace.go:171","msg":"trace[152030206] linearizableReadLoop","detail":"{readStateIndex:25620; appliedIndex:25619; }","duration":"201.378561ms","start":"2026-04-14T06:30:55.674536Z","end":"2026-04-14T06:30:55.875914Z","steps":["trace[152030206] 'read index received' (duration: 30.393991ms)","trace[152030206] 'applied index is now lower than readState.Index' (duration: 170.98388ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:30:55.876257Z","caller":"traceutil/trace.go:171","msg":"trace[1401051549] transaction","detail":"{read_only:false; response_revision:24808; number_of_response:1; }","duration":"232.298744ms","start":"2026-04-14T06:30:55.643943Z","end":"2026-04-14T06:30:55.876242Z","steps":["trace[1401051549] 'process raft request' (duration: 60.998386ms)","trace[1401051549] 'compare' (duration: 170.571433ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:55.876287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.728559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumes/\" range_end:\"/registry/openstack.k-orc.cloud/volumes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:30:55.87636Z","caller":"traceutil/trace.go:171","msg":"trace[14242050] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumes/; range_end:/registry/openstack.k-orc.cloud/volumes0; response_count:0; response_revision:24808; }","duration":"201.849942ms","start":"2026-04-14T06:30:55.67449Z","end":"2026-04-14T06:30:55.87634Z","steps":["trace[14242050] 'agreement among raft nodes before linearized reading' (duration: 201.705759ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:55.941918Z","caller":"traceutil/trace.go:171","msg":"trace[1139076512] transaction","detail":"{read_only:false; response_revision:24809; number_of_response:1; }","duration":"234.268834ms","start":"2026-04-14T06:30:55.707627Z","end":"2026-04-14T06:30:55.941895Z","steps":["trace[1139076512] 'process raft request' (duration: 233.954698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:56.283259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.64932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-14T06:30:56.283327Z","caller":"traceutil/trace.go:171","msg":"trace[231510703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24809; }","duration":"305.748632ms","start":"2026-04-14T06:30:55.977564Z","end":"2026-04-14T06:30:56.283312Z","steps":["trace[231510703] 'range keys from in-memory index tree' (duration: 305.534637ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:56.283361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:55.977552Z","time spent":"305.801553ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":464,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-14T06:30:56.29479Z","caller":"traceutil/trace.go:171","msg":"trace[520069444] linearizableReadLoop","detail":"{readStateIndex:25622; appliedIndex:25621; }","duration":"135.326563ms","start":"2026-04-14T06:30:56.15945Z","end":"2026-04-14T06:30:56.294777Z","steps":["trace[520069444] 'read index received' (duration: 135.17038ms)","trace[520069444] 'applied index is now lower than readState.Index' (duration: 155.663µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:56.295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.492086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-14T06:30:56.295024Z","caller":"traceutil/trace.go:171","msg":"trace[544259613] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24810; }","duration":"135.615578ms","start":"2026-04-14T06:30:56.159402Z","end":"2026-04-14T06:30:56.295017Z","steps":["trace[544259613] 'agreement among raft nodes before linearized reading' (duration: 135.442735ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:56.294852Z","caller":"traceutil/trace.go:171","msg":"trace[43696108] transaction","detail":"{read_only:false; response_revision:24810; number_of_response:1; }","duration":"141.960847ms","start":"2026-04-14T06:30:56.152866Z","end":"2026-04-14T06:30:56.294827Z","steps":["trace[43696108] 'process raft request' (duration: 141.811494ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:56.70992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.037971ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-04-14T06:30:56.710158Z","caller":"traceutil/trace.go:171","msg":"trace[1066147817] linearizableReadLoop","detail":"{readStateIndex:25624; appliedIndex:25622; }","duration":"415.315396ms","start":"2026-04-14T06:30:56.294828Z","end":"2026-04-14T06:30:56.710144Z","steps":["trace[1066147817] 'read index received' (duration: 160.098071ms)","trace[1066147817] 'applied index is now lower than readState.Index' (duration: 255.216535ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:30:56.710188Z","caller":"traceutil/trace.go:171","msg":"trace[591744799] transaction","detail":"{read_only:false; response_revision:24811; number_of_response:1; }","duration":"421.239015ms","start":"2026-04-14T06:30:56.288914Z","end":"2026-04-14T06:30:56.710153Z","steps":["trace[591744799] 'process raft request' (duration: 421.081862ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:56.710295Z","caller":"traceutil/trace.go:171","msg":"trace[1872680711] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"410.990459ms","start":"2026-04-14T06:30:56.299296Z","end":"2026-04-14T06:30:56.710287Z","steps":["trace[1872680711] 'process raft request' (duration: 410.811316ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:56.710309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:56.288895Z","time spent":"421.350967ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:30:56.710367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:56.299276Z","time spent":"411.04386ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:30:56.710472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.150068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-14T06:30:56.71051Z","caller":"traceutil/trace.go:171","msg":"trace[1700625432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24812; }","duration":"453.207398ms","start":"2026-04-14T06:30:56.257293Z","end":"2026-04-14T06:30:56.710501Z","steps":["trace[1700625432] 'agreement among raft nodes before linearized reading' (duration: 452.872452ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:56.71053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:56.257265Z","time spent":"453.25981ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T06:30:56.710702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.388102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-14T06:30:56.710737Z","caller":"traceutil/trace.go:171","msg":"trace[265788548] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24813; }","duration":"453.447303ms","start":"2026-04-14T06:30:56.257279Z","end":"2026-04-14T06:30:56.710726Z","steps":["trace[265788548] 'agreement among raft nodes before linearized reading' (duration: 452.926102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:56.710773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:56.257264Z","time spent":"453.498834ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-14T06:30:56.711008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.425476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-04-14T06:30:56.71104Z","caller":"traceutil/trace.go:171","msg":"trace[895208091] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:24814; }","duration":"313.493408ms","start":"2026-04-14T06:30:56.397538Z","end":"2026-04-14T06:30:56.711031Z","steps":["trace[895208091] 'agreement among raft nodes before linearized reading' (duration: 313.316093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:56.711065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:56.397515Z","time spent":"313.543259ms","remote":"127.0.0.1:45998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1658,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"warn","ts":"2026-04-14T06:30:56.711063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.900262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:30:56.711108Z","caller":"traceutil/trace.go:171","msg":"trace[1413663533] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24814; }","duration":"114.960453ms","start":"2026-04-14T06:30:56.596136Z","end":"2026-04-14T06:30:56.711097Z","steps":["trace[1413663533] 'agreement among raft nodes before linearized reading' (duration: 114.894252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:56.711103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.285384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:30:56.711173Z","caller":"traceutil/trace.go:171","msg":"trace[138490311] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:24814; }","duration":"147.395636ms","start":"2026-04-14T06:30:56.563761Z","end":"2026-04-14T06:30:56.711156Z","steps":["trace[138490311] 'agreement among raft nodes before linearized reading' (duration: 147.290994ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:57.020136Z","caller":"traceutil/trace.go:171","msg":"trace[1770606702] transaction","detail":"{read_only:false; response_revision:24815; number_of_response:1; }","duration":"304.287924ms","start":"2026-04-14T06:30:56.715821Z","end":"2026-04-14T06:30:57.020109Z","steps":["trace[1770606702] 'process raft request' (duration: 275.644957ms)","trace[1770606702] 'compare' (duration: 28.525784ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:57.020267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:56.715808Z","time spent":"304.393155ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:30:57.03404Z","caller":"traceutil/trace.go:171","msg":"trace[350787804] linearizableReadLoop","detail":"{readStateIndex:25630; appliedIndex:25627; }","duration":"147.259044ms","start":"2026-04-14T06:30:56.886763Z","end":"2026-04-14T06:30:57.034023Z","steps":["trace[350787804] 'read index received' (duration: 104.687757ms)","trace[350787804] 'applied index is now lower than readState.Index' (duration: 42.570446ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:30:57.034107Z","caller":"traceutil/trace.go:171","msg":"trace[1199393524] transaction","detail":"{read_only:false; response_revision:24816; number_of_response:1; }","duration":"317.782164ms","start":"2026-04-14T06:30:56.716284Z","end":"2026-04-14T06:30:57.034066Z","steps":["trace[1199393524] 'process raft request' (duration: 317.598011ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:57.034129Z","caller":"traceutil/trace.go:171","msg":"trace[702605744] transaction","detail":"{read_only:false; response_revision:24817; number_of_response:1; }","duration":"317.539899ms","start":"2026-04-14T06:30:56.716556Z","end":"2026-04-14T06:30:57.034096Z","steps":["trace[702605744] 'process raft request' (duration: 317.435187ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:57.034158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.387326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T06:30:57.034275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:56.716272Z","time spent":"317.917918ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:30:57.034297Z","caller":"traceutil/trace.go:171","msg":"trace[1868011092] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24817; }","duration":"147.5627ms","start":"2026-04-14T06:30:56.886713Z","end":"2026-04-14T06:30:57.034276Z","steps":["trace[1868011092] 'agreement among raft nodes before linearized reading' (duration: 147.401156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:57.034319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:56.716548Z","time spent":"317.690512ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:30:57.332834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.322019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:30:57.332958Z","caller":"traceutil/trace.go:171","msg":"trace[887214154] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:24817; }","duration":"192.550464ms","start":"2026-04-14T06:30:57.140381Z","end":"2026-04-14T06:30:57.332931Z","steps":["trace[887214154] 'count revisions from in-memory index tree' (duration: 192.206986ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:57.495968Z","caller":"traceutil/trace.go:171","msg":"trace[2051635419] transaction","detail":"{read_only:false; response_revision:24818; number_of_response:1; }","duration":"115.617557ms","start":"2026-04-14T06:30:57.380325Z","end":"2026-04-14T06:30:57.495943Z","steps":["trace[2051635419] 'process raft request' (duration: 115.441964ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:57.652869Z","caller":"traceutil/trace.go:171","msg":"trace[205235046] transaction","detail":"{read_only:false; response_revision:24819; number_of_response:1; }","duration":"271.792728ms","start":"2026-04-14T06:30:57.381048Z","end":"2026-04-14T06:30:57.652841Z","steps":["trace[205235046] 'process raft request' (duration: 269.714366ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:57.958032Z","caller":"traceutil/trace.go:171","msg":"trace[1044344013] transaction","detail":"{read_only:false; response_revision:24820; number_of_response:1; }","duration":"312.097781ms","start":"2026-04-14T06:30:57.645909Z","end":"2026-04-14T06:30:57.958007Z","steps":["trace[1044344013] 'process raft request' (duration: 293.507906ms)","trace[1044344013] 'compare' (duration: 18.274629ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:57.958162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:57.645874Z","time spent":"312.220443ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:30:58.258123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.444738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T06:30:58.25829Z","caller":"traceutil/trace.go:171","msg":"trace[852525769] transaction","detail":"{read_only:false; response_revision:24822; number_of_response:1; }","duration":"295.376084ms","start":"2026-04-14T06:30:57.962886Z","end":"2026-04-14T06:30:58.258262Z","steps":["trace[852525769] 'process raft request' (duration: 139.201491ms)","trace[852525769] 'compare' (duration: 155.113861ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:58.595837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.552812ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T06:30:58.595938Z","caller":"traceutil/trace.go:171","msg":"trace[1856564576] transaction","detail":"{read_only:false; response_revision:24823; number_of_response:1; }","duration":"293.678709ms","start":"2026-04-14T06:30:58.30224Z","end":"2026-04-14T06:30:58.595919Z","steps":["trace[1856564576] 'process raft request' (duration: 117.971194ms)","trace[1856564576] 'compare' (duration: 174.409479ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:58.946332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.805907ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T06:30:58.946652Z","caller":"traceutil/trace.go:171","msg":"trace[798827372] linearizableReadLoop","detail":"{readStateIndex:25638; appliedIndex:25636; }","duration":"226.380265ms","start":"2026-04-14T06:30:58.720252Z","end":"2026-04-14T06:30:58.946632Z","steps":["trace[798827372] 'read index received' (duration: 54.21485ms)","trace[798827372] 'applied index is now lower than readState.Index' (duration: 172.164065ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:30:58.946717Z","caller":"traceutil/trace.go:171","msg":"trace[583291243] transaction","detail":"{read_only:false; response_revision:24825; number_of_response:1; }","duration":"230.250953ms","start":"2026-04-14T06:30:58.716443Z","end":"2026-04-14T06:30:58.946693Z","steps":["trace[583291243] 'process raft request' (duration: 229.998948ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:58.946771Z","caller":"traceutil/trace.go:171","msg":"trace[334253954] transaction","detail":"{read_only:false; response_revision:24826; number_of_response:1; }","duration":"226.432047ms","start":"2026-04-14T06:30:58.720324Z","end":"2026-04-14T06:30:58.946756Z","steps":["trace[334253954] 'process raft request' (duration: 226.225052ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:58.94675Z","caller":"traceutil/trace.go:171","msg":"trace[422449239] transaction","detail":"{read_only:false; response_revision:24824; number_of_response:1; }","duration":"255.297998ms","start":"2026-04-14T06:30:58.691389Z","end":"2026-04-14T06:30:58.946687Z","steps":["trace[422449239] 'process raft request' (duration: 83.061192ms)","trace[422449239] 'compare' (duration: 170.810057ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:58.946951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.668211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-14T06:30:58.947003Z","caller":"traceutil/trace.go:171","msg":"trace[1185278019] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24826; }","duration":"226.771213ms","start":"2026-04-14T06:30:58.720218Z","end":"2026-04-14T06:30:58.946989Z","steps":["trace[1185278019] 'agreement among raft nodes before linearized reading' (duration: 226.535769ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:58.947049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.638221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-14T06:30:58.94713Z","caller":"traceutil/trace.go:171","msg":"trace[288421597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24826; }","duration":"226.747153ms","start":"2026-04-14T06:30:58.720365Z","end":"2026-04-14T06:30:58.947112Z","steps":["trace[288421597] 'agreement among raft nodes before linearized reading' (duration: 226.411296ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:59.168792Z","caller":"traceutil/trace.go:171","msg":"trace[891842364] linearizableReadLoop","detail":"{readStateIndex:25642; appliedIndex:25641; }","duration":"130.912585ms","start":"2026-04-14T06:30:59.037852Z","end":"2026-04-14T06:30:59.168765Z","steps":["trace[891842364] 'read index received' (duration: 34.943144ms)","trace[891842364] 'applied index is now lower than readState.Index' (duration: 95.96812ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:30:59.168943Z","caller":"traceutil/trace.go:171","msg":"trace[1326480160] transaction","detail":"{read_only:false; response_revision:24829; number_of_response:1; }","duration":"140.566979ms","start":"2026-04-14T06:30:59.02836Z","end":"2026-04-14T06:30:59.168927Z","steps":["trace[1326480160] 'process raft request' (duration: 44.556557ms)","trace[1326480160] 'compare' (duration: 95.716996ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:59.169255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.626629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-14T06:30:59.169338Z","caller":"traceutil/trace.go:171","msg":"trace[1840675397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24829; }","duration":"130.734521ms","start":"2026-04-14T06:30:59.038584Z","end":"2026-04-14T06:30:59.169318Z","steps":["trace[1840675397] 'agreement among raft nodes before linearized reading' (duration: 130.405435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:59.169714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.845073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-14T06:30:59.169761Z","caller":"traceutil/trace.go:171","msg":"trace[5191577] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24829; }","duration":"131.938525ms","start":"2026-04-14T06:30:59.037811Z","end":"2026-04-14T06:30:59.16975Z","steps":["trace[5191577] 'agreement among raft nodes before linearized reading' (duration: 131.819753ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:59.509836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.499332ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T06:30:59.509932Z","caller":"traceutil/trace.go:171","msg":"trace[422430656] linearizableReadLoop","detail":"{readStateIndex:25643; appliedIndex:25642; }","duration":"334.147224ms","start":"2026-04-14T06:30:59.175769Z","end":"2026-04-14T06:30:59.509916Z","steps":["trace[422430656] 'read index received' (duration: 146.56857ms)","trace[422430656] 'applied index is now lower than readState.Index' (duration: 187.576794ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:30:59.510155Z","caller":"traceutil/trace.go:171","msg":"trace[1846067912] transaction","detail":"{read_only:false; response_revision:24830; number_of_response:1; }","duration":"335.903149ms","start":"2026-04-14T06:30:59.174236Z","end":"2026-04-14T06:30:59.510139Z","steps":["trace[1846067912] 'process raft request' (duration: 148.06922ms)","trace[1846067912] 'compare' (duration: 186.295508ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:59.51023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:59.174212Z","time spent":"335.97743ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:30:59.510473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.682115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:30:59.510514Z","caller":"traceutil/trace.go:171","msg":"trace[656662174] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24830; }","duration":"334.792777ms","start":"2026-04-14T06:30:59.175709Z","end":"2026-04-14T06:30:59.510501Z","steps":["trace[656662174] 'agreement among raft nodes before linearized reading' (duration: 334.698656ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:59.510545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:59.175684Z","time spent":"334.851608ms","remote":"127.0.0.1:41734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":30,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:30:59.510799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.029832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:215"} {"level":"info","ts":"2026-04-14T06:30:59.510835Z","caller":"traceutil/trace.go:171","msg":"trace[1840050125] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:24830; }","duration":"335.091353ms","start":"2026-04-14T06:30:59.175735Z","end":"2026-04-14T06:30:59.510826Z","steps":["trace[1840050125] 'agreement among raft nodes before linearized reading' (duration: 334.96409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:59.510861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:59.175724Z","time spent":"335.130873ms","remote":"127.0.0.1:46054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"info","ts":"2026-04-14T06:30:59.717035Z","caller":"traceutil/trace.go:171","msg":"trace[678879354] transaction","detail":"{read_only:false; response_revision:24831; number_of_response:1; }","duration":"540.380353ms","start":"2026-04-14T06:30:59.176625Z","end":"2026-04-14T06:30:59.717006Z","steps":["trace[678879354] 'process raft request' (duration: 469.824783ms)","trace[678879354] 'compare' (duration: 70.255593ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:59.717216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:59.176603Z","time spent":"540.522006ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:30:59.717594Z","caller":"traceutil/trace.go:171","msg":"trace[1926733259] linearizableReadLoop","detail":"{readStateIndex:25645; appliedIndex:25643; }","duration":"207.492815ms","start":"2026-04-14T06:30:59.510081Z","end":"2026-04-14T06:30:59.717574Z","steps":["trace[1926733259] 'read index received' (duration: 136.383294ms)","trace[1926733259] 'applied index is now lower than readState.Index' (duration: 71.108551ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:30:59.717641Z","caller":"traceutil/trace.go:171","msg":"trace[2043014853] transaction","detail":"{read_only:false; response_revision:24833; number_of_response:1; }","duration":"112.358011ms","start":"2026-04-14T06:30:59.605256Z","end":"2026-04-14T06:30:59.717614Z","steps":["trace[2043014853] 'process raft request' (duration: 112.194078ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:59.717676Z","caller":"traceutil/trace.go:171","msg":"trace[1437099465] transaction","detail":"{read_only:false; response_revision:24832; number_of_response:1; }","duration":"215.874514ms","start":"2026-04-14T06:30:59.501779Z","end":"2026-04-14T06:30:59.717654Z","steps":["trace[1437099465] 'process raft request' (duration: 215.520757ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:59.717768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.75263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbuckettopics/\" range_end:\"/registry/ceph.rook.io/cephbuckettopics0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:30:59.717825Z","caller":"traceutil/trace.go:171","msg":"trace[2121054945] range","detail":"{range_begin:/registry/ceph.rook.io/cephbuckettopics/; range_end:/registry/ceph.rook.io/cephbuckettopics0; response_count:0; response_revision:24834; }","duration":"283.845691ms","start":"2026-04-14T06:30:59.433964Z","end":"2026-04-14T06:30:59.71781Z","steps":["trace[2121054945] 'agreement among raft nodes before linearized reading' (duration: 283.720018ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:59.71783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.033327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:217"} {"level":"info","ts":"2026-04-14T06:30:59.717893Z","caller":"traceutil/trace.go:171","msg":"trace[759893312] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:24834; }","duration":"542.129159ms","start":"2026-04-14T06:30:59.17575Z","end":"2026-04-14T06:30:59.717879Z","steps":["trace[759893312] 'agreement among raft nodes before linearized reading' (duration: 541.936725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:30:59.717942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:30:59.175733Z","time spent":"542.19729ms","remote":"127.0.0.1:46054","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"info","ts":"2026-04-14T06:30:59.948746Z","caller":"traceutil/trace.go:171","msg":"trace[834125759] linearizableReadLoop","detail":"{readStateIndex:25649; appliedIndex:25647; }","duration":"231.046338ms","start":"2026-04-14T06:30:59.717653Z","end":"2026-04-14T06:30:59.948699Z","steps":["trace[834125759] 'read index received' (duration: 162.687973ms)","trace[834125759] 'applied index is now lower than readState.Index' (duration: 68.357045ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:30:59.948814Z","caller":"traceutil/trace.go:171","msg":"trace[1963478688] transaction","detail":"{read_only:false; response_revision:24835; number_of_response:1; }","duration":"296.522507ms","start":"2026-04-14T06:30:59.652255Z","end":"2026-04-14T06:30:59.948777Z","steps":["trace[1963478688] 'process raft request' (duration: 228.182422ms)","trace[1963478688] 'compare' (duration: 67.987768ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:30:59.948937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.462262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:30:59.948903Z","caller":"traceutil/trace.go:171","msg":"trace[824486133] transaction","detail":"{read_only:false; response_revision:24836; number_of_response:1; }","duration":"292.559436ms","start":"2026-04-14T06:30:59.656285Z","end":"2026-04-14T06:30:59.948845Z","steps":["trace[824486133] 'process raft request' (duration: 292.334092ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:30:59.94907Z","caller":"traceutil/trace.go:171","msg":"trace[1839397947] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24836; }","duration":"271.576725ms","start":"2026-04-14T06:30:59.677409Z","end":"2026-04-14T06:30:59.948985Z","steps":["trace[1839397947] 'agreement among raft nodes before linearized reading' (duration: 271.461852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:00.336079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.501617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectrealms/\" range_end:\"/registry/ceph.rook.io/cephobjectrealms0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T06:31:00.336158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.666895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:31:00.336164Z","caller":"traceutil/trace.go:171","msg":"trace[964101970] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:24838; }","duration":"136.618789ms","start":"2026-04-14T06:31:00.199525Z","end":"2026-04-14T06:31:00.336144Z","steps":["trace[964101970] 'count revisions from in-memory index tree' (duration: 136.447976ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:00.336215Z","caller":"traceutil/trace.go:171","msg":"trace[1697877479] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:24838; }","duration":"172.753677ms","start":"2026-04-14T06:31:00.163433Z","end":"2026-04-14T06:31:00.336187Z","steps":["trace[1697877479] 'count revisions from in-memory index tree' (duration: 172.552432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:00.33608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.321555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-14T06:31:00.336788Z","caller":"traceutil/trace.go:171","msg":"trace[230564519] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24838; }","duration":"145.090549ms","start":"2026-04-14T06:31:00.191688Z","end":"2026-04-14T06:31:00.336778Z","steps":["trace[230564519] 'range keys from in-memory index tree' (duration: 144.11092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:00.807187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.194808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:31:00.807272Z","caller":"traceutil/trace.go:171","msg":"trace[624499697] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24839; }","duration":"364.28544ms","start":"2026-04-14T06:31:00.442964Z","end":"2026-04-14T06:31:00.80725Z","steps":["trace[624499697] 'range keys from in-memory index tree' (duration: 364.093346ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:00.807312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:00.442883Z","time spent":"364.419003ms","remote":"127.0.0.1:44464","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":30,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-14T06:31:00.807929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.404776ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T06:31:00.808128Z","caller":"traceutil/trace.go:171","msg":"trace[165764242] transaction","detail":"{read_only:false; response_revision:24840; number_of_response:1; }","duration":"206.498175ms","start":"2026-04-14T06:31:00.601589Z","end":"2026-04-14T06:31:00.808087Z","steps":["trace[165764242] 'process raft request' (duration: 59.859045ms)","trace[165764242] 'compare' (duration: 145.977587ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:00.989043Z","caller":"traceutil/trace.go:171","msg":"trace[1047953871] transaction","detail":"{read_only:false; response_revision:24841; number_of_response:1; }","duration":"148.742422ms","start":"2026-04-14T06:31:00.840274Z","end":"2026-04-14T06:31:00.989017Z","steps":["trace[1047953871] 'process raft request' (duration: 144.410985ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:00.989081Z","caller":"traceutil/trace.go:171","msg":"trace[2041051051] linearizableReadLoop","detail":"{readStateIndex:25655; appliedIndex:25654; }","duration":"147.411627ms","start":"2026-04-14T06:31:00.841522Z","end":"2026-04-14T06:31:00.988933Z","steps":["trace[2041051051] 'read index received' (duration: 143.220232ms)","trace[2041051051] 'applied index is now lower than readState.Index' (duration: 4.189805ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:31:00.989335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.794514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-14T06:31:00.989412Z","caller":"traceutil/trace.go:171","msg":"trace[638684430] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24841; }","duration":"147.909376ms","start":"2026-04-14T06:31:00.841483Z","end":"2026-04-14T06:31:00.989393Z","steps":["trace[638684430] 'agreement among raft nodes before linearized reading' (duration: 147.666091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:01.038233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.195772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:31:01.038316Z","caller":"traceutil/trace.go:171","msg":"trace[1010424655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24843; }","duration":"150.317414ms","start":"2026-04-14T06:31:00.887977Z","end":"2026-04-14T06:31:01.038295Z","steps":["trace[1010424655] 'agreement among raft nodes before linearized reading' (duration: 150.205362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:01.038286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.671997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:31:01.038374Z","caller":"traceutil/trace.go:171","msg":"trace[735422311] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:24843; }","duration":"116.803599ms","start":"2026-04-14T06:31:00.92155Z","end":"2026-04-14T06:31:01.038353Z","steps":["trace[735422311] 'agreement among raft nodes before linearized reading' (duration: 116.642526ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:01.163578Z","caller":"traceutil/trace.go:171","msg":"trace[1638937127] transaction","detail":"{read_only:false; response_revision:24844; number_of_response:1; }","duration":"119.307331ms","start":"2026-04-14T06:31:01.044247Z","end":"2026-04-14T06:31:01.163554Z","steps":["trace[1638937127] 'process raft request' (duration: 119.126147ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:01.193554Z","caller":"traceutil/trace.go:171","msg":"trace[154672837] linearizableReadLoop","detail":"{readStateIndex:25659; appliedIndex:25657; }","duration":"143.938787ms","start":"2026-04-14T06:31:01.049579Z","end":"2026-04-14T06:31:01.193518Z","steps":["trace[154672837] 'read index received' (duration: 113.838771ms)","trace[154672837] 'applied index is now lower than readState.Index' (duration: 30.098866ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:01.193897Z","caller":"traceutil/trace.go:171","msg":"trace[332618424] transaction","detail":"{read_only:false; response_revision:24845; number_of_response:1; }","duration":"146.019048ms","start":"2026-04-14T06:31:01.047864Z","end":"2026-04-14T06:31:01.193883Z","steps":["trace[332618424] 'process raft request' (duration: 145.502387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:01.194441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.841955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-04-14T06:31:01.194493Z","caller":"traceutil/trace.go:171","msg":"trace[2041819770] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24845; }","duration":"144.921916ms","start":"2026-04-14T06:31:01.049558Z","end":"2026-04-14T06:31:01.19448Z","steps":["trace[2041819770] 'agreement among raft nodes before linearized reading' (duration: 144.517768ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:01.722248Z","caller":"traceutil/trace.go:171","msg":"trace[1475284594] linearizableReadLoop","detail":"{readStateIndex:25661; appliedIndex:25660; }","duration":"203.309191ms","start":"2026-04-14T06:31:01.51891Z","end":"2026-04-14T06:31:01.722219Z","steps":["trace[1475284594] 'read index received' (duration: 203.219599ms)","trace[1475284594] 'applied index is now lower than readState.Index' (duration: 88.122µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:01.722297Z","caller":"traceutil/trace.go:171","msg":"trace[716623531] transaction","detail":"{read_only:false; response_revision:24847; number_of_response:1; }","duration":"278.158146ms","start":"2026-04-14T06:31:01.444104Z","end":"2026-04-14T06:31:01.722262Z","steps":["trace[716623531] 'process raft request' (duration: 277.924822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:01.72247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.542245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-14T06:31:01.722513Z","caller":"traceutil/trace.go:171","msg":"trace[1650483226] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24847; }","duration":"203.606856ms","start":"2026-04-14T06:31:01.518894Z","end":"2026-04-14T06:31:01.722501Z","steps":["trace[1650483226] 'agreement among raft nodes before linearized reading' (duration: 203.428623ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:01.96591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.828237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T06:31:01.966045Z","caller":"traceutil/trace.go:171","msg":"trace[178171831] transaction","detail":"{read_only:false; response_revision:24848; number_of_response:1; }","duration":"241.549291ms","start":"2026-04-14T06:31:01.724481Z","end":"2026-04-14T06:31:01.96603Z","steps":["trace[178171831] 'process raft request' (duration: 70.522629ms)","trace[178171831] 'compare' (duration: 170.718265ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:01.966328Z","caller":"traceutil/trace.go:171","msg":"trace[184842867] transaction","detail":"{read_only:false; response_revision:24850; number_of_response:1; }","duration":"239.302636ms","start":"2026-04-14T06:31:01.727012Z","end":"2026-04-14T06:31:01.966315Z","steps":["trace[184842867] 'process raft request' (duration: 239.191133ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:01.966317Z","caller":"traceutil/trace.go:171","msg":"trace[502959229] transaction","detail":"{read_only:false; response_revision:24849; number_of_response:1; }","duration":"239.980169ms","start":"2026-04-14T06:31:01.726311Z","end":"2026-04-14T06:31:01.966291Z","steps":["trace[502959229] 'process raft request' (duration: 239.689252ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:02.166739Z","caller":"traceutil/trace.go:171","msg":"trace[2040846855] transaction","detail":"{read_only:false; response_revision:24852; number_of_response:1; }","duration":"168.651574ms","start":"2026-04-14T06:31:01.99805Z","end":"2026-04-14T06:31:02.166702Z","steps":["trace[2040846855] 'process raft request' (duration: 168.44142ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:02.206691Z","caller":"traceutil/trace.go:171","msg":"trace[223461795] transaction","detail":"{read_only:false; response_revision:24853; number_of_response:1; }","duration":"198.800601ms","start":"2026-04-14T06:31:02.007863Z","end":"2026-04-14T06:31:02.206663Z","steps":["trace[223461795] 'process raft request' (duration: 198.643517ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:04.669059Z","caller":"traceutil/trace.go:171","msg":"trace[1114571247] transaction","detail":"{read_only:false; response_revision:24868; number_of_response:1; }","duration":"200.077476ms","start":"2026-04-14T06:31:04.468958Z","end":"2026-04-14T06:31:04.669035Z","steps":["trace[1114571247] 'process raft request' (duration: 199.952793ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:05.190769Z","caller":"traceutil/trace.go:171","msg":"trace[483016676] transaction","detail":"{read_only:false; response_revision:24869; number_of_response:1; }","duration":"466.479937ms","start":"2026-04-14T06:31:04.724266Z","end":"2026-04-14T06:31:05.190746Z","steps":["trace[483016676] 'process raft request' (duration: 466.248481ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:05.191227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:04.724247Z","time spent":"466.595598ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:31:05.191817Z","caller":"traceutil/trace.go:171","msg":"trace[822934437] linearizableReadLoop","detail":"{readStateIndex:25684; appliedIndex:25682; }","duration":"297.330792ms","start":"2026-04-14T06:31:04.894468Z","end":"2026-04-14T06:31:05.191799Z","steps":["trace[822934437] 'read index received' (duration: 296.109647ms)","trace[822934437] 'applied index is now lower than readState.Index' (duration: 1.219805ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:05.192005Z","caller":"traceutil/trace.go:171","msg":"trace[1077098961] transaction","detail":"{read_only:false; response_revision:24872; number_of_response:1; }","duration":"142.064429ms","start":"2026-04-14T06:31:05.049922Z","end":"2026-04-14T06:31:05.191987Z","steps":["trace[1077098961] 'process raft request' (duration: 141.844665ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:05.192149Z","caller":"traceutil/trace.go:171","msg":"trace[2096725557] transaction","detail":"{read_only:false; response_revision:24870; number_of_response:1; }","duration":"368.693469ms","start":"2026-04-14T06:31:04.823447Z","end":"2026-04-14T06:31:05.192141Z","steps":["trace[2096725557] 'process raft request' (duration: 368.172629ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:05.192238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:04.823431Z","time spent":"368.73901ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:31:05.192259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.780081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T06:31:05.192286Z","caller":"traceutil/trace.go:171","msg":"trace[492825069] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:24872; }","duration":"297.859453ms","start":"2026-04-14T06:31:04.894417Z","end":"2026-04-14T06:31:05.192277Z","steps":["trace[492825069] 'agreement among raft nodes before linearized reading' (duration: 297.780831ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:05.192547Z","caller":"traceutil/trace.go:171","msg":"trace[1705780693] transaction","detail":"{read_only:false; response_revision:24871; number_of_response:1; }","duration":"142.987777ms","start":"2026-04-14T06:31:05.049476Z","end":"2026-04-14T06:31:05.192464Z","steps":["trace[1705780693] 'process raft request' (duration: 142.257732ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:12.475599Z","caller":"traceutil/trace.go:171","msg":"trace[16659232] transaction","detail":"{read_only:false; response_revision:24983; number_of_response:1; }","duration":"273.815259ms","start":"2026-04-14T06:31:12.201758Z","end":"2026-04-14T06:31:12.475573Z","steps":["trace[16659232] 'process raft request' (duration: 273.624726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:12.659762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.935756ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T06:31:12.659959Z","caller":"traceutil/trace.go:171","msg":"trace[50408547] transaction","detail":"{read_only:false; response_revision:24984; number_of_response:1; }","duration":"415.303486ms","start":"2026-04-14T06:31:12.244599Z","end":"2026-04-14T06:31:12.659902Z","steps":["trace[50408547] 'process raft request' (duration: 272.131225ms)","trace[50408547] 'compare' (duration: 142.755762ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:31:12.660135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:12.244586Z","time spent":"415.45885ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:31:12.939269Z","caller":"traceutil/trace.go:171","msg":"trace[1904373288] linearizableReadLoop","detail":"{readStateIndex:25801; appliedIndex:25800; }","duration":"237.114642ms","start":"2026-04-14T06:31:12.702131Z","end":"2026-04-14T06:31:12.939246Z","steps":["trace[1904373288] 'read index received' (duration: 236.899676ms)","trace[1904373288] 'applied index is now lower than readState.Index' (duration: 214.026µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:12.939314Z","caller":"traceutil/trace.go:171","msg":"trace[220749805] transaction","detail":"{read_only:false; response_revision:24985; number_of_response:1; }","duration":"271.4049ms","start":"2026-04-14T06:31:12.667878Z","end":"2026-04-14T06:31:12.939283Z","steps":["trace[220749805] 'process raft request' (duration: 271.224456ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:12.939495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.353266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-14T06:31:12.939527Z","caller":"traceutil/trace.go:171","msg":"trace[873962078] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24985; }","duration":"237.437018ms","start":"2026-04-14T06:31:12.702082Z","end":"2026-04-14T06:31:12.939519Z","steps":["trace[873962078] 'agreement among raft nodes before linearized reading' (duration: 237.296075ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:13.152733Z","caller":"traceutil/trace.go:171","msg":"trace[54658861] linearizableReadLoop","detail":"{readStateIndex:25802; appliedIndex:25801; }","duration":"167.150974ms","start":"2026-04-14T06:31:12.985555Z","end":"2026-04-14T06:31:13.152706Z","steps":["trace[54658861] 'read index received' (duration: 71.303155ms)","trace[54658861] 'applied index is now lower than readState.Index' (duration: 95.846518ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:13.152826Z","caller":"traceutil/trace.go:171","msg":"trace[564268808] transaction","detail":"{read_only:false; response_revision:24986; number_of_response:1; }","duration":"207.601417ms","start":"2026-04-14T06:31:12.945185Z","end":"2026-04-14T06:31:13.152787Z","steps":["trace[564268808] 'process raft request' (duration: 111.718108ms)","trace[564268808] 'compare' (duration: 95.672835ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:31:13.152967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.691142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-14T06:31:13.152931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.315537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:31:13.153021Z","caller":"traceutil/trace.go:171","msg":"trace[51146926] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:24986; }","duration":"154.807484ms","start":"2026-04-14T06:31:12.998197Z","end":"2026-04-14T06:31:13.153004Z","steps":["trace[51146926] 'agreement among raft nodes before linearized reading' (duration: 154.664662ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:13.153051Z","caller":"traceutil/trace.go:171","msg":"trace[1104466346] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:24986; }","duration":"167.502551ms","start":"2026-04-14T06:31:12.98552Z","end":"2026-04-14T06:31:13.153022Z","steps":["trace[1104466346] 'agreement among raft nodes before linearized reading' (duration: 167.272596ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:13.427547Z","caller":"traceutil/trace.go:171","msg":"trace[168922880] transaction","detail":"{read_only:false; response_revision:24988; number_of_response:1; }","duration":"212.498046ms","start":"2026-04-14T06:31:13.215018Z","end":"2026-04-14T06:31:13.427516Z","steps":["trace[168922880] 'process raft request' (duration: 212.240991ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:13.42795Z","caller":"traceutil/trace.go:171","msg":"trace[519347776] linearizableReadLoop","detail":"{readStateIndex:25806; appliedIndex:25803; }","duration":"176.205346ms","start":"2026-04-14T06:31:13.251708Z","end":"2026-04-14T06:31:13.427913Z","steps":["trace[519347776] 'read index received' (duration: 175.521262ms)","trace[519347776] 'applied index is now lower than readState.Index' (duration: 682.774µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:13.427945Z","caller":"traceutil/trace.go:171","msg":"trace[1000850524] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"208.579387ms","start":"2026-04-14T06:31:13.219296Z","end":"2026-04-14T06:31:13.427875Z","steps":["trace[1000850524] 'process raft request' (duration: 208.157149ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:13.428039Z","caller":"traceutil/trace.go:171","msg":"trace[1420485251] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"193.408401ms","start":"2026-04-14T06:31:13.234617Z","end":"2026-04-14T06:31:13.428026Z","steps":["trace[1420485251] 'process raft request' (duration: 193.147776ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:13.428184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.891835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8edc41e9-bc7e-40af-87b4-2cd7ab97599e\" ","response":"range_response_count:1 size:3763"} {"level":"warn","ts":"2026-04-14T06:31:13.428237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.757965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-14T06:31:13.428255Z","caller":"traceutil/trace.go:171","msg":"trace[1164362965] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8edc41e9-bc7e-40af-87b4-2cd7ab97599e; range_end:; response_count:1; response_revision:24990; }","duration":"222.019197ms","start":"2026-04-14T06:31:13.206218Z","end":"2026-04-14T06:31:13.428237Z","steps":["trace[1164362965] 'agreement among raft nodes before linearized reading' (duration: 221.811303ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:13.428274Z","caller":"traceutil/trace.go:171","msg":"trace[1830232317] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24990; }","duration":"166.859417ms","start":"2026-04-14T06:31:13.261405Z","end":"2026-04-14T06:31:13.428264Z","steps":["trace[1830232317] 'agreement among raft nodes before linearized reading' (duration: 166.692563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:13.428297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.50161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18a6257132710977\" ","response":"range_response_count:1 size:736"} {"level":"info","ts":"2026-04-14T06:31:13.428326Z","caller":"traceutil/trace.go:171","msg":"trace[311346928] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18a6257132710977; range_end:; response_count:1; response_revision:24990; }","duration":"164.528901ms","start":"2026-04-14T06:31:13.263788Z","end":"2026-04-14T06:31:13.428317Z","steps":["trace[311346928] 'agreement among raft nodes before linearized reading' (duration: 164.442119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:13.428201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.392324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-04-14T06:31:13.428425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.27643ms","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-04-14T06:31:13.428471Z","caller":"traceutil/trace.go:171","msg":"trace[145063742] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24990; }","duration":"135.764972ms","start":"2026-04-14T06:31:13.292696Z","end":"2026-04-14T06:31:13.42846Z","steps":["trace[145063742] 'agreement among raft nodes before linearized reading' (duration: 135.424915ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:13.428479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.722194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1446"} {"level":"info","ts":"2026-04-14T06:31:13.428495Z","caller":"traceutil/trace.go:171","msg":"trace[1717951167] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24990; }","duration":"114.438373ms","start":"2026-04-14T06:31:13.314037Z","end":"2026-04-14T06:31:13.428475Z","steps":["trace[1717951167] 'agreement among raft nodes before linearized reading' (duration: 114.187138ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:13.428516Z","caller":"traceutil/trace.go:171","msg":"trace[201296916] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24990; }","duration":"164.753465ms","start":"2026-04-14T06:31:13.263749Z","end":"2026-04-14T06:31:13.428502Z","steps":["trace[201296916] 'agreement among raft nodes before linearized reading' (duration: 164.655953ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:13.556895Z","caller":"traceutil/trace.go:171","msg":"trace[1260682650] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"124.765521ms","start":"2026-04-14T06:31:13.4321Z","end":"2026-04-14T06:31:13.556866Z","steps":["trace[1260682650] 'process raft request' (duration: 124.032876ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:13.556994Z","caller":"traceutil/trace.go:171","msg":"trace[1597113285] transaction","detail":"{read_only:false; response_revision:24993; number_of_response:1; }","duration":"115.527495ms","start":"2026-04-14T06:31:13.441443Z","end":"2026-04-14T06:31:13.55697Z","steps":["trace[1597113285] 'process raft request' (duration: 115.421523ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:13.55706Z","caller":"traceutil/trace.go:171","msg":"trace[410768781] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"123.461684ms","start":"2026-04-14T06:31:13.433545Z","end":"2026-04-14T06:31:13.557007Z","steps":["trace[410768781] 'process raft request' (duration: 123.146558ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:13.557125Z","caller":"traceutil/trace.go:171","msg":"trace[243262328] linearizableReadLoop","detail":"{readStateIndex:25808; appliedIndex:25806; }","duration":"117.231269ms","start":"2026-04-14T06:31:13.439869Z","end":"2026-04-14T06:31:13.5571Z","steps":["trace[243262328] 'read index received' (duration: 116.203249ms)","trace[243262328] 'applied index is now lower than readState.Index' (duration: 1.02655ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:13.557148Z","caller":"traceutil/trace.go:171","msg":"trace[817879863] transaction","detail":"{read_only:false; response_revision:24994; number_of_response:1; }","duration":"115.457703ms","start":"2026-04-14T06:31:13.441645Z","end":"2026-04-14T06:31:13.557103Z","steps":["trace[817879863] 'process raft request' (duration: 115.28081ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:13.557319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.430333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9602"} {"level":"info","ts":"2026-04-14T06:31:13.55736Z","caller":"traceutil/trace.go:171","msg":"trace[1082753454] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24994; }","duration":"117.510724ms","start":"2026-04-14T06:31:13.439839Z","end":"2026-04-14T06:31:13.557349Z","steps":["trace[1082753454] 'agreement among raft nodes before linearized reading' (duration: 117.347191ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:13.75974Z","caller":"traceutil/trace.go:171","msg":"trace[187930270] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"195.575665ms","start":"2026-04-14T06:31:13.564138Z","end":"2026-04-14T06:31:13.759714Z","steps":["trace[187930270] 'process raft request' (duration: 194.101716ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:16.158465Z","caller":"traceutil/trace.go:171","msg":"trace[366324768] linearizableReadLoop","detail":"{readStateIndex:25829; appliedIndex:25827; }","duration":"121.015916ms","start":"2026-04-14T06:31:16.037426Z","end":"2026-04-14T06:31:16.158442Z","steps":["trace[366324768] 'read index received' (duration: 43.146029ms)","trace[366324768] 'applied index is now lower than readState.Index' (duration: 77.868667ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:31:16.158742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.295791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-14T06:31:16.15882Z","caller":"traceutil/trace.go:171","msg":"trace[1482705049] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25013; }","duration":"121.427444ms","start":"2026-04-14T06:31:16.037378Z","end":"2026-04-14T06:31:16.158806Z","steps":["trace[1482705049] 'agreement among raft nodes before linearized reading' (duration: 121.20809ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:16.15906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.554566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-14T06:31:16.15912Z","caller":"traceutil/trace.go:171","msg":"trace[891692591] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25013; }","duration":"117.647647ms","start":"2026-04-14T06:31:16.041456Z","end":"2026-04-14T06:31:16.159103Z","steps":["trace[891692591] 'agreement among raft nodes before linearized reading' (duration: 117.190048ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:16.159147Z","caller":"traceutil/trace.go:171","msg":"trace[441681637] transaction","detail":"{read_only:false; response_revision:25013; number_of_response:1; }","duration":"133.778912ms","start":"2026-04-14T06:31:16.024755Z","end":"2026-04-14T06:31:16.158534Z","steps":["trace[441681637] 'process raft request' (duration: 90.545011ms)","trace[441681637] 'compare' (duration: 43.018587ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:19.87007Z","caller":"traceutil/trace.go:171","msg":"trace[519244592] transaction","detail":"{read_only:false; response_revision:25058; number_of_response:1; }","duration":"127.466905ms","start":"2026-04-14T06:31:19.742569Z","end":"2026-04-14T06:31:19.870036Z","steps":["trace[519244592] 'process raft request' (duration: 127.262931ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:20.26265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.460502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T06:31:20.26276Z","caller":"traceutil/trace.go:171","msg":"trace[1574008681] transaction","detail":"{read_only:false; response_revision:25059; number_of_response:1; }","duration":"385.758642ms","start":"2026-04-14T06:31:19.876982Z","end":"2026-04-14T06:31:20.262741Z","steps":["trace[1574008681] 'process raft request' (duration: 282.109986ms)","trace[1574008681] 'compare' (duration: 103.337629ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:31:20.262826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:19.87695Z","time spent":"385.842464ms","remote":"127.0.0.1:46124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1415,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:31:20.57207Z","caller":"traceutil/trace.go:171","msg":"trace[1453795849] transaction","detail":"{read_only:false; response_revision:25060; number_of_response:1; }","duration":"694.514915ms","start":"2026-04-14T06:31:19.877462Z","end":"2026-04-14T06:31:20.571977Z","steps":["trace[1453795849] 'process raft request' (duration: 694.217009ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:20.572122Z","caller":"traceutil/trace.go:171","msg":"trace[1418304649] transaction","detail":"{read_only:false; response_revision:25063; number_of_response:1; }","duration":"481.297004ms","start":"2026-04-14T06:31:20.0908Z","end":"2026-04-14T06:31:20.572097Z","steps":["trace[1418304649] 'process raft request' (duration: 481.159351ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:20.572215Z","caller":"traceutil/trace.go:171","msg":"trace[116640602] transaction","detail":"{read_only:false; response_revision:25061; number_of_response:1; }","duration":"694.717499ms","start":"2026-04-14T06:31:19.877479Z","end":"2026-04-14T06:31:20.572196Z","steps":["trace[116640602] 'process raft request' (duration: 694.364472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:20.572261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:19.877452Z","time spent":"694.711138ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":885,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:31:20.572255Z","caller":"traceutil/trace.go:171","msg":"trace[2145462589] transaction","detail":"{read_only:false; response_revision:25062; number_of_response:1; }","duration":"694.749479ms","start":"2026-04-14T06:31:19.877472Z","end":"2026-04-14T06:31:20.572221Z","steps":["trace[2145462589] 'process raft request' (duration: 694.431063ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:20.572314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:19.87746Z","time spent":"694.80016ms","remote":"127.0.0.1:46000","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:31:20.5724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:20.090775Z","time spent":"481.418677ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:31:20.572441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:19.877449Z","time spent":"694.898932ms","remote":"127.0.0.1:46124","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:31:20.580325Z","caller":"traceutil/trace.go:171","msg":"trace[2108923961] linearizableReadLoop","detail":"{readStateIndex:25882; appliedIndex:25879; }","duration":"401.60041ms","start":"2026-04-14T06:31:20.178666Z","end":"2026-04-14T06:31:20.580267Z","steps":["trace[2108923961] 'read index received' (duration: 393.278553ms)","trace[2108923961] 'applied index is now lower than readState.Index' (duration: 8.320587ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:20.580551Z","caller":"traceutil/trace.go:171","msg":"trace[254412529] transaction","detail":"{read_only:false; response_revision:25064; number_of_response:1; }","duration":"409.265765ms","start":"2026-04-14T06:31:20.17127Z","end":"2026-04-14T06:31:20.580536Z","steps":["trace[254412529] 'process raft request' (duration: 408.662143ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:20.580644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:20.171248Z","time spent":"409.344306ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:31:20.580793Z","caller":"traceutil/trace.go:171","msg":"trace[219317209] transaction","detail":"{read_only:false; response_revision:25065; number_of_response:1; }","duration":"367.363062ms","start":"2026-04-14T06:31:20.21342Z","end":"2026-04-14T06:31:20.580783Z","steps":["trace[219317209] 'process raft request' (duration: 366.644727ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:20.580841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:20.2134Z","time spent":"367.418402ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:31:20.580934Z","caller":"traceutil/trace.go:171","msg":"trace[550826020] transaction","detail":"{read_only:false; response_revision:25066; number_of_response:1; }","duration":"303.632709ms","start":"2026-04-14T06:31:20.277295Z","end":"2026-04-14T06:31:20.580927Z","steps":["trace[550826020] 'process raft request' (duration: 302.824353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:20.580974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:20.277274Z","time spent":"303.68045ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:31:20.58118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.521418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-14T06:31:20.581212Z","caller":"traceutil/trace.go:171","msg":"trace[1913511297] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25067; }","duration":"402.5915ms","start":"2026-04-14T06:31:20.178613Z","end":"2026-04-14T06:31:20.581205Z","steps":["trace[1913511297] 'agreement among raft nodes before linearized reading' (duration: 402.495888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:20.58123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:20.178591Z","time spent":"402.634901ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":518,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T06:31:20.581326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.198823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-14T06:31:20.58135Z","caller":"traceutil/trace.go:171","msg":"trace[224557947] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25067; }","duration":"401.671103ms","start":"2026-04-14T06:31:20.179673Z","end":"2026-04-14T06:31:20.581344Z","steps":["trace[224557947] 'agreement among raft nodes before linearized reading' (duration: 401.60534ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:20.581367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:20.179662Z","time spent":"401.700143ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-14T06:31:35.027903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.021365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T06:31:35.028023Z","caller":"traceutil/trace.go:171","msg":"trace[833714062] transaction","detail":"{read_only:false; response_revision:25180; number_of_response:1; }","duration":"400.615091ms","start":"2026-04-14T06:31:34.627392Z","end":"2026-04-14T06:31:35.028007Z","steps":["trace[833714062] 'process raft request' (duration: 198.428351ms)","trace[833714062] 'compare' (duration: 201.808171ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:31:35.028065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:34.627371Z","time spent":"400.675662ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:31:35.028419Z","caller":"traceutil/trace.go:171","msg":"trace[703671178] transaction","detail":"{read_only:false; response_revision:25181; number_of_response:1; }","duration":"398.10091ms","start":"2026-04-14T06:31:34.630288Z","end":"2026-04-14T06:31:35.028389Z","steps":["trace[703671178] 'process raft request' (duration: 397.686212ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:35.028477Z","caller":"traceutil/trace.go:171","msg":"trace[715755083] linearizableReadLoop","detail":"{readStateIndex:26003; appliedIndex:26000; }","duration":"378.454285ms","start":"2026-04-14T06:31:34.650008Z","end":"2026-04-14T06:31:35.028463Z","steps":["trace[715755083] 'read index received' (duration: 52.458725ms)","trace[715755083] 'applied index is now lower than readState.Index' (duration: 325.99413ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:35.028518Z","caller":"traceutil/trace.go:171","msg":"trace[1863718609] transaction","detail":"{read_only:false; response_revision:25182; number_of_response:1; }","duration":"294.411774ms","start":"2026-04-14T06:31:34.734078Z","end":"2026-04-14T06:31:35.028489Z","steps":["trace[1863718609] 'process raft request' (duration: 294.307352ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:35.028576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:34.630277Z","time spent":"398.214783ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:31:35.028662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.684667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-04-14T06:31:35.028693Z","caller":"traceutil/trace.go:171","msg":"trace[665653899] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25182; }","duration":"361.739389ms","start":"2026-04-14T06:31:34.666943Z","end":"2026-04-14T06:31:35.028682Z","steps":["trace[665653899] 'agreement among raft nodes before linearized reading' (duration: 361.603096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:35.028718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:34.66693Z","time spent":"361.782989ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":582,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-04-14T06:31:35.028712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.68995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-04-14T06:31:35.028779Z","caller":"traceutil/trace.go:171","msg":"trace[333830678] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25182; }","duration":"378.783341ms","start":"2026-04-14T06:31:34.649976Z","end":"2026-04-14T06:31:35.02876Z","steps":["trace[333830678] 'agreement among raft nodes before linearized reading' (duration: 378.548897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:35.028833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:34.649961Z","time spent":"378.861134ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":519,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-14T06:31:35.028725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.199492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:31:35.02919Z","caller":"traceutil/trace.go:171","msg":"trace[393769253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25182; }","duration":"141.704661ms","start":"2026-04-14T06:31:34.887468Z","end":"2026-04-14T06:31:35.029173Z","steps":["trace[393769253] 'agreement among raft nodes before linearized reading' (duration: 141.224152ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:35.386552Z","caller":"traceutil/trace.go:171","msg":"trace[2062024606] transaction","detail":"{read_only:false; response_revision:25183; number_of_response:1; }","duration":"353.228868ms","start":"2026-04-14T06:31:35.033299Z","end":"2026-04-14T06:31:35.386528Z","steps":["trace[2062024606] 'process raft request' (duration: 353.062784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:35.386759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:35.033282Z","time spent":"353.400621ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:31:35.388Z","caller":"traceutil/trace.go:171","msg":"trace[1844659888] linearizableReadLoop","detail":"{readStateIndex:26006; appliedIndex:26004; }","duration":"138.441975ms","start":"2026-04-14T06:31:35.249538Z","end":"2026-04-14T06:31:35.38798Z","steps":["trace[1844659888] 'read index received' (duration: 136.802292ms)","trace[1844659888] 'applied index is now lower than readState.Index' (duration: 1.638623ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:35.388063Z","caller":"traceutil/trace.go:171","msg":"trace[598918920] transaction","detail":"{read_only:false; response_revision:25184; number_of_response:1; }","duration":"354.241597ms","start":"2026-04-14T06:31:35.033793Z","end":"2026-04-14T06:31:35.388035Z","steps":["trace[598918920] 'process raft request' (duration: 354.083594ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:35.388226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:35.033783Z","time spent":"354.35959ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:31:35.388321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.72856ms","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-04-14T06:31:35.388489Z","caller":"traceutil/trace.go:171","msg":"trace[624605923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25184; }","duration":"138.978736ms","start":"2026-04-14T06:31:35.249492Z","end":"2026-04-14T06:31:35.388471Z","steps":["trace[624605923] 'agreement among raft nodes before linearized reading' (duration: 138.561717ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:35.754676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.166161ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T06:31:35.754788Z","caller":"traceutil/trace.go:171","msg":"trace[1456238002] transaction","detail":"{read_only:false; response_revision:25186; number_of_response:1; }","duration":"238.834425ms","start":"2026-04-14T06:31:35.515933Z","end":"2026-04-14T06:31:35.754768Z","steps":["trace[1456238002] 'process raft request' (duration: 90.47014ms)","trace[1456238002] 'compare' (duration: 147.987108ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:35.835284Z","caller":"traceutil/trace.go:171","msg":"trace[1313253206] transaction","detail":"{read_only:false; response_revision:25187; number_of_response:1; }","duration":"319.07189ms","start":"2026-04-14T06:31:35.516186Z","end":"2026-04-14T06:31:35.835258Z","steps":["trace[1313253206] 'process raft request' (duration: 318.880356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:35.835457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:35.516175Z","time spent":"319.184432ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-14T06:31:35.835581Z","caller":"traceutil/trace.go:171","msg":"trace[1684426645] linearizableReadLoop","detail":"{readStateIndex:26011; appliedIndex:26008; }","duration":"171.564652ms","start":"2026-04-14T06:31:35.663993Z","end":"2026-04-14T06:31:35.835557Z","steps":["trace[1684426645] 'read index received' (duration: 171.082993ms)","trace[1684426645] 'applied index is now lower than readState.Index' (duration: 480.349µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:35.835618Z","caller":"traceutil/trace.go:171","msg":"trace[605390350] transaction","detail":"{read_only:false; response_revision:25189; number_of_response:1; }","duration":"232.987718ms","start":"2026-04-14T06:31:35.602605Z","end":"2026-04-14T06:31:35.835593Z","steps":["trace[605390350] 'process raft request' (duration: 232.830455ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:35.835597Z","caller":"traceutil/trace.go:171","msg":"trace[943711953] transaction","detail":"{read_only:false; response_revision:25188; number_of_response:1; }","duration":"319.267134ms","start":"2026-04-14T06:31:35.516294Z","end":"2026-04-14T06:31:35.835561Z","steps":["trace[943711953] 'process raft request' (duration: 318.919267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:35.835825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:35.516271Z","time spent":"319.496878ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:31:35.835864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.864418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-14T06:31:35.835925Z","caller":"traceutil/trace.go:171","msg":"trace[1768768334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25189; }","duration":"172.00088ms","start":"2026-04-14T06:31:35.66391Z","end":"2026-04-14T06:31:35.835911Z","steps":["trace[1768768334] 'agreement among raft nodes before linearized reading' (duration: 171.785376ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:36.428236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.72372ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-04-14T06:31:36.428515Z","caller":"traceutil/trace.go:171","msg":"trace[182207720] linearizableReadLoop","detail":"{readStateIndex:26012; appliedIndex:26011; }","duration":"592.829438ms","start":"2026-04-14T06:31:35.83566Z","end":"2026-04-14T06:31:36.428489Z","steps":["trace[182207720] 'read index received' (duration: 132.850463ms)","trace[182207720] 'applied index is now lower than readState.Index' (duration: 459.977645ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:31:36.428553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.403991ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:31:36.428625Z","caller":"traceutil/trace.go:171","msg":"trace[375723691] transaction","detail":"{read_only:false; response_revision:25190; number_of_response:1; }","duration":"588.201384ms","start":"2026-04-14T06:31:35.840399Z","end":"2026-04-14T06:31:36.428601Z","steps":["trace[375723691] 'process raft request' (duration: 587.96906ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:36.428678Z","caller":"traceutil/trace.go:171","msg":"trace[1551280165] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:25189; }","duration":"194.541114ms","start":"2026-04-14T06:31:36.234117Z","end":"2026-04-14T06:31:36.428659Z","steps":["trace[1551280165] 'range keys from in-memory index tree' (duration: 194.38614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:36.428695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"526.184558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-14T06:31:36.428738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"762.276319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-14T06:31:36.428821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.731286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:31:36.428882Z","caller":"traceutil/trace.go:171","msg":"trace[1694631185] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:25190; }","duration":"174.823279ms","start":"2026-04-14T06:31:36.254047Z","end":"2026-04-14T06:31:36.428871Z","steps":["trace[1694631185] 'agreement among raft nodes before linearized reading' (duration: 174.736437ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:36.428885Z","caller":"traceutil/trace.go:171","msg":"trace[1102745325] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25190; }","duration":"762.438553ms","start":"2026-04-14T06:31:35.666429Z","end":"2026-04-14T06:31:36.428867Z","steps":["trace[1102745325] 'agreement among raft nodes before linearized reading' (duration: 762.172376ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:36.428748Z","caller":"traceutil/trace.go:171","msg":"trace[378917297] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:25190; }","duration":"526.28919ms","start":"2026-04-14T06:31:35.902441Z","end":"2026-04-14T06:31:36.42873Z","steps":["trace[378917297] 'agreement among raft nodes before linearized reading' (duration: 526.190348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:36.428958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:35.666419Z","time spent":"762.509004ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-14T06:31:36.428751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:35.840378Z","time spent":"588.304138ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:31:36.429108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:35.902419Z","time spent":"526.628517ms","remote":"127.0.0.1:41782","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:31:36.428773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.88428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-14T06:31:36.429349Z","caller":"traceutil/trace.go:171","msg":"trace[1729791052] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25190; }","duration":"762.516313ms","start":"2026-04-14T06:31:35.666815Z","end":"2026-04-14T06:31:36.429331Z","steps":["trace[1729791052] 'agreement among raft nodes before linearized reading' (duration: 761.753497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:36.429439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:35.666791Z","time spent":"762.630425ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":533,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-14T06:31:36.42892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"485.280695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-04-14T06:31:36.429631Z","caller":"traceutil/trace.go:171","msg":"trace[1269673651] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25190; }","duration":"486.01649ms","start":"2026-04-14T06:31:35.943589Z","end":"2026-04-14T06:31:36.429606Z","steps":["trace[1269673651] 'agreement among raft nodes before linearized reading' (duration: 485.251995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:36.429691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:35.943566Z","time spent":"486.106571ms","remote":"127.0.0.1:53372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3260,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-04-14T06:31:36.889484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.392268ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-14T06:31:36.889785Z","caller":"traceutil/trace.go:171","msg":"trace[851868967] transaction","detail":"{read_only:false; response_revision:25191; number_of_response:1; }","duration":"456.73744ms","start":"2026-04-14T06:31:36.43302Z","end":"2026-04-14T06:31:36.889758Z","steps":["trace[851868967] 'process raft request' (duration: 131.994705ms)","trace[851868967] 'compare' (duration: 323.913578ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:36.889834Z","caller":"traceutil/trace.go:171","msg":"trace[1704691738] transaction","detail":"{read_only:false; response_revision:25192; number_of_response:1; }","duration":"455.995186ms","start":"2026-04-14T06:31:36.433818Z","end":"2026-04-14T06:31:36.889813Z","steps":["trace[1704691738] 'process raft request' (duration: 455.766871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:36.889877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:36.432997Z","time spent":"456.837382ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:31:36.889948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:36.433801Z","time spent":"456.089347ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:31:36.889988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.716309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:31:36.889972Z","caller":"traceutil/trace.go:171","msg":"trace[1283022259] linearizableReadLoop","detail":"{readStateIndex:26015; appliedIndex:26013; }","duration":"448.599277ms","start":"2026-04-14T06:31:36.441244Z","end":"2026-04-14T06:31:36.889843Z","steps":["trace[1283022259] 'read index received' (duration: 123.77818ms)","trace[1283022259] 'applied index is now lower than readState.Index' (duration: 324.778846ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:36.890083Z","caller":"traceutil/trace.go:171","msg":"trace[55962563] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:25192; }","duration":"448.840782ms","start":"2026-04-14T06:31:36.441208Z","end":"2026-04-14T06:31:36.890049Z","steps":["trace[55962563] 'agreement among raft nodes before linearized reading' (duration: 448.685089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:36.890186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:36.4412Z","time spent":"448.973794ms","remote":"127.0.0.1:43288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true "} {"level":"info","ts":"2026-04-14T06:31:37.123856Z","caller":"traceutil/trace.go:171","msg":"trace[1911461905] linearizableReadLoop","detail":"{readStateIndex:26016; appliedIndex:26015; }","duration":"233.693663ms","start":"2026-04-14T06:31:36.890136Z","end":"2026-04-14T06:31:37.12383Z","steps":["trace[1911461905] 'read index received' (duration: 233.52635ms)","trace[1911461905] 'applied index is now lower than readState.Index' (duration: 165.473µs)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:37.123968Z","caller":"traceutil/trace.go:171","msg":"trace[91321736] transaction","detail":"{read_only:false; response_revision:25193; number_of_response:1; }","duration":"415.957421ms","start":"2026-04-14T06:31:36.707999Z","end":"2026-04-14T06:31:37.123956Z","steps":["trace[91321736] 'process raft request' (duration: 415.614984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:37.124105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:36.70798Z","time spent":"416.019121ms","remote":"127.0.0.1:46114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-14T06:31:37.124177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.203323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:31:37.124232Z","caller":"traceutil/trace.go:171","msg":"trace[265020913] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25193; }","duration":"235.276494ms","start":"2026-04-14T06:31:36.888937Z","end":"2026-04-14T06:31:37.124213Z","steps":["trace[265020913] 'agreement among raft nodes before linearized reading' (duration: 235.217512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:37.124113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.575336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-04-14T06:31:37.12442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.620711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-04-14T06:31:37.124452Z","caller":"traceutil/trace.go:171","msg":"trace[895186818] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:25193; }","duration":"568.686763ms","start":"2026-04-14T06:31:36.555756Z","end":"2026-04-14T06:31:37.124442Z","steps":["trace[895186818] 'agreement among raft nodes before linearized reading' (duration: 568.53752ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:37.124485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:36.55574Z","time spent":"568.733654ms","remote":"127.0.0.1:45964","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":143,"response size":33,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-04-14T06:31:37.124443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.93172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:31:37.124646Z","caller":"traceutil/trace.go:171","msg":"trace[17702455] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25193; }","duration":"285.804311ms","start":"2026-04-14T06:31:36.838471Z","end":"2026-04-14T06:31:37.124275Z","steps":["trace[17702455] 'agreement among raft nodes before linearized reading' (duration: 285.507655ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:37.124667Z","caller":"traceutil/trace.go:171","msg":"trace[1563896323] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25193; }","duration":"669.141134ms","start":"2026-04-14T06:31:36.455413Z","end":"2026-04-14T06:31:37.124554Z","steps":["trace[1563896323] 'agreement among raft nodes before linearized reading' (duration: 668.856679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:37.124907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-14T06:31:36.4554Z","time spent":"669.378619ms","remote":"127.0.0.1:46152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":30,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-04-14T06:31:37.141981Z","caller":"traceutil/trace.go:171","msg":"trace[250002070] transaction","detail":"{read_only:false; response_revision:25195; number_of_response:1; }","duration":"107.481392ms","start":"2026-04-14T06:31:37.034485Z","end":"2026-04-14T06:31:37.141967Z","steps":["trace[250002070] 'process raft request' (duration: 107.408881ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:37.142004Z","caller":"traceutil/trace.go:171","msg":"trace[846310863] transaction","detail":"{read_only:false; response_revision:25194; number_of_response:1; }","duration":"108.589755ms","start":"2026-04-14T06:31:37.033379Z","end":"2026-04-14T06:31:37.141969Z","steps":["trace[846310863] 'process raft request' (duration: 108.410062ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:37.14267Z","caller":"traceutil/trace.go:171","msg":"trace[1872238501] transaction","detail":"{read_only:false; response_revision:25196; number_of_response:1; }","duration":"107.513853ms","start":"2026-04-14T06:31:37.034617Z","end":"2026-04-14T06:31:37.142131Z","steps":["trace[1872238501] 'process raft request' (duration: 107.312839ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:39.466702Z","caller":"traceutil/trace.go:171","msg":"trace[496006242] transaction","detail":"{read_only:false; response_revision:25220; number_of_response:1; }","duration":"257.404541ms","start":"2026-04-14T06:31:39.209258Z","end":"2026-04-14T06:31:39.466662Z","steps":["trace[496006242] 'process raft request' (duration: 257.187076ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:39.574012Z","caller":"traceutil/trace.go:171","msg":"trace[1030124279] transaction","detail":"{read_only:false; response_revision:25223; number_of_response:1; }","duration":"101.389789ms","start":"2026-04-14T06:31:39.472596Z","end":"2026-04-14T06:31:39.573985Z","steps":["trace[1030124279] 'process raft request' (duration: 101.334838ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:39.5741Z","caller":"traceutil/trace.go:171","msg":"trace[782347746] transaction","detail":"{read_only:false; response_revision:25222; number_of_response:1; }","duration":"102.115834ms","start":"2026-04-14T06:31:39.471941Z","end":"2026-04-14T06:31:39.574057Z","steps":["trace[782347746] 'process raft request' (duration: 101.815498ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:39.848199Z","caller":"traceutil/trace.go:171","msg":"trace[109742490] transaction","detail":"{read_only:false; response_revision:25225; number_of_response:1; }","duration":"125.464095ms","start":"2026-04-14T06:31:39.722713Z","end":"2026-04-14T06:31:39.848177Z","steps":["trace[109742490] 'process raft request' (duration: 125.23356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:40.104091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.850716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9545"} {"level":"info","ts":"2026-04-14T06:31:40.104165Z","caller":"traceutil/trace.go:171","msg":"trace[282407993] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:25229; }","duration":"154.988008ms","start":"2026-04-14T06:31:39.949157Z","end":"2026-04-14T06:31:40.104145Z","steps":["trace[282407993] 'range keys from in-memory index tree' (duration: 154.670751ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:47.287011Z","caller":"traceutil/trace.go:171","msg":"trace[289598279] transaction","detail":"{read_only:false; response_revision:25281; number_of_response:1; }","duration":"109.655768ms","start":"2026-04-14T06:31:47.177327Z","end":"2026-04-14T06:31:47.286983Z","steps":["trace[289598279] 'process raft request' (duration: 109.574316ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:47.287116Z","caller":"traceutil/trace.go:171","msg":"trace[2080970015] transaction","detail":"{read_only:false; response_revision:25279; number_of_response:1; }","duration":"114.680478ms","start":"2026-04-14T06:31:47.172417Z","end":"2026-04-14T06:31:47.287098Z","steps":["trace[2080970015] 'process raft request' (duration: 61.766723ms)","trace[2080970015] 'compare' (duration: 52.487215ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:47.287144Z","caller":"traceutil/trace.go:171","msg":"trace[928825862] transaction","detail":"{read_only:false; response_revision:25280; number_of_response:1; }","duration":"110.878681ms","start":"2026-04-14T06:31:47.176228Z","end":"2026-04-14T06:31:47.287106Z","steps":["trace[928825862] 'process raft request' (duration: 110.624276ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:48.712226Z","caller":"traceutil/trace.go:171","msg":"trace[62279648] transaction","detail":"{read_only:false; response_revision:25289; number_of_response:1; }","duration":"152.852425ms","start":"2026-04-14T06:31:48.559348Z","end":"2026-04-14T06:31:48.7122Z","steps":["trace[62279648] 'process raft request' (duration: 152.686272ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:52.82068Z","caller":"traceutil/trace.go:171","msg":"trace[1143922573] transaction","detail":"{read_only:false; response_revision:25321; number_of_response:1; }","duration":"234.429408ms","start":"2026-04-14T06:31:52.586221Z","end":"2026-04-14T06:31:52.82065Z","steps":["trace[1143922573] 'process raft request' (duration: 234.234394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:53.230078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.787599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-14T06:31:53.230156Z","caller":"traceutil/trace.go:171","msg":"trace[599255662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25321; }","duration":"270.908611ms","start":"2026-04-14T06:31:52.959231Z","end":"2026-04-14T06:31:53.230139Z","steps":["trace[599255662] 'range keys from in-memory index tree' (duration: 270.590425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:31:53.230363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.677386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-04-14T06:31:53.230416Z","caller":"traceutil/trace.go:171","msg":"trace[1427991157] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25321; }","duration":"270.779547ms","start":"2026-04-14T06:31:52.959625Z","end":"2026-04-14T06:31:53.230405Z","steps":["trace[1427991157] 'range keys from in-memory index tree' (duration: 270.556053ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:53.454292Z","caller":"traceutil/trace.go:171","msg":"trace[1023586832] transaction","detail":"{read_only:false; response_revision:25325; number_of_response:1; }","duration":"142.437826ms","start":"2026-04-14T06:31:53.311831Z","end":"2026-04-14T06:31:53.454269Z","steps":["trace[1023586832] 'process raft request' (duration: 88.139504ms)","trace[1023586832] 'compare' (duration: 54.130169ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:31:53.454502Z","caller":"traceutil/trace.go:171","msg":"trace[933801819] transaction","detail":"{read_only:false; response_revision:25326; number_of_response:1; }","duration":"139.968197ms","start":"2026-04-14T06:31:53.314525Z","end":"2026-04-14T06:31:53.454494Z","steps":["trace[933801819] 'process raft request' (duration: 139.709101ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:53.454524Z","caller":"traceutil/trace.go:171","msg":"trace[907979476] transaction","detail":"{read_only:false; response_revision:25327; number_of_response:1; }","duration":"139.904595ms","start":"2026-04-14T06:31:53.314595Z","end":"2026-04-14T06:31:53.4545Z","steps":["trace[907979476] 'process raft request' (duration: 139.806443ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:55.588558Z","caller":"traceutil/trace.go:171","msg":"trace[1039423658] transaction","detail":"{read_only:false; response_revision:25339; number_of_response:1; }","duration":"128.73769ms","start":"2026-04-14T06:31:55.459788Z","end":"2026-04-14T06:31:55.588525Z","steps":["trace[1039423658] 'process raft request' (duration: 128.586297ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:55.588706Z","caller":"traceutil/trace.go:171","msg":"trace[1391649770] transaction","detail":"{read_only:false; response_revision:25340; number_of_response:1; }","duration":"128.829013ms","start":"2026-04-14T06:31:55.45987Z","end":"2026-04-14T06:31:55.588699Z","steps":["trace[1391649770] 'process raft request' (duration: 128.616528ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:55.588738Z","caller":"traceutil/trace.go:171","msg":"trace[673244518] linearizableReadLoop","detail":"{readStateIndex:26166; appliedIndex:26163; }","duration":"101.550203ms","start":"2026-04-14T06:31:55.487138Z","end":"2026-04-14T06:31:55.588689Z","steps":["trace[673244518] 'read index received' (duration: 41.778761ms)","trace[673244518] 'applied index is now lower than readState.Index' (duration: 59.767452ms)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:31:55.588844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.691236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-14T06:31:55.588915Z","caller":"traceutil/trace.go:171","msg":"trace[347286949] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25340; }","duration":"101.800859ms","start":"2026-04-14T06:31:55.4871Z","end":"2026-04-14T06:31:55.588901Z","steps":["trace[347286949] 'agreement among raft nodes before linearized reading' (duration: 101.678526ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:56.971632Z","caller":"traceutil/trace.go:171","msg":"trace[1290693272] transaction","detail":"{read_only:false; response_revision:25348; number_of_response:1; }","duration":"119.634427ms","start":"2026-04-14T06:31:56.851966Z","end":"2026-04-14T06:31:56.9716Z","steps":["trace[1290693272] 'process raft request' (duration: 119.341012ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:31:59.09528Z","caller":"traceutil/trace.go:171","msg":"trace[800668322] transaction","detail":"{read_only:false; response_revision:25361; number_of_response:1; }","duration":"114.547075ms","start":"2026-04-14T06:31:58.9807Z","end":"2026-04-14T06:31:59.095247Z","steps":["trace[800668322] 'process raft request' (duration: 114.050825ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:32:47.553089Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22908} {"level":"info","ts":"2026-04-14T06:32:47.686542Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22908,"took":"129.129728ms","hash":700084053,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":35520512,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-14T06:32:47.686676Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":700084053,"revision":22908,"compact-revision":20044} {"level":"info","ts":"2026-04-14T06:33:19.756214Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006997.wal"} {"level":"info","ts":"2026-04-14T06:34:31.622214Z","caller":"traceutil/trace.go:171","msg":"trace[1776260351] transaction","detail":"{read_only:false; response_revision:26849; number_of_response:1; }","duration":"110.801179ms","start":"2026-04-14T06:34:31.51139Z","end":"2026-04-14T06:34:31.622191Z","steps":["trace[1776260351] 'process raft request' (duration: 110.657216ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:34:31.846798Z","caller":"traceutil/trace.go:171","msg":"trace[1042401979] transaction","detail":"{read_only:false; response_revision:26851; number_of_response:1; }","duration":"163.656102ms","start":"2026-04-14T06:34:31.683123Z","end":"2026-04-14T06:34:31.846779Z","steps":["trace[1042401979] 'process raft request' (duration: 162.188293ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:37:47.562212Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25905} {"level":"info","ts":"2026-04-14T06:37:47.707423Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25905,"took":"139.67959ms","hash":3883197102,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":34238464,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-14T06:37:47.707503Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3883197102,"revision":25905,"compact-revision":22908} {"level":"info","ts":"2026-04-14T06:38:53.226869Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T06:38:53.239707Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-14T06:38:53.239854Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-14T06:42:47.568301Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28601} {"level":"info","ts":"2026-04-14T06:42:47.714745Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28601,"took":"143.06245ms","hash":804752966,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":29970432,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-14T06:42:47.714823Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":804752966,"revision":28601,"compact-revision":25905} {"level":"info","ts":"2026-04-14T06:47:47.575332Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30821} {"level":"info","ts":"2026-04-14T06:47:47.684142Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30821,"took":"105.432456ms","hash":1101389698,"current-db-size-bytes":56573952,"current-db-size":"57 MB","current-db-size-in-use-bytes":30875648,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-14T06:47:47.684214Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1101389698,"revision":30821,"compact-revision":28601} {"level":"info","ts":"2026-04-14T06:48:47.740009Z","caller":"traceutil/trace.go:171","msg":"trace[1379594673] linearizableReadLoop","detail":"{readStateIndex:34604; appliedIndex:34603; }","duration":"196.088748ms","start":"2026-04-14T06:48:47.543889Z","end":"2026-04-14T06:48:47.739977Z","steps":["trace[1379594673] 'read index received' (duration: 193.832943ms)","trace[1379594673] 'applied index is now lower than readState.Index' (duration: 2.254305ms)"],"step_count":2} {"level":"info","ts":"2026-04-14T06:48:47.740119Z","caller":"traceutil/trace.go:171","msg":"trace[902639453] transaction","detail":"{read_only:false; response_revision:33550; number_of_response:1; }","duration":"218.081638ms","start":"2026-04-14T06:48:47.522015Z","end":"2026-04-14T06:48:47.740096Z","steps":["trace[902639453] 'process raft request' (duration: 215.563598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:48:47.740414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.513619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-04-14T06:48:47.740562Z","caller":"traceutil/trace.go:171","msg":"trace[1845615852] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:33550; }","duration":"108.714813ms","start":"2026-04-14T06:48:47.631825Z","end":"2026-04-14T06:48:47.74054Z","steps":["trace[1845615852] 'agreement among raft nodes before linearized reading' (duration: 108.456808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-14T06:48:47.740836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.379404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-14T06:48:47.741001Z","caller":"traceutil/trace.go:171","msg":"trace[33325024] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:33550; }","duration":"197.139539ms","start":"2026-04-14T06:48:47.543846Z","end":"2026-04-14T06:48:47.740985Z","steps":["trace[33325024] 'agreement among raft nodes before linearized reading' (duration: 196.294243ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:48:47.977679Z","caller":"traceutil/trace.go:171","msg":"trace[891233198] transaction","detail":"{read_only:false; response_revision:33551; number_of_response:1; }","duration":"155.086984ms","start":"2026-04-14T06:48:47.822555Z","end":"2026-04-14T06:48:47.977642Z","steps":["trace[891233198] 'process raft request' (duration: 154.542353ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:48:47.977889Z","caller":"traceutil/trace.go:171","msg":"trace[1195149420] linearizableReadLoop","detail":"{readStateIndex:34605; appliedIndex:34604; }","duration":"151.877679ms","start":"2026-04-14T06:48:47.825475Z","end":"2026-04-14T06:48:47.977353Z","steps":["trace[1195149420] 'read index received' (duration: 151.808418ms)","trace[1195149420] 'applied index is now lower than readState.Index' (duration: 68.121µs)"],"step_count":2} {"level":"warn","ts":"2026-04-14T06:48:47.97858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.064764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-04-14T06:48:47.978695Z","caller":"traceutil/trace.go:171","msg":"trace[999171626] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:33551; }","duration":"153.232067ms","start":"2026-04-14T06:48:47.825438Z","end":"2026-04-14T06:48:47.97867Z","steps":["trace[999171626] 'agreement among raft nodes before linearized reading' (duration: 152.818089ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:48:47.978826Z","caller":"traceutil/trace.go:171","msg":"trace[45543875] transaction","detail":"{read_only:false; response_revision:33553; number_of_response:1; }","duration":"110.988119ms","start":"2026-04-14T06:48:47.867815Z","end":"2026-04-14T06:48:47.978804Z","steps":["trace[45543875] 'process raft request' (duration: 110.939538ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:48:47.978887Z","caller":"traceutil/trace.go:171","msg":"trace[288420836] transaction","detail":"{read_only:false; response_revision:33552; number_of_response:1; }","duration":"152.060814ms","start":"2026-04-14T06:48:47.8268Z","end":"2026-04-14T06:48:47.978861Z","steps":["trace[288420836] 'process raft request' (duration: 151.83074ms)"],"step_count":1} {"level":"info","ts":"2026-04-14T06:52:47.583201Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33085} {"level":"info","ts":"2026-04-14T06:52:47.742372Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33085,"took":"155.749078ms","hash":663273598,"current-db-size-bytes":57614336,"current-db-size":"58 MB","current-db-size-in-use-bytes":31825920,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-14T06:52:47.742419Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":663273598,"revision":33085,"compact-revision":30821} {"level":"info","ts":"2026-04-14T06:57:47.590111Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35379} {"level":"info","ts":"2026-04-14T06:57:47.731817Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35379,"took":"136.4466ms","hash":4176661830,"current-db-size-bytes":57614336,"current-db-size":"58 MB","current-db-size-in-use-bytes":31375360,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-14T06:57:47.731879Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4176661830,"revision":35379,"compact-revision":33085} {"level":"info","ts":"2026-04-14T07:00:12.547511Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"c47ca1a7b1fe506b","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-14T07:00:12.552334Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-14T07:00:12.552445Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}