{"level":"warn","ts":"2026-10-07T03:46:42.933271Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-07T03:46:42.933399Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.136:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.136:2380","--initial-cluster=instance=https://162.253.55.136:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.136:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.136: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-10-07T03:46:42.933486Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-07T03:46:42.933504Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.136:2380"]} {"level":"info","ts":"2026-10-07T03:46:42.933536Z","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-10-07T03:46:42.93439Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.136:2379"]} {"level":"info","ts":"2026-10-07T03:46:42.934485Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.136:2380"],"listen-peer-urls":["https://162.253.55.136:2380"],"advertise-client-urls":["https://162.253.55.136:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.136:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.136: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-10-07T03:46:42.936908Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.151346ms"} {"level":"info","ts":"2026-10-07T03:46:42.945329Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"308ce60d3e55f7ff","cluster-id":"dd7f8b36a12acab1"} {"level":"info","ts":"2026-10-07T03:46:42.945474Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff switched to configuration voters=()"} {"level":"info","ts":"2026-10-07T03:46:42.945517Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became follower at term 0"} {"level":"info","ts":"2026-10-07T03:46:42.945538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 308ce60d3e55f7ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-07T03:46:42.945553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became follower at term 1"} {"level":"info","ts":"2026-10-07T03:46:42.945642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff switched to configuration voters=(3498423955114817535)"} {"level":"warn","ts":"2026-10-07T03:46:42.952496Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-07T03:46:42.954901Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-07T03:46:42.957979Z","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-10-07T03:46:42.961296Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"308ce60d3e55f7ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-07T03:46:42.961713Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"308ce60d3e55f7ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-07T03:46:42.961858Z","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-10-07T03:46:42.96192Z","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-10-07T03:46:42.961937Z","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-10-07T03:46:42.96321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff switched to configuration voters=(3498423955114817535)"} {"level":"info","ts":"2026-10-07T03:46:42.963387Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dd7f8b36a12acab1","local-member-id":"308ce60d3e55f7ff","added-peer-id":"308ce60d3e55f7ff","added-peer-peer-urls":["https://162.253.55.136:2380"]} {"level":"info","ts":"2026-10-07T03:46:42.967019Z","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-10-07T03:46:42.967236Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.136:2380"} {"level":"info","ts":"2026-10-07T03:46:42.96734Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.136:2380"} {"level":"info","ts":"2026-10-07T03:46:42.968672Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"308ce60d3e55f7ff","initial-advertise-peer-urls":["https://162.253.55.136:2380"],"listen-peer-urls":["https://162.253.55.136:2380"],"advertise-client-urls":["https://162.253.55.136:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.136:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-07T03:46:42.968736Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-07T03:46:43.648652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff is starting a new election at term 1"} {"level":"info","ts":"2026-10-07T03:46:43.648763Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became pre-candidate at term 1"} {"level":"info","ts":"2026-10-07T03:46:43.648806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff received MsgPreVoteResp from 308ce60d3e55f7ff at term 1"} {"level":"info","ts":"2026-10-07T03:46:43.648842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became candidate at term 2"} {"level":"info","ts":"2026-10-07T03:46:43.648866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff received MsgVoteResp from 308ce60d3e55f7ff at term 2"} {"level":"info","ts":"2026-10-07T03:46:43.648891Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"308ce60d3e55f7ff became leader at term 2"} {"level":"info","ts":"2026-10-07T03:46:43.648915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 308ce60d3e55f7ff elected leader 308ce60d3e55f7ff at term 2"} {"level":"info","ts":"2026-10-07T03:46:43.656962Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"308ce60d3e55f7ff","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.136:2379]}","request-path":"/0/members/308ce60d3e55f7ff/attributes","cluster-id":"dd7f8b36a12acab1","publish-timeout":"7s"} {"level":"info","ts":"2026-10-07T03:46:43.657064Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-07T03:46:43.657117Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-07T03:46:43.657221Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T03:46:43.657534Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-07T03:46:43.657601Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-07T03:46:43.665701Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dd7f8b36a12acab1","local-member-id":"308ce60d3e55f7ff","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T03:46:43.666416Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T03:46:43.667343Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-07T03:46:43.671113Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-07T03:46:43.671409Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.136:2379"} {"level":"warn","ts":"2026-10-07T03:47:37.082005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.811596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-cl46d\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-10-07T03:47:37.082213Z","caller":"traceutil/trace.go:171","msg":"trace[1376283097] range","detail":"{range_begin:/registry/pods/kube-system/cilium-cl46d; range_end:; response_count:1; response_revision:609; }","duration":"124.127092ms","start":"2026-10-07T03:47:36.958057Z","end":"2026-10-07T03:47:37.082184Z","steps":["trace[1376283097] 'range keys from in-memory index tree' (duration: 123.641723ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:47:37.290469Z","caller":"traceutil/trace.go:171","msg":"trace[1826657445] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:631; }","duration":"107.419729ms","start":"2026-10-07T03:47:37.182975Z","end":"2026-10-07T03:47:37.290395Z","steps":["trace[1826657445] 'read index received' (duration: 85.483434ms)","trace[1826657445] 'applied index is now lower than readState.Index' (duration: 21.935125ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:47:37.290673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.672834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.136\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-10-07T03:47:37.290669Z","caller":"traceutil/trace.go:171","msg":"trace[1782025284] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"198.663994ms","start":"2026-10-07T03:47:37.091958Z","end":"2026-10-07T03:47:37.290623Z","steps":["trace[1782025284] 'process raft request' (duration: 180.964549ms)","trace[1782025284] 'compare' (duration: 16.647693ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:47:37.290719Z","caller":"traceutil/trace.go:171","msg":"trace[2020633499] range","detail":"{range_begin:/registry/masterleases/162.253.55.136; range_end:; response_count:1; response_revision:612; }","duration":"107.822397ms","start":"2026-10-07T03:47:37.182882Z","end":"2026-10-07T03:47:37.290704Z","steps":["trace[2020633499] 'agreement among raft nodes before linearized reading' (duration: 107.693724ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:47:37.29078Z","caller":"traceutil/trace.go:171","msg":"trace[204692269] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"193.332601ms","start":"2026-10-07T03:47:37.097411Z","end":"2026-10-07T03:47:37.290743Z","steps":["trace[204692269] 'process raft request' (duration: 192.914281ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:47:37.643208Z","caller":"traceutil/trace.go:171","msg":"trace[614384322] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"160.161807ms","start":"2026-10-07T03:47:37.483017Z","end":"2026-10-07T03:47:37.643178Z","steps":["trace[614384322] 'read index received' (duration: 159.897101ms)","trace[614384322] 'applied index is now lower than readState.Index' (duration: 263.656µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:47:37.643356Z","caller":"traceutil/trace.go:171","msg":"trace[2141766470] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"279.146231ms","start":"2026-10-07T03:47:37.364162Z","end":"2026-10-07T03:47:37.643308Z","steps":["trace[2141766470] 'process raft request' (duration: 278.860345ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:47:37.64348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.532585ms","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-10-07T03:47:37.643526Z","caller":"traceutil/trace.go:171","msg":"trace[167691624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:614; }","duration":"160.658448ms","start":"2026-10-07T03:47:37.482856Z","end":"2026-10-07T03:47:37.643514Z","steps":["trace[167691624] 'agreement among raft nodes before linearized reading' (duration: 160.430804ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:47:39.242675Z","caller":"traceutil/trace.go:171","msg":"trace[800106693] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"113.926826ms","start":"2026-10-07T03:47:39.128694Z","end":"2026-10-07T03:47:39.24262Z","steps":["trace[800106693] 'process raft request' (duration: 74.254595ms)","trace[800106693] 'compare' (duration: 39.416836ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:48:23.651592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.921603ms","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-10-07T03:48:23.651695Z","caller":"traceutil/trace.go:171","msg":"trace[563216641] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1181; }","duration":"148.119138ms","start":"2026-10-07T03:48:23.503555Z","end":"2026-10-07T03:48:23.651674Z","steps":["trace[563216641] 'range keys from in-memory index tree' (duration: 147.654377ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:49:12.148593Z","caller":"traceutil/trace.go:171","msg":"trace[845540404] linearizableReadLoop","detail":"{readStateIndex:1921; appliedIndex:1920; }","duration":"213.992943ms","start":"2026-10-07T03:49:11.93457Z","end":"2026-10-07T03:49:12.148563Z","steps":["trace[845540404] 'read index received' (duration: 213.711847ms)","trace[845540404] 'applied index is now lower than readState.Index' (duration: 279.316µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:49:12.148654Z","caller":"traceutil/trace.go:171","msg":"trace[381858349] transaction","detail":"{read_only:false; response_revision:1852; number_of_response:1; }","duration":"268.790644ms","start":"2026-10-07T03:49:11.879839Z","end":"2026-10-07T03:49:12.14863Z","steps":["trace[381858349] 'process raft request' (duration: 268.531329ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:49:12.148884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.162241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-10-07T03:49:12.148885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.30218ms","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-10-07T03:49:12.149002Z","caller":"traceutil/trace.go:171","msg":"trace[877845673] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1852; }","duration":"214.467183ms","start":"2026-10-07T03:49:11.934497Z","end":"2026-10-07T03:49:12.148964Z","steps":["trace[877845673] 'agreement among raft nodes before linearized reading' (duration: 214.213628ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:49:12.148941Z","caller":"traceutil/trace.go:171","msg":"trace[453944041] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1852; }","duration":"192.285404ms","start":"2026-10-07T03:49:11.956646Z","end":"2026-10-07T03:49:12.148931Z","steps":["trace[453944041] 'agreement among raft nodes before linearized reading' (duration: 192.12009ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:49:12.471648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.890826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-10-07T03:49:12.471755Z","caller":"traceutil/trace.go:171","msg":"trace[1760757564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1853; }","duration":"227.07152ms","start":"2026-10-07T03:49:12.244655Z","end":"2026-10-07T03:49:12.471727Z","steps":["trace[1760757564] 'range keys from in-memory index tree' (duration: 226.630631ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:50:02.345625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.689564ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-10-07T03:50:02.345938Z","caller":"traceutil/trace.go:171","msg":"trace[1555266917] transaction","detail":"{read_only:false; response_revision:2179; number_of_response:1; }","duration":"151.305195ms","start":"2026-10-07T03:50:02.194613Z","end":"2026-10-07T03:50:02.345918Z","steps":["trace[1555266917] 'process raft request' (duration: 151.129162ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:50:07.927221Z","caller":"traceutil/trace.go:171","msg":"trace[1170120497] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"211.897571ms","start":"2026-10-07T03:50:07.715297Z","end":"2026-10-07T03:50:07.927194Z","steps":["trace[1170120497] 'process raft request' (duration: 211.731317ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:50:07.99702Z","caller":"traceutil/trace.go:171","msg":"trace[201848111] transaction","detail":"{read_only:false; response_revision:2209; number_of_response:1; }","duration":"197.044295ms","start":"2026-10-07T03:50:07.799946Z","end":"2026-10-07T03:50:07.99699Z","steps":["trace[201848111] 'process raft request' (duration: 178.634554ms)","trace[201848111] 'compare' (duration: 18.236837ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:56:22.350393Z","caller":"traceutil/trace.go:171","msg":"trace[894720514] transaction","detail":"{read_only:false; response_revision:5162; number_of_response:1; }","duration":"100.797415ms","start":"2026-10-07T03:56:22.249553Z","end":"2026-10-07T03:56:22.35035Z","steps":["trace[894720514] 'process raft request' (duration: 78.960941ms)","trace[894720514] 'compare' (duration: 21.590949ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:56:26.105805Z","caller":"traceutil/trace.go:171","msg":"trace[776670027] linearizableReadLoop","detail":"{readStateIndex:5522; appliedIndex:5521; }","duration":"122.659679ms","start":"2026-10-07T03:56:25.98311Z","end":"2026-10-07T03:56:26.10577Z","steps":["trace[776670027] 'read index received' (duration: 88.346429ms)","trace[776670027] 'applied index is now lower than readState.Index' (duration: 34.31114ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:56:26.105954Z","caller":"traceutil/trace.go:171","msg":"trace[1266333756] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"161.458195ms","start":"2026-10-07T03:56:25.944435Z","end":"2026-10-07T03:56:26.105893Z","steps":["trace[1266333756] 'process raft request' (duration: 127.095995ms)","trace[1266333756] 'compare' (duration: 34.031564ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:56:26.10608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.940226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2e149e9b-8863-4806-852c-f03844274b72\" ","response":"range_response_count:1 size:3721"} {"level":"info","ts":"2026-10-07T03:56:26.106131Z","caller":"traceutil/trace.go:171","msg":"trace[1365710300] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2e149e9b-8863-4806-852c-f03844274b72; range_end:; response_count:1; response_revision:5321; }","duration":"123.071938ms","start":"2026-10-07T03:56:25.983046Z","end":"2026-10-07T03:56:26.106118Z","steps":["trace[1365710300] 'agreement among raft nodes before linearized reading' (duration: 122.939886ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:56:26.297492Z","caller":"traceutil/trace.go:171","msg":"trace[1215623877] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5321; }","duration":"153.263321ms","start":"2026-10-07T03:56:26.144196Z","end":"2026-10-07T03:56:26.29746Z","steps":["trace[1215623877] 'process raft request' (duration: 115.880547ms)","trace[1215623877] 'compare' (duration: 37.309543ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:56:41.535916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.024563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T03:56:41.536069Z","caller":"traceutil/trace.go:171","msg":"trace[1087066189] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:5604; }","duration":"104.27402ms","start":"2026-10-07T03:56:41.431763Z","end":"2026-10-07T03:56:41.536037Z","steps":["trace[1087066189] 'count revisions from in-memory index tree' (duration: 103.87318ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:56:43.705711Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2921} {"level":"info","ts":"2026-10-07T03:56:43.78749Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2921,"took":"79.536602ms","hash":162592468,"current-db-size-bytes":55103488,"current-db-size":"55 MB","current-db-size-in-use-bytes":31404032,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-10-07T03:56:43.787592Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":162592468,"revision":2921,"compact-revision":-1} {"level":"info","ts":"2026-10-07T03:56:50.582042Z","caller":"traceutil/trace.go:171","msg":"trace[1275646228] transaction","detail":"{read_only:false; response_revision:5810; number_of_response:1; }","duration":"249.322226ms","start":"2026-10-07T03:56:50.332683Z","end":"2026-10-07T03:56:50.582005Z","steps":["trace[1275646228] 'process raft request' (duration: 249.07312ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:56:50.582327Z","caller":"traceutil/trace.go:171","msg":"trace[168538868] linearizableReadLoop","detail":"{readStateIndex:6026; appliedIndex:6026; }","duration":"178.432237ms","start":"2026-10-07T03:56:50.403883Z","end":"2026-10-07T03:56:50.582315Z","steps":["trace[168538868] 'read index received' (duration: 178.426407ms)","trace[168538868] 'applied index is now lower than readState.Index' (duration: 5.28µs)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:56:50.582513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.602261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-fd644c87-b488-45e9-9002-6d3f2a349eb2\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-10-07T03:56:50.582553Z","caller":"traceutil/trace.go:171","msg":"trace[1867387764] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-fd644c87-b488-45e9-9002-6d3f2a349eb2; range_end:; response_count:1; response_revision:5810; }","duration":"178.713262ms","start":"2026-10-07T03:56:50.403827Z","end":"2026-10-07T03:56:50.58254Z","steps":["trace[1867387764] 'agreement among raft nodes before linearized reading' (duration: 178.530227ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:56:50.821389Z","caller":"traceutil/trace.go:171","msg":"trace[1264933837] linearizableReadLoop","detail":"{readStateIndex:6027; appliedIndex:6026; }","duration":"233.230991ms","start":"2026-10-07T03:56:50.588114Z","end":"2026-10-07T03:56:50.821345Z","steps":["trace[1264933837] 'read index received' (duration: 175.670637ms)","trace[1264933837] 'applied index is now lower than readState.Index' (duration: 57.558654ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:56:50.821518Z","caller":"traceutil/trace.go:171","msg":"trace[2009558495] transaction","detail":"{read_only:false; response_revision:5811; number_of_response:1; }","duration":"321.92657ms","start":"2026-10-07T03:56:50.49956Z","end":"2026-10-07T03:56:50.821486Z","steps":["trace[2009558495] 'process raft request' (duration: 264.238843ms)","trace[2009558495] 'compare' (duration: 57.240528ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:56:50.82184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.695262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-kwfls\" ","response":"range_response_count:1 size:8468"} {"level":"info","ts":"2026-10-07T03:56:50.821964Z","caller":"traceutil/trace.go:171","msg":"trace[799156026] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-kwfls; range_end:; response_count:1; response_revision:5811; }","duration":"233.892996ms","start":"2026-10-07T03:56:50.58804Z","end":"2026-10-07T03:56:50.821933Z","steps":["trace[799156026] 'agreement among raft nodes before linearized reading' (duration: 233.468668ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:56:50.822279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T03:56:50.499525Z","time spent":"322.050432ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T03:56:50.909313Z","caller":"traceutil/trace.go:171","msg":"trace[1493326563] transaction","detail":"{read_only:false; response_revision:5812; number_of_response:1; }","duration":"108.371945ms","start":"2026-10-07T03:56:50.800907Z","end":"2026-10-07T03:56:50.909278Z","steps":["trace[1493326563] 'process raft request' (duration: 108.12603ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:56:50.909559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.764108ms","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-10-07T03:56:50.909674Z","caller":"traceutil/trace.go:171","msg":"trace[979572913] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5812; }","duration":"284.948493ms","start":"2026-10-07T03:56:50.624702Z","end":"2026-10-07T03:56:50.90965Z","steps":["trace[979572913] 'agreement among raft nodes before linearized reading' (duration: 284.591884ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:56:51.041358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.200322ms","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-10-07T03:56:51.041544Z","caller":"traceutil/trace.go:171","msg":"trace[2099557589] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5812; }","duration":"115.406326ms","start":"2026-10-07T03:56:50.926113Z","end":"2026-10-07T03:56:51.041519Z","steps":["trace[2099557589] 'range keys from in-memory index tree' (duration: 115.182921ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:56:51.09648Z","caller":"traceutil/trace.go:171","msg":"trace[1774630375] linearizableReadLoop","detail":"{readStateIndex:6029; appliedIndex:6028; }","duration":"187.201712ms","start":"2026-10-07T03:56:50.909235Z","end":"2026-10-07T03:56:51.096437Z","steps":["trace[1774630375] 'read index received' (duration: 187.024868ms)","trace[1774630375] 'applied index is now lower than readState.Index' (duration: 171.054µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T03:56:51.096649Z","caller":"traceutil/trace.go:171","msg":"trace[1924777970] transaction","detail":"{read_only:false; response_revision:5813; number_of_response:1; }","duration":"260.776759ms","start":"2026-10-07T03:56:50.835806Z","end":"2026-10-07T03:56:51.096583Z","steps":["trace[1924777970] 'process raft request' (duration: 260.41416ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:56:51.09687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.941953ms","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-10-07T03:56:51.096961Z","caller":"traceutil/trace.go:171","msg":"trace[637994247] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5813; }","duration":"246.096546ms","start":"2026-10-07T03:56:50.850842Z","end":"2026-10-07T03:56:51.096938Z","steps":["trace[637994247] 'agreement among raft nodes before linearized reading' (duration: 245.797309ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T03:56:51.164634Z","caller":"traceutil/trace.go:171","msg":"trace[195593102] transaction","detail":"{read_only:false; response_revision:5814; number_of_response:1; }","duration":"248.649509ms","start":"2026-10-07T03:56:50.915905Z","end":"2026-10-07T03:56:51.164554Z","steps":["trace[195593102] 'process raft request' (duration: 226.009288ms)","trace[195593102] 'compare' (duration: 22.424877ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T03:56:51.164751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.51003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T03:56:51.164822Z","caller":"traceutil/trace.go:171","msg":"trace[309057634] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5814; }","duration":"124.698263ms","start":"2026-10-07T03:56:51.040104Z","end":"2026-10-07T03:56:51.164802Z","steps":["trace[309057634] 'agreement among raft nodes before linearized reading' (duration: 124.524309ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T03:56:51.165068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.467106ms","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-10-07T03:56:51.165587Z","caller":"traceutil/trace.go:171","msg":"trace[1836134032] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5814; }","duration":"225.056278ms","start":"2026-10-07T03:56:50.940505Z","end":"2026-10-07T03:56:51.165561Z","steps":["trace[1836134032] 'agreement among raft nodes before linearized reading' (duration: 224.281811ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:01:43.713837Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5647} {"level":"info","ts":"2026-10-07T04:01:43.80274Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5647,"took":"86.741004ms","hash":4031996080,"current-db-size-bytes":55103488,"current-db-size":"55 MB","current-db-size-in-use-bytes":26365952,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-07T04:01:43.802823Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4031996080,"revision":5647,"compact-revision":2921} {"level":"info","ts":"2026-10-07T04:03:21.739414Z","caller":"traceutil/trace.go:171","msg":"trace[1658622529] linearizableReadLoop","detail":"{readStateIndex:9041; appliedIndex:9040; }","duration":"283.306654ms","start":"2026-10-07T04:03:21.456082Z","end":"2026-10-07T04:03:21.739388Z","steps":["trace[1658622529] 'read index received' (duration: 186.992897ms)","trace[1658622529] 'applied index is now lower than readState.Index' (duration: 96.312277ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:03:21.739512Z","caller":"traceutil/trace.go:171","msg":"trace[721144250] transaction","detail":"{read_only:false; response_revision:8732; number_of_response:1; }","duration":"346.425903ms","start":"2026-10-07T04:03:21.393046Z","end":"2026-10-07T04:03:21.739472Z","steps":["trace[721144250] 'process raft request' (duration: 250.082274ms)","trace[721144250] 'compare' (duration: 95.964681ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:03:21.739655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.57158ms","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-10-07T04:03:21.739694Z","caller":"traceutil/trace.go:171","msg":"trace[62367776] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8732; }","duration":"283.666031ms","start":"2026-10-07T04:03:21.456019Z","end":"2026-10-07T04:03:21.739685Z","steps":["trace[62367776] 'agreement among raft nodes before linearized reading' (duration: 283.509238ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:03:21.739762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:03:21.393012Z","time spent":"346.588786ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:03:21.739771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.287865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-10-07T04:03:21.739851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.175111ms","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-10-07T04:03:21.739929Z","caller":"traceutil/trace.go:171","msg":"trace[931550247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8732; }","duration":"196.504139ms","start":"2026-10-07T04:03:21.54341Z","end":"2026-10-07T04:03:21.739915Z","steps":["trace[931550247] 'agreement among raft nodes before linearized reading' (duration: 196.207552ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:03:21.739975Z","caller":"traceutil/trace.go:171","msg":"trace[516893248] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8732; }","duration":"276.356856ms","start":"2026-10-07T04:03:21.463591Z","end":"2026-10-07T04:03:21.739948Z","steps":["trace[516893248] 'agreement among raft nodes before linearized reading' (duration: 275.995307ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:03:21.740163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.398736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-10-07T04:03:21.740196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.163684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-10-07T04:03:21.740195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.68219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T04:03:21.740217Z","caller":"traceutil/trace.go:171","msg":"trace[538793170] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8732; }","duration":"196.525909ms","start":"2026-10-07T04:03:21.543678Z","end":"2026-10-07T04:03:21.740204Z","steps":["trace[538793170] 'agreement among raft nodes before linearized reading' (duration: 196.412796ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:03:21.740227Z","caller":"traceutil/trace.go:171","msg":"trace[1194222469] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8732; }","duration":"219.244266ms","start":"2026-10-07T04:03:21.520974Z","end":"2026-10-07T04:03:21.740218Z","steps":["trace[1194222469] 'agreement among raft nodes before linearized reading' (duration: 219.159753ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:03:21.740249Z","caller":"traceutil/trace.go:171","msg":"trace[694710529] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:8732; }","duration":"237.795382ms","start":"2026-10-07T04:03:21.502435Z","end":"2026-10-07T04:03:21.740231Z","steps":["trace[694710529] 'agreement among raft nodes before linearized reading' (duration: 237.70413ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:03:21.740371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.706986ms","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-10-07T04:03:21.740408Z","caller":"traceutil/trace.go:171","msg":"trace[1047418723] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8732; }","duration":"242.787478ms","start":"2026-10-07T04:03:21.497609Z","end":"2026-10-07T04:03:21.740397Z","steps":["trace[1047418723] 'agreement among raft nodes before linearized reading' (duration: 242.697056ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:03:48.006555Z","caller":"traceutil/trace.go:171","msg":"trace[1276597168] transaction","detail":"{read_only:false; response_revision:8924; number_of_response:1; }","duration":"104.766618ms","start":"2026-10-07T04:03:47.901758Z","end":"2026-10-07T04:03:48.006524Z","steps":["trace[1276597168] 'process raft request' (duration: 12.63977ms)","trace[1276597168] 'compare' (duration: 91.688709ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:03:48.006722Z","caller":"traceutil/trace.go:171","msg":"trace[1841321116] transaction","detail":"{read_only:false; response_revision:8925; number_of_response:1; }","duration":"102.882218ms","start":"2026-10-07T04:03:47.903812Z","end":"2026-10-07T04:03:48.006694Z","steps":["trace[1841321116] 'process raft request' (duration: 102.537921ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:03:50.900655Z","caller":"traceutil/trace.go:171","msg":"trace[1113487499] transaction","detail":"{read_only:false; response_revision:8941; number_of_response:1; }","duration":"252.739399ms","start":"2026-10-07T04:03:50.647892Z","end":"2026-10-07T04:03:50.900632Z","steps":["trace[1113487499] 'process raft request' (duration: 252.558465ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:03:51.087821Z","caller":"traceutil/trace.go:171","msg":"trace[2115234163] transaction","detail":"{read_only:false; response_revision:8942; number_of_response:1; }","duration":"182.420577ms","start":"2026-10-07T04:03:50.905378Z","end":"2026-10-07T04:03:51.087799Z","steps":["trace[2115234163] 'process raft request' (duration: 168.05416ms)","trace[2115234163] 'compare' (duration: 14.120771ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:03:53.450257Z","caller":"traceutil/trace.go:171","msg":"trace[789407747] linearizableReadLoop","detail":"{readStateIndex:9273; appliedIndex:9272; }","duration":"106.660599ms","start":"2026-10-07T04:03:53.343574Z","end":"2026-10-07T04:03:53.450235Z","steps":["trace[789407747] 'read index received' (duration: 106.509285ms)","trace[789407747] 'applied index is now lower than readState.Index' (duration: 150.143µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:03:53.450339Z","caller":"traceutil/trace.go:171","msg":"trace[1626393353] transaction","detail":"{read_only:false; response_revision:8954; number_of_response:1; }","duration":"108.836474ms","start":"2026-10-07T04:03:53.341465Z","end":"2026-10-07T04:03:53.450301Z","steps":["trace[1626393353] 'process raft request' (duration: 108.61296ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:03:53.450443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.850392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-07T04:03:53.450526Z","caller":"traceutil/trace.go:171","msg":"trace[1065245528] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8954; }","duration":"106.978934ms","start":"2026-10-07T04:03:53.343528Z","end":"2026-10-07T04:03:53.450507Z","steps":["trace[1065245528] 'agreement among raft nodes before linearized reading' (duration: 106.818261ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:04:00.226961Z","caller":"traceutil/trace.go:171","msg":"trace[1464692223] linearizableReadLoop","detail":"{readStateIndex:9310; appliedIndex:9309; }","duration":"150.563326ms","start":"2026-10-07T04:04:00.076357Z","end":"2026-10-07T04:04:00.22692Z","steps":["trace[1464692223] 'read index received' (duration: 150.235679ms)","trace[1464692223] 'applied index is now lower than readState.Index' (duration: 326.167µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:04:00.227163Z","caller":"traceutil/trace.go:171","msg":"trace[739359667] transaction","detail":"{read_only:false; response_revision:8990; number_of_response:1; }","duration":"154.360317ms","start":"2026-10-07T04:04:00.072788Z","end":"2026-10-07T04:04:00.227149Z","steps":["trace[739359667] 'process raft request' (duration: 153.868496ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:04:00.227433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.980241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-10-07T04:04:00.227485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.099857ms","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-10-07T04:04:00.227596Z","caller":"traceutil/trace.go:171","msg":"trace[526028050] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8990; }","duration":"151.273411ms","start":"2026-10-07T04:04:00.076299Z","end":"2026-10-07T04:04:00.227572Z","steps":["trace[526028050] 'agreement among raft nodes before linearized reading' (duration: 150.958494ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:04:00.227531Z","caller":"traceutil/trace.go:171","msg":"trace[1764466773] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8990; }","duration":"148.112484ms","start":"2026-10-07T04:04:00.079396Z","end":"2026-10-07T04:04:00.227509Z","steps":["trace[1764466773] 'agreement among raft nodes before linearized reading' (duration: 147.863859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:04:00.227689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.53368ms","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-10-07T04:04:00.227785Z","caller":"traceutil/trace.go:171","msg":"trace[1179997541] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8990; }","duration":"146.656272ms","start":"2026-10-07T04:04:00.081106Z","end":"2026-10-07T04:04:00.227762Z","steps":["trace[1179997541] 'agreement among raft nodes before linearized reading' (duration: 146.427788ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:04:00.741532Z","caller":"traceutil/trace.go:171","msg":"trace[198530749] transaction","detail":"{read_only:false; response_revision:8994; number_of_response:1; }","duration":"162.055252ms","start":"2026-10-07T04:04:00.579433Z","end":"2026-10-07T04:04:00.741488Z","steps":["trace[198530749] 'process raft request' (duration: 161.739425ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:04:01.065685Z","caller":"traceutil/trace.go:171","msg":"trace[1238190580] linearizableReadLoop","detail":"{readStateIndex:9316; appliedIndex:9315; }","duration":"104.725026ms","start":"2026-10-07T04:04:00.960932Z","end":"2026-10-07T04:04:01.065657Z","steps":["trace[1238190580] 'read index received' (duration: 104.551092ms)","trace[1238190580] 'applied index is now lower than readState.Index' (duration: 172.864µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:04:01.065793Z","caller":"traceutil/trace.go:171","msg":"trace[1663484506] transaction","detail":"{read_only:false; response_revision:8996; number_of_response:1; }","duration":"123.317523ms","start":"2026-10-07T04:04:00.942448Z","end":"2026-10-07T04:04:01.065766Z","steps":["trace[1663484506] 'process raft request' (duration: 123.017968ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:04:01.065927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.984522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-97wwt\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-10-07T04:04:01.065984Z","caller":"traceutil/trace.go:171","msg":"trace[8835465] range","detail":"{range_begin:/registry/pods/openstack/keepalived-97wwt; range_end:; response_count:1; response_revision:8996; }","duration":"105.107194ms","start":"2026-10-07T04:04:00.960863Z","end":"2026-10-07T04:04:01.06597Z","steps":["trace[8835465] 'agreement among raft nodes before linearized reading' (duration: 104.917821ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:04:04.380586Z","caller":"traceutil/trace.go:171","msg":"trace[1702686793] transaction","detail":"{read_only:false; response_revision:9023; number_of_response:1; }","duration":"108.164549ms","start":"2026-10-07T04:04:04.272398Z","end":"2026-10-07T04:04:04.380563Z","steps":["trace[1702686793] 'process raft request' (duration: 108.014016ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:04:04.430109Z","caller":"traceutil/trace.go:171","msg":"trace[993193053] transaction","detail":"{read_only:false; response_revision:9024; number_of_response:1; }","duration":"157.628577ms","start":"2026-10-07T04:04:04.272463Z","end":"2026-10-07T04:04:04.430091Z","steps":["trace[993193053] 'process raft request' (duration: 157.34156ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:04:04.430228Z","caller":"traceutil/trace.go:171","msg":"trace[917714170] linearizableReadLoop","detail":"{readStateIndex:9345; appliedIndex:9343; }","duration":"147.637924ms","start":"2026-10-07T04:04:04.282555Z","end":"2026-10-07T04:04:04.430193Z","steps":["trace[917714170] 'read index received' (duration: 97.818949ms)","trace[917714170] 'applied index is now lower than readState.Index' (duration: 49.816705ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:04:04.430776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.603633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-10-07T04:04:04.431025Z","caller":"traceutil/trace.go:171","msg":"trace[639062476] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:9025; }","duration":"118.958981ms","start":"2026-10-07T04:04:04.312028Z","end":"2026-10-07T04:04:04.430987Z","steps":["trace[639062476] 'agreement among raft nodes before linearized reading' (duration: 118.524781ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:04:04.431157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.674093ms","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-10-07T04:04:04.431519Z","caller":"traceutil/trace.go:171","msg":"trace[2103438940] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9025; }","duration":"147.105163ms","start":"2026-10-07T04:04:04.284396Z","end":"2026-10-07T04:04:04.431502Z","steps":["trace[2103438940] 'agreement among raft nodes before linearized reading' (duration: 146.02548ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:04:04.432311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.746099ms","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-10-07T04:04:04.432415Z","caller":"traceutil/trace.go:171","msg":"trace[916248567] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9025; }","duration":"149.848341ms","start":"2026-10-07T04:04:04.28255Z","end":"2026-10-07T04:04:04.432398Z","steps":["trace[916248567] 'agreement among raft nodes before linearized reading' (duration: 147.915339ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:04:16.810185Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002500.wal"} {"level":"info","ts":"2026-10-07T04:05:04.919145Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"308ce60d3e55f7ff","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T04:05:04.926505Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-10-07T04:05:04.926643Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-10-07T04:06:43.721122Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7962} {"level":"info","ts":"2026-10-07T04:06:43.796101Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7962,"took":"72.352856ms","hash":2182821806,"current-db-size-bytes":55103488,"current-db-size":"55 MB","current-db-size-in-use-bytes":27746304,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-07T04:06:43.796197Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2182821806,"revision":7962,"compact-revision":5647} {"level":"info","ts":"2026-10-07T04:11:43.73122Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10350} {"level":"info","ts":"2026-10-07T04:11:43.82516Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10350,"took":"91.107936ms","hash":2463884126,"current-db-size-bytes":55103488,"current-db-size":"55 MB","current-db-size-in-use-bytes":25575424,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-10-07T04:11:43.825227Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2463884126,"revision":10350,"compact-revision":7962} {"level":"info","ts":"2026-10-07T04:16:43.741775Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12519} {"level":"info","ts":"2026-10-07T04:16:43.856117Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12519,"took":"110.166124ms","hash":1852748084,"current-db-size-bytes":55103488,"current-db-size":"55 MB","current-db-size-in-use-bytes":27897856,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-07T04:16:43.8562Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1852748084,"revision":12519,"compact-revision":10350} {"level":"info","ts":"2026-10-07T04:16:59.975427Z","caller":"traceutil/trace.go:171","msg":"trace[1098962756] transaction","detail":"{read_only:false; response_revision:14680; number_of_response:1; }","duration":"130.916337ms","start":"2026-10-07T04:16:59.84446Z","end":"2026-10-07T04:16:59.975376Z","steps":["trace[1098962756] 'process raft request' (duration: 130.713573ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:17:24.863784Z","caller":"traceutil/trace.go:171","msg":"trace[1191574956] transaction","detail":"{read_only:false; response_revision:14814; number_of_response:1; }","duration":"131.00898ms","start":"2026-10-07T04:17:24.732742Z","end":"2026-10-07T04:17:24.863751Z","steps":["trace[1191574956] 'process raft request' (duration: 130.789725ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:17:34.428292Z","caller":"traceutil/trace.go:171","msg":"trace[100899618] transaction","detail":"{read_only:false; response_revision:14862; number_of_response:1; }","duration":"118.026711ms","start":"2026-10-07T04:17:34.310232Z","end":"2026-10-07T04:17:34.428258Z","steps":["trace[100899618] 'process raft request' (duration: 103.862569ms)","trace[100899618] 'compare' (duration: 14.006029ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:17:43.488427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.38626ms","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-10-07T04:17:43.488524Z","caller":"traceutil/trace.go:171","msg":"trace[692333096] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:14923; }","duration":"324.554423ms","start":"2026-10-07T04:17:43.163951Z","end":"2026-10-07T04:17:43.488505Z","steps":["trace[692333096] 'range keys from in-memory index tree' (duration: 324.176596ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:17:43.488561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:17:43.163869Z","time spent":"324.683606ms","remote":"127.0.0.1:47966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-10-07T04:17:43.489111Z","caller":"traceutil/trace.go:171","msg":"trace[299510820] transaction","detail":"{read_only:false; response_revision:14924; number_of_response:1; }","duration":"157.798111ms","start":"2026-10-07T04:17:43.331256Z","end":"2026-10-07T04:17:43.489054Z","steps":["trace[299510820] 'process raft request' (duration: 151.947486ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:21:43.750187Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14555} {"level":"info","ts":"2026-10-07T04:21:43.833877Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14555,"took":"80.584942ms","hash":3122660948,"current-db-size-bytes":55103488,"current-db-size":"55 MB","current-db-size-in-use-bytes":31494144,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-07T04:21:43.833967Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3122660948,"revision":14555,"compact-revision":12519} {"level":"warn","ts":"2026-10-07T04:25:44.525732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.73543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-lcksc\" ","response":"range_response_count:1 size:22133"} {"level":"info","ts":"2026-10-07T04:25:44.525827Z","caller":"traceutil/trace.go:171","msg":"trace[177472515] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-lcksc; range_end:; response_count:1; response_revision:18746; }","duration":"137.880763ms","start":"2026-10-07T04:25:44.387927Z","end":"2026-10-07T04:25:44.525808Z","steps":["trace[177472515] 'range keys from in-memory index tree' (duration: 137.540024ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:25:44.879433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.281458ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:25:44.879552Z","caller":"traceutil/trace.go:171","msg":"trace[1795711635] linearizableReadLoop","detail":"{readStateIndex:19393; appliedIndex:19392; }","duration":"102.74055ms","start":"2026-10-07T04:25:44.776793Z","end":"2026-10-07T04:25:44.879534Z","steps":["trace[1795711635] 'read index received' (duration: 44.28µs)","trace[1795711635] 'applied index is now lower than readState.Index' (duration: 102.694539ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:25:44.879718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.935823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-clhg8\" ","response":"range_response_count:1 size:9797"} {"level":"info","ts":"2026-10-07T04:25:44.879752Z","caller":"traceutil/trace.go:171","msg":"trace[949700448] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-clhg8; range_end:; response_count:1; response_revision:18748; }","duration":"102.970584ms","start":"2026-10-07T04:25:44.776772Z","end":"2026-10-07T04:25:44.879742Z","steps":["trace[949700448] 'agreement among raft nodes before linearized reading' (duration: 102.809541ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:25:44.879798Z","caller":"traceutil/trace.go:171","msg":"trace[1887774790] transaction","detail":"{read_only:false; response_revision:18748; number_of_response:1; }","duration":"266.308481ms","start":"2026-10-07T04:25:44.613458Z","end":"2026-10-07T04:25:44.879766Z","steps":["trace[1887774790] 'process raft request' (duration: 44.592354ms)","trace[1887774790] 'compare' (duration: 220.993082ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:25:45.19655Z","caller":"traceutil/trace.go:171","msg":"trace[1699943718] transaction","detail":"{read_only:false; response_revision:18750; number_of_response:1; }","duration":"227.090063ms","start":"2026-10-07T04:25:44.969425Z","end":"2026-10-07T04:25:45.196515Z","steps":["trace[1699943718] 'process raft request' (duration: 219.398589ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:25:45.196962Z","caller":"traceutil/trace.go:171","msg":"trace[455294655] linearizableReadLoop","detail":"{readStateIndex:19395; appliedIndex:19394; }","duration":"158.675878ms","start":"2026-10-07T04:25:45.038262Z","end":"2026-10-07T04:25:45.196938Z","steps":["trace[455294655] 'read index received' (duration: 150.546174ms)","trace[455294655] 'applied index is now lower than readState.Index' (duration: 8.127494ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:25:45.197071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.79405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:25:45.197108Z","caller":"traceutil/trace.go:171","msg":"trace[1857804992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18750; }","duration":"158.899152ms","start":"2026-10-07T04:25:45.038197Z","end":"2026-10-07T04:25:45.197096Z","steps":["trace[1857804992] 'agreement among raft nodes before linearized reading' (duration: 158.803431ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:25:54.157437Z","caller":"traceutil/trace.go:171","msg":"trace[463291151] transaction","detail":"{read_only:false; response_revision:18910; number_of_response:1; }","duration":"141.780257ms","start":"2026-10-07T04:25:54.015623Z","end":"2026-10-07T04:25:54.157403Z","steps":["trace[463291151] 'process raft request' (duration: 141.664944ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:25:54.157445Z","caller":"traceutil/trace.go:171","msg":"trace[179571084] transaction","detail":"{read_only:false; response_revision:18909; number_of_response:1; }","duration":"141.991681ms","start":"2026-10-07T04:25:54.015414Z","end":"2026-10-07T04:25:54.157406Z","steps":["trace[179571084] 'process raft request' (duration: 49.093322ms)","trace[179571084] 'compare' (duration: 92.431099ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:25:54.575505Z","caller":"traceutil/trace.go:171","msg":"trace[345192399] transaction","detail":"{read_only:false; response_revision:18918; number_of_response:1; }","duration":"212.49763ms","start":"2026-10-07T04:25:54.362969Z","end":"2026-10-07T04:25:54.575467Z","steps":["trace[345192399] 'process raft request' (duration: 212.195273ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:25:54.575566Z","caller":"traceutil/trace.go:171","msg":"trace[823436770] transaction","detail":"{read_only:false; response_revision:18920; number_of_response:1; }","duration":"212.433949ms","start":"2026-10-07T04:25:54.363088Z","end":"2026-10-07T04:25:54.575522Z","steps":["trace[823436770] 'process raft request' (duration: 212.308505ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:25:54.575576Z","caller":"traceutil/trace.go:171","msg":"trace[992076903] linearizableReadLoop","detail":"{readStateIndex:19571; appliedIndex:19566; }","duration":"207.059693ms","start":"2026-10-07T04:25:54.368492Z","end":"2026-10-07T04:25:54.575551Z","steps":["trace[992076903] 'read index received' (duration: 10.597027ms)","trace[992076903] 'applied index is now lower than readState.Index' (duration: 196.461316ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:25:54.575683Z","caller":"traceutil/trace.go:171","msg":"trace[374727263] transaction","detail":"{read_only:false; response_revision:18921; number_of_response:1; }","duration":"212.555031ms","start":"2026-10-07T04:25:54.363118Z","end":"2026-10-07T04:25:54.575673Z","steps":["trace[374727263] 'process raft request' (duration: 212.309256ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:25:54.575613Z","caller":"traceutil/trace.go:171","msg":"trace[201472597] transaction","detail":"{read_only:false; response_revision:18919; number_of_response:1; }","duration":"212.526961ms","start":"2026-10-07T04:25:54.363048Z","end":"2026-10-07T04:25:54.575574Z","steps":["trace[201472597] 'process raft request' (duration: 212.293086ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:25:54.575906Z","caller":"traceutil/trace.go:171","msg":"trace[834105610] transaction","detail":"{read_only:false; response_revision:18923; number_of_response:1; }","duration":"203.023327ms","start":"2026-10-07T04:25:54.372871Z","end":"2026-10-07T04:25:54.575894Z","steps":["trace[834105610] 'process raft request' (duration: 202.619519ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:25:54.57591Z","caller":"traceutil/trace.go:171","msg":"trace[156358427] transaction","detail":"{read_only:false; response_revision:18922; number_of_response:1; }","duration":"212.340356ms","start":"2026-10-07T04:25:54.363557Z","end":"2026-10-07T04:25:54.575897Z","steps":["trace[156358427] 'process raft request' (duration: 211.902577ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:25:54.576053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.483702ms","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-10-07T04:25:54.576167Z","caller":"traceutil/trace.go:171","msg":"trace[239233226] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-1; range_end:; response_count:0; response_revision:18923; }","duration":"207.692317ms","start":"2026-10-07T04:25:54.368455Z","end":"2026-10-07T04:25:54.576147Z","steps":["trace[239233226] 'agreement among raft nodes before linearized reading' (duration: 207.469872ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:25:54.581775Z","caller":"traceutil/trace.go:171","msg":"trace[2115631517] transaction","detail":"{read_only:false; response_revision:18924; number_of_response:1; }","duration":"137.474824ms","start":"2026-10-07T04:25:54.444274Z","end":"2026-10-07T04:25:54.581749Z","steps":["trace[2115631517] 'process raft request' (duration: 137.30845ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:25:54.581873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.516323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T04:25:54.581955Z","caller":"traceutil/trace.go:171","msg":"trace[2117469660] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:18924; }","duration":"129.680227ms","start":"2026-10-07T04:25:54.452261Z","end":"2026-10-07T04:25:54.581941Z","steps":["trace[2117469660] 'agreement among raft nodes before linearized reading' (duration: 129.453182ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:26:31.680285Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"308ce60d3e55f7ff","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T04:26:31.694683Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-10-07T04:26:31.694922Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-10-07T04:26:43.759918Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16924} {"level":"info","ts":"2026-10-07T04:26:43.884991Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16924,"took":"109.32257ms","hash":176983644,"current-db-size-bytes":55103488,"current-db-size":"55 MB","current-db-size-in-use-bytes":33300480,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-10-07T04:26:43.885158Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":176983644,"revision":16924,"compact-revision":14555} {"level":"info","ts":"2026-10-07T04:31:24.053794Z","caller":"traceutil/trace.go:171","msg":"trace[975965010] transaction","detail":"{read_only:false; response_revision:21399; number_of_response:1; }","duration":"125.472833ms","start":"2026-10-07T04:31:23.928289Z","end":"2026-10-07T04:31:24.053762Z","steps":["trace[975965010] 'process raft request' (duration: 124.680386ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:31:29.634535Z","caller":"traceutil/trace.go:171","msg":"trace[1975720029] transaction","detail":"{read_only:false; response_revision:21424; number_of_response:1; }","duration":"103.268017ms","start":"2026-10-07T04:31:29.531221Z","end":"2026-10-07T04:31:29.634489Z","steps":["trace[1975720029] 'process raft request' (duration: 102.48773ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:31:29.948387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.260601ms","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-10-07T04:31:29.948482Z","caller":"traceutil/trace.go:171","msg":"trace[688158844] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:21424; }","duration":"216.415814ms","start":"2026-10-07T04:31:29.732046Z","end":"2026-10-07T04:31:29.948461Z","steps":["trace[688158844] 'range keys from in-memory index tree' (duration: 216.066047ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:31:29.948982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.882964ms","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-10-07T04:31:29.949021Z","caller":"traceutil/trace.go:171","msg":"trace[277809941] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21424; }","duration":"175.968256ms","start":"2026-10-07T04:31:29.773043Z","end":"2026-10-07T04:31:29.949011Z","steps":["trace[277809941] 'range keys from in-memory index tree' (duration: 175.682231ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:31:29.949179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.142198ms","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-10-07T04:31:29.949276Z","caller":"traceutil/trace.go:171","msg":"trace[1937319648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21424; }","duration":"175.304552ms","start":"2026-10-07T04:31:29.77395Z","end":"2026-10-07T04:31:29.949254Z","steps":["trace[1937319648] 'range keys from in-memory index tree' (duration: 174.854893ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:31:29.949539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.413712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-07T04:31:29.949577Z","caller":"traceutil/trace.go:171","msg":"trace[1862662674] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:21424; }","duration":"173.452873ms","start":"2026-10-07T04:31:29.776114Z","end":"2026-10-07T04:31:29.949567Z","steps":["trace[1862662674] 'range keys from in-memory index tree' (duration: 173.226868ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:31:30.197536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.610384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:31:30.197798Z","caller":"traceutil/trace.go:171","msg":"trace[1191818872] transaction","detail":"{read_only:false; response_revision:21428; number_of_response:1; }","duration":"124.922931ms","start":"2026-10-07T04:31:30.072855Z","end":"2026-10-07T04:31:30.197778Z","steps":["trace[1191818872] 'process raft request' (duration: 124.862929ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:31:30.197839Z","caller":"traceutil/trace.go:171","msg":"trace[1765297802] transaction","detail":"{read_only:false; response_revision:21426; number_of_response:1; }","duration":"241.114175ms","start":"2026-10-07T04:31:29.956685Z","end":"2026-10-07T04:31:30.197799Z","steps":["trace[1765297802] 'process raft request' (duration: 102.127312ms)","trace[1765297802] 'compare' (duration: 138.437081ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:31:30.197836Z","caller":"traceutil/trace.go:171","msg":"trace[928791576] transaction","detail":"{read_only:false; response_revision:21427; number_of_response:1; }","duration":"238.214522ms","start":"2026-10-07T04:31:29.959569Z","end":"2026-10-07T04:31:30.197783Z","steps":["trace[928791576] 'process raft request' (duration: 238.082658ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:31:30.197989Z","caller":"traceutil/trace.go:171","msg":"trace[251686904] linearizableReadLoop","detail":"{readStateIndex:22159; appliedIndex:22157; }","duration":"240.242675ms","start":"2026-10-07T04:31:29.957707Z","end":"2026-10-07T04:31:30.197949Z","steps":["trace[251686904] 'read index received' (duration: 13.682643ms)","trace[251686904] 'applied index is now lower than readState.Index' (duration: 226.555522ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:31:30.198848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.730497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:31:30.199024Z","caller":"traceutil/trace.go:171","msg":"trace[1402360605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21428; }","duration":"159.924053ms","start":"2026-10-07T04:31:30.039023Z","end":"2026-10-07T04:31:30.198947Z","steps":["trace[1402360605] 'agreement among raft nodes before linearized reading' (duration: 159.184687ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:31:30.19964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.911349ms","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-10-07T04:31:30.19975Z","caller":"traceutil/trace.go:171","msg":"trace[1622093368] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21428; }","duration":"117.051281ms","start":"2026-10-07T04:31:30.082661Z","end":"2026-10-07T04:31:30.199712Z","steps":["trace[1622093368] 'agreement among raft nodes before linearized reading' (duration: 115.57805ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:31:30.19989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.146117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-07T04:31:30.200083Z","caller":"traceutil/trace.go:171","msg":"trace[995780840] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:21428; }","duration":"242.367871ms","start":"2026-10-07T04:31:29.957635Z","end":"2026-10-07T04:31:30.200003Z","steps":["trace[995780840] 'agreement among raft nodes before linearized reading' (duration: 240.383309ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:31:30.436461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.949755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:31:30.436675Z","caller":"traceutil/trace.go:171","msg":"trace[132400609] linearizableReadLoop","detail":"{readStateIndex:22163; appliedIndex:22162; }","duration":"217.316884ms","start":"2026-10-07T04:31:30.21933Z","end":"2026-10-07T04:31:30.436647Z","steps":["trace[132400609] 'read index received' (duration: 44.375753ms)","trace[132400609] 'applied index is now lower than readState.Index' (duration: 172.938021ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:31:30.436813Z","caller":"traceutil/trace.go:171","msg":"trace[790094785] transaction","detail":"{read_only:false; response_revision:21430; number_of_response:1; }","duration":"228.122805ms","start":"2026-10-07T04:31:30.208664Z","end":"2026-10-07T04:31:30.436787Z","steps":["trace[790094785] 'process raft request' (duration: 93.679691ms)","trace[790094785] 'compare' (duration: 133.035625ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:31:30.437035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.700912ms","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-10-07T04:31:30.437102Z","caller":"traceutil/trace.go:171","msg":"trace[238526803] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:21430; }","duration":"217.769883ms","start":"2026-10-07T04:31:30.219311Z","end":"2026-10-07T04:31:30.437081Z","steps":["trace[238526803] 'agreement among raft nodes before linearized reading' (duration: 217.410876ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:31:43.773209Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19426} {"level":"info","ts":"2026-10-07T04:31:43.931461Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19426,"took":"154.162078ms","hash":4199858802,"current-db-size-bytes":55103488,"current-db-size":"55 MB","current-db-size-in-use-bytes":29208576,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-07T04:31:43.931535Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4199858802,"revision":19426,"compact-revision":16924} {"level":"info","ts":"2026-10-07T04:35:35.70232Z","caller":"traceutil/trace.go:171","msg":"trace[1680924240] linearizableReadLoop","detail":"{readStateIndex:24752; appliedIndex:24751; }","duration":"149.303454ms","start":"2026-10-07T04:35:35.55299Z","end":"2026-10-07T04:35:35.702293Z","steps":["trace[1680924240] 'read index received' (duration: 149.136661ms)","trace[1680924240] 'applied index is now lower than readState.Index' (duration: 165.673µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:35:35.702403Z","caller":"traceutil/trace.go:171","msg":"trace[390108137] transaction","detail":"{read_only:false; response_revision:23946; number_of_response:1; }","duration":"258.247182ms","start":"2026-10-07T04:35:35.444115Z","end":"2026-10-07T04:35:35.702363Z","steps":["trace[390108137] 'process raft request' (duration: 257.992506ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:35:35.7025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.498228ms","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:6"} {"level":"info","ts":"2026-10-07T04:35:35.702546Z","caller":"traceutil/trace.go:171","msg":"trace[805871738] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:23946; }","duration":"149.621371ms","start":"2026-10-07T04:35:35.552913Z","end":"2026-10-07T04:35:35.702535Z","steps":["trace[805871738] 'agreement among raft nodes before linearized reading' (duration: 149.51577ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:35:35.729326Z","caller":"traceutil/trace.go:171","msg":"trace[762037524] transaction","detail":"{read_only:false; response_revision:23947; number_of_response:1; }","duration":"107.16582ms","start":"2026-10-07T04:35:35.62211Z","end":"2026-10-07T04:35:35.729276Z","steps":["trace[762037524] 'process raft request' (duration: 106.926995ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:35:35.729415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.857377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-07T04:35:35.729504Z","caller":"traceutil/trace.go:171","msg":"trace[638294368] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23947; }","duration":"103.000051ms","start":"2026-10-07T04:35:35.626485Z","end":"2026-10-07T04:35:35.729485Z","steps":["trace[638294368] 'agreement among raft nodes before linearized reading' (duration: 102.738886ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:36:43.780765Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21499} {"level":"info","ts":"2026-10-07T04:36:43.893326Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21499,"took":"108.377565ms","hash":1125289759,"current-db-size-bytes":56901632,"current-db-size":"57 MB","current-db-size-in-use-bytes":42995712,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-10-07T04:36:43.89341Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1125289759,"revision":21499,"compact-revision":19426} {"level":"info","ts":"2026-10-07T04:38:55.086895Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006899.wal"} {"level":"warn","ts":"2026-10-07T04:39:05.357412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.466868ms","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-10-07T04:39:05.357527Z","caller":"traceutil/trace.go:171","msg":"trace[515699794] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26031; }","duration":"129.650182ms","start":"2026-10-07T04:39:05.227853Z","end":"2026-10-07T04:39:05.357503Z","steps":["trace[515699794] 'range keys from in-memory index tree' (duration: 129.244103ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:06.315481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.72175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-07T04:39:06.315606Z","caller":"traceutil/trace.go:171","msg":"trace[1021408318] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:26035; }","duration":"140.986294ms","start":"2026-10-07T04:39:06.174592Z","end":"2026-10-07T04:39:06.315579Z","steps":["trace[1021408318] 'count revisions from in-memory index tree' (duration: 140.568576ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:10.722073Z","caller":"traceutil/trace.go:171","msg":"trace[1560213208] transaction","detail":"{read_only:false; response_revision:26067; number_of_response:1; }","duration":"107.326503ms","start":"2026-10-07T04:39:10.614708Z","end":"2026-10-07T04:39:10.722034Z","steps":["trace[1560213208] 'process raft request' (duration: 107.051257ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:10.939478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.3867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T04:39:10.939638Z","caller":"traceutil/trace.go:171","msg":"trace[1149751865] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:26068; }","duration":"111.614655ms","start":"2026-10-07T04:39:10.828003Z","end":"2026-10-07T04:39:10.939617Z","steps":["trace[1149751865] 'count revisions from in-memory index tree' (duration: 111.254258ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:11.234271Z","caller":"traceutil/trace.go:171","msg":"trace[390251640] transaction","detail":"{read_only:false; response_revision:26070; number_of_response:1; }","duration":"151.657994ms","start":"2026-10-07T04:39:11.082577Z","end":"2026-10-07T04:39:11.234235Z","steps":["trace[390251640] 'process raft request' (duration: 121.985097ms)","trace[390251640] 'compare' (duration: 29.35933ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:11.293194Z","caller":"traceutil/trace.go:171","msg":"trace[2089390323] transaction","detail":"{read_only:false; response_revision:26071; number_of_response:1; }","duration":"209.276891ms","start":"2026-10-07T04:39:11.083876Z","end":"2026-10-07T04:39:11.293153Z","steps":["trace[2089390323] 'process raft request' (duration: 208.989676ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:11.293476Z","caller":"traceutil/trace.go:171","msg":"trace[21289001] transaction","detail":"{read_only:false; response_revision:26072; number_of_response:1; }","duration":"176.441977ms","start":"2026-10-07T04:39:11.116986Z","end":"2026-10-07T04:39:11.293428Z","steps":["trace[21289001] 'process raft request' (duration: 176.062719ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:11.293544Z","caller":"traceutil/trace.go:171","msg":"trace[1877380335] transaction","detail":"{read_only:false; response_revision:26073; number_of_response:1; }","duration":"175.098228ms","start":"2026-10-07T04:39:11.118415Z","end":"2026-10-07T04:39:11.293514Z","steps":["trace[1877380335] 'process raft request' (duration: 174.876153ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:11.293808Z","caller":"traceutil/trace.go:171","msg":"trace[730825127] transaction","detail":"{read_only:false; response_revision:26074; number_of_response:1; }","duration":"112.478424ms","start":"2026-10-07T04:39:11.181311Z","end":"2026-10-07T04:39:11.29379Z","steps":["trace[730825127] 'process raft request' (duration: 112.128796ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:11.522469Z","caller":"traceutil/trace.go:171","msg":"trace[531726997] transaction","detail":"{read_only:false; response_revision:26075; number_of_response:1; }","duration":"118.134465ms","start":"2026-10-07T04:39:11.404305Z","end":"2026-10-07T04:39:11.52244Z","steps":["trace[531726997] 'process raft request' (duration: 117.938421ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:11.7475Z","caller":"traceutil/trace.go:171","msg":"trace[1473532462] transaction","detail":"{read_only:false; response_revision:26077; number_of_response:1; }","duration":"187.127926ms","start":"2026-10-07T04:39:11.560344Z","end":"2026-10-07T04:39:11.747472Z","steps":["trace[1473532462] 'process raft request' (duration: 186.899661ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:12.502789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.358514ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-07T04:39:12.502884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.373765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-10-07T04:39:12.502918Z","caller":"traceutil/trace.go:171","msg":"trace[1143971851] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:26078; }","duration":"278.492276ms","start":"2026-10-07T04:39:12.224377Z","end":"2026-10-07T04:39:12.502869Z","steps":["trace[1143971851] 'count revisions from in-memory index tree' (duration: 278.247501ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:12.502961Z","caller":"traceutil/trace.go:171","msg":"trace[1617908867] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26078; }","duration":"204.567251ms","start":"2026-10-07T04:39:12.298369Z","end":"2026-10-07T04:39:12.502936Z","steps":["trace[1617908867] 'range keys from in-memory index tree' (duration: 204.08157ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:12.50256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.815034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:12.503658Z","caller":"traceutil/trace.go:171","msg":"trace[1812460666] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:26078; }","duration":"300.967078ms","start":"2026-10-07T04:39:12.202637Z","end":"2026-10-07T04:39:12.503604Z","steps":["trace[1812460666] 'range keys from in-memory index tree' (duration: 299.683161ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:12.503826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:12.202612Z","time spent":"301.147872ms","remote":"127.0.0.1:47978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-10-07T04:39:12.546074Z","caller":"traceutil/trace.go:171","msg":"trace[1867161480] linearizableReadLoop","detail":"{readStateIndex:26939; appliedIndex:26938; }","duration":"111.321939ms","start":"2026-10-07T04:39:12.434715Z","end":"2026-10-07T04:39:12.546037Z","steps":["trace[1867161480] 'read index received' (duration: 87.14782ms)","trace[1867161480] 'applied index is now lower than readState.Index' (duration: 24.172039ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:12.546459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.711467ms","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-10-07T04:39:12.546513Z","caller":"traceutil/trace.go:171","msg":"trace[1475715149] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26078; }","duration":"111.84212ms","start":"2026-10-07T04:39:12.434643Z","end":"2026-10-07T04:39:12.546485Z","steps":["trace[1475715149] 'agreement among raft nodes before linearized reading' (duration: 111.619835ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:12.771646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.044516ms","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-10-07T04:39:12.771718Z","caller":"traceutil/trace.go:171","msg":"trace[1366645749] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26079; }","duration":"141.178349ms","start":"2026-10-07T04:39:12.630524Z","end":"2026-10-07T04:39:12.771703Z","steps":["trace[1366645749] 'range keys from in-memory index tree' (duration: 140.911194ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:13.045835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.337508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:13.045951Z","caller":"traceutil/trace.go:171","msg":"trace[1347914284] linearizableReadLoop","detail":"{readStateIndex:26942; appliedIndex:26941; }","duration":"270.654138ms","start":"2026-10-07T04:39:12.775279Z","end":"2026-10-07T04:39:13.045933Z","steps":["trace[1347914284] 'read index received' (duration: 126.162637ms)","trace[1347914284] 'applied index is now lower than readState.Index' (duration: 144.490131ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:13.046017Z","caller":"traceutil/trace.go:171","msg":"trace[615723401] transaction","detail":"{read_only:false; response_revision:26081; number_of_response:1; }","duration":"315.9177ms","start":"2026-10-07T04:39:12.730055Z","end":"2026-10-07T04:39:13.045973Z","steps":["trace[615723401] 'process raft request' (duration: 171.337127ms)","trace[615723401] 'compare' (duration: 143.917138ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:13.046322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:12.730008Z","time spent":"316.121233ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:39:13.04711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.211529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:13.047254Z","caller":"traceutil/trace.go:171","msg":"trace[22084304] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26081; }","duration":"271.961085ms","start":"2026-10-07T04:39:12.775259Z","end":"2026-10-07T04:39:13.04722Z","steps":["trace[22084304] 'agreement among raft nodes before linearized reading' (duration: 270.747469ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:13.346278Z","caller":"traceutil/trace.go:171","msg":"trace[1537236190] transaction","detail":"{read_only:false; response_revision:26082; number_of_response:1; }","duration":"259.627422ms","start":"2026-10-07T04:39:13.086617Z","end":"2026-10-07T04:39:13.346245Z","steps":["trace[1537236190] 'process raft request' (duration: 259.382517ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:13.346824Z","caller":"traceutil/trace.go:171","msg":"trace[1959395064] linearizableReadLoop","detail":"{readStateIndex:26943; appliedIndex:26943; }","duration":"111.343629ms","start":"2026-10-07T04:39:13.235441Z","end":"2026-10-07T04:39:13.346784Z","steps":["trace[1959395064] 'read index received' (duration: 111.322108ms)","trace[1959395064] 'applied index is now lower than readState.Index' (duration: 17.781µs)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:13.346989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.529493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:13.347034Z","caller":"traceutil/trace.go:171","msg":"trace[1600108644] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:26082; }","duration":"111.666426ms","start":"2026-10-07T04:39:13.235355Z","end":"2026-10-07T04:39:13.347022Z","steps":["trace[1600108644] 'agreement among raft nodes before linearized reading' (duration: 111.546074ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:13.374862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.473054ms","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-10-07T04:39:13.374925Z","caller":"traceutil/trace.go:171","msg":"trace[1807575836] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26085; }","duration":"133.564437ms","start":"2026-10-07T04:39:13.241346Z","end":"2026-10-07T04:39:13.374911Z","steps":["trace[1807575836] 'agreement among raft nodes before linearized reading' (duration: 133.366562ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:13.55302Z","caller":"traceutil/trace.go:171","msg":"trace[135925126] transaction","detail":"{read_only:false; response_revision:26087; number_of_response:1; }","duration":"172.460301ms","start":"2026-10-07T04:39:13.380527Z","end":"2026-10-07T04:39:13.552988Z","steps":["trace[135925126] 'process raft request' (duration: 172.291517ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:13.552953Z","caller":"traceutil/trace.go:171","msg":"trace[876336716] transaction","detail":"{read_only:false; response_revision:26086; number_of_response:1; }","duration":"172.4392ms","start":"2026-10-07T04:39:13.380471Z","end":"2026-10-07T04:39:13.55291Z","steps":["trace[876336716] 'process raft request' (duration: 130.160943ms)","trace[876336716] 'compare' (duration: 41.801637ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:13.872925Z","caller":"traceutil/trace.go:171","msg":"trace[1537121138] transaction","detail":"{read_only:false; response_revision:26088; number_of_response:1; }","duration":"343.721676ms","start":"2026-10-07T04:39:13.529169Z","end":"2026-10-07T04:39:13.872891Z","steps":["trace[1537121138] 'process raft request' (duration: 343.507621ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:13.873104Z","caller":"traceutil/trace.go:171","msg":"trace[364161861] linearizableReadLoop","detail":"{readStateIndex:26949; appliedIndex:26949; }","duration":"320.105509ms","start":"2026-10-07T04:39:13.552938Z","end":"2026-10-07T04:39:13.873044Z","steps":["trace[364161861] 'read index received' (duration: 320.090928ms)","trace[364161861] 'applied index is now lower than readState.Index' (duration: 11.511µs)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:13.873166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:13.529136Z","time spent":"343.891579ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:39:13.873345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.648855ms","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-10-07T04:39:13.87339Z","caller":"traceutil/trace.go:171","msg":"trace[942420709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26088; }","duration":"343.708425ms","start":"2026-10-07T04:39:13.529669Z","end":"2026-10-07T04:39:13.873378Z","steps":["trace[942420709] 'agreement among raft nodes before linearized reading' (duration: 343.556892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:13.873424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:13.529659Z","time spent":"343.757535ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-07T04:39:13.873538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.490937ms","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-10-07T04:39:13.873622Z","caller":"traceutil/trace.go:171","msg":"trace[1489206122] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:26088; }","duration":"279.651041ms","start":"2026-10-07T04:39:13.593947Z","end":"2026-10-07T04:39:13.873598Z","steps":["trace[1489206122] 'agreement among raft nodes before linearized reading' (duration: 279.484787ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:13.873795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.770401ms","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-10-07T04:39:13.873933Z","caller":"traceutil/trace.go:171","msg":"trace[1702914295] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26088; }","duration":"120.957325ms","start":"2026-10-07T04:39:13.752937Z","end":"2026-10-07T04:39:13.873894Z","steps":["trace[1702914295] 'agreement among raft nodes before linearized reading' (duration: 120.643859ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:14.126749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.771524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:14.127564Z","caller":"traceutil/trace.go:171","msg":"trace[1335642061] transaction","detail":"{read_only:false; response_revision:26089; number_of_response:1; }","duration":"248.276308ms","start":"2026-10-07T04:39:13.879268Z","end":"2026-10-07T04:39:14.127544Z","steps":["trace[1335642061] 'process raft request' (duration: 97.537834ms)","trace[1335642061] 'compare' (duration: 149.493248ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:14.127362Z","caller":"traceutil/trace.go:171","msg":"trace[1485249201] transaction","detail":"{read_only:false; response_revision:26090; number_of_response:1; }","duration":"246.720414ms","start":"2026-10-07T04:39:13.8806Z","end":"2026-10-07T04:39:14.12732Z","steps":["trace[1485249201] 'process raft request' (duration: 246.294165ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:14.32995Z","caller":"traceutil/trace.go:171","msg":"trace[255434732] transaction","detail":"{read_only:false; response_revision:26091; number_of_response:1; }","duration":"133.233879ms","start":"2026-10-07T04:39:14.196682Z","end":"2026-10-07T04:39:14.329916Z","steps":["trace[255434732] 'process raft request' (duration: 126.144127ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:14.880271Z","caller":"traceutil/trace.go:171","msg":"trace[77341741] linearizableReadLoop","detail":"{readStateIndex:26954; appliedIndex:26953; }","duration":"181.170698ms","start":"2026-10-07T04:39:14.699067Z","end":"2026-10-07T04:39:14.880238Z","steps":["trace[77341741] 'read index received' (duration: 180.79411ms)","trace[77341741] 'applied index is now lower than readState.Index' (duration: 375.428µs)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:14.880502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.394143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T04:39:14.880471Z","caller":"traceutil/trace.go:171","msg":"trace[1644230292] transaction","detail":"{read_only:false; response_revision:26093; number_of_response:1; }","duration":"292.105449ms","start":"2026-10-07T04:39:14.588298Z","end":"2026-10-07T04:39:14.880403Z","steps":["trace[1644230292] 'process raft request' (duration: 291.550306ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:14.880567Z","caller":"traceutil/trace.go:171","msg":"trace[1518628275] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:26093; }","duration":"181.488054ms","start":"2026-10-07T04:39:14.699058Z","end":"2026-10-07T04:39:14.880546Z","steps":["trace[1518628275] 'agreement among raft nodes before linearized reading' (duration: 181.30292ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:15.304816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.101837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:15.305038Z","caller":"traceutil/trace.go:171","msg":"trace[376835664] transaction","detail":"{read_only:false; response_revision:26095; number_of_response:1; }","duration":"252.378056ms","start":"2026-10-07T04:39:15.052635Z","end":"2026-10-07T04:39:15.305013Z","steps":["trace[376835664] 'process raft request' (duration: 252.297964ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:15.305207Z","caller":"traceutil/trace.go:171","msg":"trace[376162926] linearizableReadLoop","detail":"{readStateIndex:26955; appliedIndex:26954; }","duration":"266.254124ms","start":"2026-10-07T04:39:15.038927Z","end":"2026-10-07T04:39:15.305181Z","steps":["trace[376162926] 'read index received' (duration: 28.636296ms)","trace[376162926] 'applied index is now lower than readState.Index' (duration: 237.614608ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:15.305207Z","caller":"traceutil/trace.go:171","msg":"trace[1415576502] transaction","detail":"{read_only:false; response_revision:26094; number_of_response:1; }","duration":"527.116881ms","start":"2026-10-07T04:39:14.778044Z","end":"2026-10-07T04:39:15.305161Z","steps":["trace[1415576502] 'process raft request' (duration: 289.559414ms)","trace[1415576502] 'compare' (duration: 236.75394ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:15.30535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.419767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:15.305394Z","caller":"traceutil/trace.go:171","msg":"trace[648122705] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26095; }","duration":"266.526369ms","start":"2026-10-07T04:39:15.038858Z","end":"2026-10-07T04:39:15.305385Z","steps":["trace[648122705] 'agreement among raft nodes before linearized reading' (duration: 266.418157ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:15.305394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:14.778001Z","time spent":"527.290855ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:39:15.307977Z","caller":"traceutil/trace.go:171","msg":"trace[1932522940] transaction","detail":"{read_only:false; response_revision:26096; number_of_response:1; }","duration":"105.262269ms","start":"2026-10-07T04:39:15.202677Z","end":"2026-10-07T04:39:15.30794Z","steps":["trace[1932522940] 'process raft request' (duration: 104.740368ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:15.477013Z","caller":"traceutil/trace.go:171","msg":"trace[2134454936] transaction","detail":"{read_only:false; response_revision:26097; number_of_response:1; }","duration":"119.057585ms","start":"2026-10-07T04:39:15.35792Z","end":"2026-10-07T04:39:15.476978Z","steps":["trace[2134454936] 'process raft request' (duration: 117.199675ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:15.747807Z","caller":"traceutil/trace.go:171","msg":"trace[562881816] transaction","detail":"{read_only:false; response_revision:26098; number_of_response:1; }","duration":"366.884114ms","start":"2026-10-07T04:39:15.380891Z","end":"2026-10-07T04:39:15.747775Z","steps":["trace[562881816] 'process raft request' (duration: 279.161151ms)","trace[562881816] 'compare' (duration: 87.536328ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:15.747982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:15.380859Z","time spent":"367.033126ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:39:15.74981Z","caller":"traceutil/trace.go:171","msg":"trace[154921403] transaction","detail":"{read_only:false; response_revision:26099; number_of_response:1; }","duration":"367.971407ms","start":"2026-10-07T04:39:15.381822Z","end":"2026-10-07T04:39:15.749794Z","steps":["trace[154921403] 'process raft request' (duration: 365.916263ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:15.749882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:15.381808Z","time spent":"368.037788ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:39:15.750359Z","caller":"traceutil/trace.go:171","msg":"trace[1339509717] transaction","detail":"{read_only:false; response_revision:26100; number_of_response:1; }","duration":"368.214602ms","start":"2026-10-07T04:39:15.382131Z","end":"2026-10-07T04:39:15.750346Z","steps":["trace[1339509717] 'process raft request' (duration: 367.861364ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:15.750412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:15.382121Z","time spent":"368.266362ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:39:15.750683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.409979ms","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-10-07T04:39:15.750786Z","caller":"traceutil/trace.go:171","msg":"trace[379856237] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26100; }","duration":"192.582783ms","start":"2026-10-07T04:39:15.558188Z","end":"2026-10-07T04:39:15.750771Z","steps":["trace[379856237] 'agreement among raft nodes before linearized reading' (duration: 192.346328ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:15.752442Z","caller":"traceutil/trace.go:171","msg":"trace[1703492276] linearizableReadLoop","detail":"{readStateIndex:26961; appliedIndex:26958; }","duration":"192.028141ms","start":"2026-10-07T04:39:15.558247Z","end":"2026-10-07T04:39:15.750275Z","steps":["trace[1703492276] 'read index received' (duration: 102.109081ms)","trace[1703492276] 'applied index is now lower than readState.Index' (duration: 89.91777ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:15.752938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.185647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-07T04:39:15.753033Z","caller":"traceutil/trace.go:171","msg":"trace[1398779879] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26100; }","duration":"194.366652ms","start":"2026-10-07T04:39:15.558642Z","end":"2026-10-07T04:39:15.753009Z","steps":["trace[1398779879] 'agreement among raft nodes before linearized reading' (duration: 194.100986ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:15.754144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.21052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-10-07T04:39:15.754192Z","caller":"traceutil/trace.go:171","msg":"trace[2129365837] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:26100; }","duration":"128.294923ms","start":"2026-10-07T04:39:15.625888Z","end":"2026-10-07T04:39:15.754183Z","steps":["trace[2129365837] 'agreement among raft nodes before linearized reading' (duration: 126.952224ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:16.007913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.856341ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:16.008158Z","caller":"traceutil/trace.go:171","msg":"trace[1922077347] transaction","detail":"{read_only:false; response_revision:26102; number_of_response:1; }","duration":"248.964213ms","start":"2026-10-07T04:39:15.759172Z","end":"2026-10-07T04:39:16.008136Z","steps":["trace[1922077347] 'process raft request' (duration: 248.85824ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:16.008393Z","caller":"traceutil/trace.go:171","msg":"trace[120801222] transaction","detail":"{read_only:false; response_revision:26101; number_of_response:1; }","duration":"250.745911ms","start":"2026-10-07T04:39:15.757586Z","end":"2026-10-07T04:39:16.008332Z","steps":["trace[120801222] 'process raft request' (duration: 96.347428ms)","trace[120801222] 'compare' (duration: 153.715548ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:16.00863Z","caller":"traceutil/trace.go:171","msg":"trace[483669077] transaction","detail":"{read_only:false; response_revision:26103; number_of_response:1; }","duration":"128.15817ms","start":"2026-10-07T04:39:15.880416Z","end":"2026-10-07T04:39:16.008575Z","steps":["trace[483669077] 'process raft request' (duration: 127.987897ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:16.349485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.371342ms","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-10-07T04:39:16.349657Z","caller":"traceutil/trace.go:171","msg":"trace[2105474555] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26103; }","duration":"215.604316ms","start":"2026-10-07T04:39:16.134022Z","end":"2026-10-07T04:39:16.349626Z","steps":["trace[2105474555] 'range keys from in-memory index tree' (duration: 215.090395ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:16.350454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.418506ms","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-10-07T04:39:16.351418Z","caller":"traceutil/trace.go:171","msg":"trace[1249109980] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26103; }","duration":"218.249104ms","start":"2026-10-07T04:39:16.132343Z","end":"2026-10-07T04:39:16.350592Z","steps":["trace[1249109980] 'range keys from in-memory index tree' (duration: 217.15822ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:16.439286Z","caller":"traceutil/trace.go:171","msg":"trace[250140553] transaction","detail":"{read_only:false; response_revision:26104; number_of_response:1; }","duration":"101.6341ms","start":"2026-10-07T04:39:16.33762Z","end":"2026-10-07T04:39:16.439254Z","steps":["trace[250140553] 'process raft request' (duration: 101.400415ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:28.743376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.362359ms","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-10-07T04:39:28.743496Z","caller":"traceutil/trace.go:171","msg":"trace[453472999] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:26247; }","duration":"288.546601ms","start":"2026-10-07T04:39:28.454922Z","end":"2026-10-07T04:39:28.743468Z","steps":["trace[453472999] 'range keys from in-memory index tree' (duration: 288.273397ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:28.82921Z","caller":"traceutil/trace.go:171","msg":"trace[127682319] linearizableReadLoop","detail":"{readStateIndex:27112; appliedIndex:27111; }","duration":"301.412729ms","start":"2026-10-07T04:39:28.527764Z","end":"2026-10-07T04:39:28.829177Z","steps":["trace[127682319] 'read index received' (duration: 301.203694ms)","trace[127682319] 'applied index is now lower than readState.Index' (duration: 207.985µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:28.82937Z","caller":"traceutil/trace.go:171","msg":"trace[1859192642] transaction","detail":"{read_only:false; response_revision:26248; number_of_response:1; }","duration":"341.654171ms","start":"2026-10-07T04:39:28.487699Z","end":"2026-10-07T04:39:28.829353Z","steps":["trace[1859192642] 'process raft request' (duration: 341.282004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:28.829513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:28.487676Z","time spent":"341.728604ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:39:28.829688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.884839ms","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-10-07T04:39:28.829817Z","caller":"traceutil/trace.go:171","msg":"trace[44192538] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26248; }","duration":"302.056691ms","start":"2026-10-07T04:39:28.527728Z","end":"2026-10-07T04:39:28.829785Z","steps":["trace[44192538] 'agreement among raft nodes before linearized reading' (duration: 301.754875ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:28.829819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.933847ms","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":"warn","ts":"2026-10-07T04:39:28.829886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:28.527712Z","time spent":"302.158394ms","remote":"127.0.0.1:48052","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":"info","ts":"2026-10-07T04:39:28.829932Z","caller":"traceutil/trace.go:171","msg":"trace[333869273] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26248; }","duration":"301.084811ms","start":"2026-10-07T04:39:28.528817Z","end":"2026-10-07T04:39:28.829902Z","steps":["trace[333869273] 'agreement among raft nodes before linearized reading' (duration: 300.677892ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:28.829997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:28.528809Z","time spent":"301.176923ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-10-07T04:39:28.830127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.30781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2f7c43d7-d32b-48cf-a688-1162e599ca58\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-10-07T04:39:28.830183Z","caller":"traceutil/trace.go:171","msg":"trace[773143351] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2f7c43d7-d32b-48cf-a688-1162e599ca58; range_end:; response_count:1; response_revision:26248; }","duration":"139.35957ms","start":"2026-10-07T04:39:28.690806Z","end":"2026-10-07T04:39:28.830165Z","steps":["trace[773143351] 'agreement among raft nodes before linearized reading' (duration: 139.159836ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:28.83022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.886848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:28.830265Z","caller":"traceutil/trace.go:171","msg":"trace[544857603] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:26248; }","duration":"185.97332ms","start":"2026-10-07T04:39:28.644277Z","end":"2026-10-07T04:39:28.83025Z","steps":["trace[544857603] 'agreement among raft nodes before linearized reading' (duration: 185.891108ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:28.942118Z","caller":"traceutil/trace.go:171","msg":"trace[884893767] linearizableReadLoop","detail":"{readStateIndex:27113; appliedIndex:27112; }","duration":"106.203019ms","start":"2026-10-07T04:39:28.835857Z","end":"2026-10-07T04:39:28.94206Z","steps":["trace[884893767] 'read index received' (duration: 105.869531ms)","trace[884893767] 'applied index is now lower than readState.Index' (duration: 331.798µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:28.942314Z","caller":"traceutil/trace.go:171","msg":"trace[75722536] transaction","detail":"{read_only:false; response_revision:26249; number_of_response:1; }","duration":"106.927325ms","start":"2026-10-07T04:39:28.835318Z","end":"2026-10-07T04:39:28.942245Z","steps":["trace[75722536] 'process raft request' (duration: 106.469015ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:28.942453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.572827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19029"} {"level":"info","ts":"2026-10-07T04:39:28.942521Z","caller":"traceutil/trace.go:171","msg":"trace[523736604] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26249; }","duration":"106.695709ms","start":"2026-10-07T04:39:28.835809Z","end":"2026-10-07T04:39:28.942505Z","steps":["trace[523736604] 'agreement among raft nodes before linearized reading' (duration: 106.442964ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:29.240026Z","caller":"traceutil/trace.go:171","msg":"trace[653584829] transaction","detail":"{read_only:false; response_revision:26250; number_of_response:1; }","duration":"403.761255ms","start":"2026-10-07T04:39:28.836226Z","end":"2026-10-07T04:39:29.239987Z","steps":["trace[653584829] 'process raft request' (duration: 402.037048ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:29.240125Z","caller":"traceutil/trace.go:171","msg":"trace[158672634] linearizableReadLoop","detail":"{readStateIndex:27115; appliedIndex:27113; }","duration":"297.849382ms","start":"2026-10-07T04:39:28.942207Z","end":"2026-10-07T04:39:29.240056Z","steps":["trace[158672634] 'read index received' (duration: 295.936901ms)","trace[158672634] 'applied index is now lower than readState.Index' (duration: 1.911561ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:29.240141Z","caller":"traceutil/trace.go:171","msg":"trace[1772050854] transaction","detail":"{read_only:false; response_revision:26251; number_of_response:1; }","duration":"371.82961ms","start":"2026-10-07T04:39:28.868238Z","end":"2026-10-07T04:39:29.240068Z","steps":["trace[1772050854] 'process raft request' (duration: 371.687916ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:29.240236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:28.836207Z","time spent":"403.929337ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:39:29.240327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:28.868204Z","time spent":"372.028003ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-07T04:39:29.240357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.455121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:29.240398Z","caller":"traceutil/trace.go:171","msg":"trace[76220498] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:26251; }","duration":"316.582893ms","start":"2026-10-07T04:39:28.923801Z","end":"2026-10-07T04:39:29.240383Z","steps":["trace[76220498] 'agreement among raft nodes before linearized reading' (duration: 316.445661ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:29.240444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:28.923765Z","time spent":"316.667786ms","remote":"127.0.0.1:59612","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"warn","ts":"2026-10-07T04:39:29.240844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.248749ms","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":"info","ts":"2026-10-07T04:39:29.240901Z","caller":"traceutil/trace.go:171","msg":"trace[1907932962] 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:26251; }","duration":"338.364001ms","start":"2026-10-07T04:39:28.902521Z","end":"2026-10-07T04:39:29.240885Z","steps":["trace[1907932962] 'agreement among raft nodes before linearized reading' (duration: 338.26113ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:29.240941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:28.902493Z","time spent":"338.438712ms","remote":"127.0.0.1:57056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":29,"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-10-07T04:39:29.241168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.31222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-10-07T04:39:29.24119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.828223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:29.241255Z","caller":"traceutil/trace.go:171","msg":"trace[699580686] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26251; }","duration":"283.449573ms","start":"2026-10-07T04:39:28.957787Z","end":"2026-10-07T04:39:29.241237Z","steps":["trace[699580686] 'agreement among raft nodes before linearized reading' (duration: 283.209378ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:29.241324Z","caller":"traceutil/trace.go:171","msg":"trace[2021340993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26251; }","duration":"203.114319ms","start":"2026-10-07T04:39:29.038174Z","end":"2026-10-07T04:39:29.241288Z","steps":["trace[2021340993] 'agreement among raft nodes before linearized reading' (duration: 202.799231ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:29.241344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.195687ms","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-10-07T04:39:29.241387Z","caller":"traceutil/trace.go:171","msg":"trace[244410721] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:26251; }","duration":"283.325391ms","start":"2026-10-07T04:39:28.958046Z","end":"2026-10-07T04:39:29.241372Z","steps":["trace[244410721] 'agreement among raft nodes before linearized reading' (duration: 283.180288ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:29.89756Z","caller":"traceutil/trace.go:171","msg":"trace[174203431] transaction","detail":"{read_only:false; response_revision:26253; number_of_response:1; }","duration":"546.496948ms","start":"2026-10-07T04:39:29.350996Z","end":"2026-10-07T04:39:29.897493Z","steps":["trace[174203431] 'process raft request' (duration: 546.156611ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:29.897864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:29.35097Z","time spent":"546.712252ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:39:29.899929Z","caller":"traceutil/trace.go:171","msg":"trace[1460809121] transaction","detail":"{read_only:false; response_revision:26254; number_of_response:1; }","duration":"530.931203ms","start":"2026-10-07T04:39:29.368962Z","end":"2026-10-07T04:39:29.899893Z","steps":["trace[1460809121] 'process raft request' (duration: 530.605116ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:29.900278Z","caller":"traceutil/trace.go:171","msg":"trace[1491397784] transaction","detail":"{read_only:false; response_revision:26255; number_of_response:1; }","duration":"530.734339ms","start":"2026-10-07T04:39:29.369517Z","end":"2026-10-07T04:39:29.900251Z","steps":["trace[1491397784] 'process raft request' (duration: 530.222588ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:29.900399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:29.369501Z","time spent":"530.835242ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:39:29.900318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:29.368942Z","time spent":"531.165369ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:39:29.901605Z","caller":"traceutil/trace.go:171","msg":"trace[1568213909] linearizableReadLoop","detail":"{readStateIndex:27119; appliedIndex:27116; }","duration":"336.762317ms","start":"2026-10-07T04:39:29.564827Z","end":"2026-10-07T04:39:29.901589Z","steps":["trace[1568213909] 'read index received' (duration: 332.356923ms)","trace[1568213909] 'applied index is now lower than readState.Index' (duration: 4.403894ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:29.901896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.061232ms","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-10-07T04:39:29.901968Z","caller":"traceutil/trace.go:171","msg":"trace[1095518393] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26258; }","duration":"337.195206ms","start":"2026-10-07T04:39:29.564761Z","end":"2026-10-07T04:39:29.901956Z","steps":["trace[1095518393] 'agreement among raft nodes before linearized reading' (duration: 336.971482ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:29.902005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:29.564731Z","time spent":"337.263348ms","remote":"127.0.0.1:48052","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":"info","ts":"2026-10-07T04:39:30.195675Z","caller":"traceutil/trace.go:171","msg":"trace[700757841] linearizableReadLoop","detail":"{readStateIndex:27123; appliedIndex:27122; }","duration":"285.265232ms","start":"2026-10-07T04:39:29.910382Z","end":"2026-10-07T04:39:30.195647Z","steps":["trace[700757841] 'read index received' (duration: 285.198241ms)","trace[700757841] 'applied index is now lower than readState.Index' (duration: 65.932µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:30.195707Z","caller":"traceutil/trace.go:171","msg":"trace[451860031] transaction","detail":"{read_only:false; response_revision:26259; number_of_response:1; }","duration":"289.245817ms","start":"2026-10-07T04:39:29.906425Z","end":"2026-10-07T04:39:30.195671Z","steps":["trace[451860031] 'process raft request' (duration: 288.977772ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:30.195903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.486527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-10-07T04:39:30.196012Z","caller":"traceutil/trace.go:171","msg":"trace[756529706] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-barbican-server-0; range_end:; response_count:1; response_revision:26259; }","duration":"285.631979ms","start":"2026-10-07T04:39:29.910352Z","end":"2026-10-07T04:39:30.195984Z","steps":["trace[756529706] 'agreement among raft nodes before linearized reading' (duration: 285.375894ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:30.207184Z","caller":"traceutil/trace.go:171","msg":"trace[50034273] transaction","detail":"{read_only:false; response_revision:26260; number_of_response:1; }","duration":"138.337608ms","start":"2026-10-07T04:39:30.068814Z","end":"2026-10-07T04:39:30.207152Z","steps":["trace[50034273] 'process raft request' (duration: 137.977721ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:30.207391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.059398ms","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-10-07T04:39:30.207503Z","caller":"traceutil/trace.go:171","msg":"trace[165746471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26260; }","duration":"122.236603ms","start":"2026-10-07T04:39:30.085238Z","end":"2026-10-07T04:39:30.207475Z","steps":["trace[165746471] 'agreement among raft nodes before linearized reading' (duration: 121.870974ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:30.207508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.479936ms","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-10-07T04:39:30.207618Z","caller":"traceutil/trace.go:171","msg":"trace[1427400966] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26260; }","duration":"121.679221ms","start":"2026-10-07T04:39:30.08591Z","end":"2026-10-07T04:39:30.20759Z","steps":["trace[1427400966] 'agreement among raft nodes before linearized reading' (duration: 121.311643ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:30.652549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.563765ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:30.652878Z","caller":"traceutil/trace.go:171","msg":"trace[296973023] transaction","detail":"{read_only:false; response_revision:26263; number_of_response:1; }","duration":"223.851094ms","start":"2026-10-07T04:39:30.429004Z","end":"2026-10-07T04:39:30.652855Z","steps":["trace[296973023] 'process raft request' (duration: 223.790693ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:30.653143Z","caller":"traceutil/trace.go:171","msg":"trace[146224855] transaction","detail":"{read_only:false; response_revision:26261; number_of_response:1; }","duration":"440.016222ms","start":"2026-10-07T04:39:30.213048Z","end":"2026-10-07T04:39:30.653064Z","steps":["trace[146224855] 'process raft request' (duration: 121.766183ms)","trace[146224855] 'compare' (duration: 317.373411ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:30.653385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:30.21302Z","time spent":"440.242768ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:39:30.653135Z","caller":"traceutil/trace.go:171","msg":"trace[390762171] transaction","detail":"{read_only:false; response_revision:26262; number_of_response:1; }","duration":"439.937771ms","start":"2026-10-07T04:39:30.213132Z","end":"2026-10-07T04:39:30.65307Z","steps":["trace[390762171] 'process raft request' (duration: 439.593324ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:30.653657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:30.213106Z","time spent":"440.40824ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:39:30.998878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.03851ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:30.999437Z","caller":"traceutil/trace.go:171","msg":"trace[2116531630] transaction","detail":"{read_only:false; response_revision:26265; number_of_response:1; }","duration":"163.602161ms","start":"2026-10-07T04:39:30.835811Z","end":"2026-10-07T04:39:30.999414Z","steps":["trace[2116531630] 'process raft request' (duration: 163.358556ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:30.99957Z","caller":"traceutil/trace.go:171","msg":"trace[970569332] transaction","detail":"{read_only:false; response_revision:26264; number_of_response:1; }","duration":"357.912702ms","start":"2026-10-07T04:39:30.641564Z","end":"2026-10-07T04:39:30.999476Z","steps":["trace[970569332] 'process raft request' (duration: 248.150606ms)","trace[970569332] 'compare' (duration: 108.722043ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:30.999796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:30.641528Z","time spent":"358.145606ms","remote":"127.0.0.1:47858","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":854,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-07T04:39:31.483551Z","caller":"traceutil/trace.go:171","msg":"trace[1571114171] linearizableReadLoop","detail":"{readStateIndex:27131; appliedIndex:27130; }","duration":"237.288002ms","start":"2026-10-07T04:39:31.246233Z","end":"2026-10-07T04:39:31.483521Z","steps":["trace[1571114171] 'read index received' (duration: 237.050287ms)","trace[1571114171] 'applied index is now lower than readState.Index' (duration: 235.975µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:31.483599Z","caller":"traceutil/trace.go:171","msg":"trace[2125133068] transaction","detail":"{read_only:false; response_revision:26267; number_of_response:1; }","duration":"257.274631ms","start":"2026-10-07T04:39:31.226277Z","end":"2026-10-07T04:39:31.483551Z","steps":["trace[2125133068] 'process raft request' (duration: 257.022045ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:31.483912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.65968ms","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-10-07T04:39:31.48401Z","caller":"traceutil/trace.go:171","msg":"trace[1776871817] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26267; }","duration":"237.822633ms","start":"2026-10-07T04:39:31.246162Z","end":"2026-10-07T04:39:31.483985Z","steps":["trace[1776871817] 'agreement among raft nodes before linearized reading' (duration: 237.485116ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:31.484051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.100289ms","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-10-07T04:39:31.484127Z","caller":"traceutil/trace.go:171","msg":"trace[1064277159] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26267; }","duration":"224.269702ms","start":"2026-10-07T04:39:31.259844Z","end":"2026-10-07T04:39:31.484114Z","steps":["trace[1064277159] 'agreement among raft nodes before linearized reading' (duration: 223.853003ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:31.66514Z","caller":"traceutil/trace.go:171","msg":"trace[264849798] transaction","detail":"{read_only:false; response_revision:26269; number_of_response:1; }","duration":"173.997673ms","start":"2026-10-07T04:39:31.491112Z","end":"2026-10-07T04:39:31.66511Z","steps":["trace[264849798] 'process raft request' (duration: 173.797219ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:34.126349Z","caller":"traceutil/trace.go:171","msg":"trace[836286197] transaction","detail":"{read_only:false; response_revision:26287; number_of_response:1; }","duration":"210.040188ms","start":"2026-10-07T04:39:33.916242Z","end":"2026-10-07T04:39:34.126282Z","steps":["trace[836286197] 'process raft request' (duration: 209.817913ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:34.180342Z","caller":"traceutil/trace.go:171","msg":"trace[1156543453] transaction","detail":"{read_only:false; response_revision:26288; number_of_response:1; }","duration":"263.944255ms","start":"2026-10-07T04:39:33.916365Z","end":"2026-10-07T04:39:34.180309Z","steps":["trace[1156543453] 'process raft request' (duration: 263.620527ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:34.180663Z","caller":"traceutil/trace.go:171","msg":"trace[2083078486] transaction","detail":"{read_only:false; response_revision:26289; number_of_response:1; }","duration":"262.074644ms","start":"2026-10-07T04:39:33.918543Z","end":"2026-10-07T04:39:34.180618Z","steps":["trace[2083078486] 'process raft request' (duration: 261.704016ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:34.180955Z","caller":"traceutil/trace.go:171","msg":"trace[89793829] transaction","detail":"{read_only:false; response_revision:26290; number_of_response:1; }","duration":"261.149324ms","start":"2026-10-07T04:39:33.919777Z","end":"2026-10-07T04:39:34.180926Z","steps":["trace[89793829] 'process raft request' (duration: 260.743936ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:34.181142Z","caller":"traceutil/trace.go:171","msg":"trace[1518138073] transaction","detail":"{read_only:false; response_revision:26291; number_of_response:1; }","duration":"260.366447ms","start":"2026-10-07T04:39:33.920754Z","end":"2026-10-07T04:39:34.18112Z","steps":["trace[1518138073] 'process raft request' (duration: 260.01844ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:34.181608Z","caller":"traceutil/trace.go:171","msg":"trace[1655529367] linearizableReadLoop","detail":"{readStateIndex:27156; appliedIndex:27152; }","duration":"165.160604ms","start":"2026-10-07T04:39:34.016434Z","end":"2026-10-07T04:39:34.181594Z","steps":["trace[1655529367] 'read index received' (duration: 109.840487ms)","trace[1655529367] 'applied index is now lower than readState.Index' (duration: 55.318767ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:34.181787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.326538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2f7c43d7-d32b-48cf-a688-1162e599ca58\" ","response":"range_response_count:1 size:3947"} {"level":"info","ts":"2026-10-07T04:39:34.181829Z","caller":"traceutil/trace.go:171","msg":"trace[849949847] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2f7c43d7-d32b-48cf-a688-1162e599ca58; range_end:; response_count:1; response_revision:26291; }","duration":"165.423469ms","start":"2026-10-07T04:39:34.016391Z","end":"2026-10-07T04:39:34.181815Z","steps":["trace[849949847] 'agreement among raft nodes before linearized reading' (duration: 165.258336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:37.207619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.013496ms","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-10-07T04:39:37.207705Z","caller":"traceutil/trace.go:171","msg":"trace[637071874] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26334; }","duration":"127.172749ms","start":"2026-10-07T04:39:37.080513Z","end":"2026-10-07T04:39:37.207685Z","steps":["trace[637071874] 'range keys from in-memory index tree' (duration: 126.77703ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:47.710602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.058715ms","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-10-07T04:39:47.710698Z","caller":"traceutil/trace.go:171","msg":"trace[1158846715] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:26417; }","duration":"181.228499ms","start":"2026-10-07T04:39:47.529444Z","end":"2026-10-07T04:39:47.710673Z","steps":["trace[1158846715] 'range keys from in-memory index tree' (duration: 180.82724ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:47.711064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.203881ms","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-10-07T04:39:47.711153Z","caller":"traceutil/trace.go:171","msg":"trace[1392348468] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:26417; }","duration":"224.339264ms","start":"2026-10-07T04:39:47.486795Z","end":"2026-10-07T04:39:47.711135Z","steps":["trace[1392348468] 'range keys from in-memory index tree' (duration: 224.056588ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:47.711128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.125181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.136\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-07T04:39:47.711233Z","caller":"traceutil/trace.go:171","msg":"trace[774330209] range","detail":"{range_begin:/registry/masterleases/162.253.55.136; range_end:; response_count:1; response_revision:26417; }","duration":"334.423925ms","start":"2026-10-07T04:39:47.376793Z","end":"2026-10-07T04:39:47.711217Z","steps":["trace[774330209] 'range keys from in-memory index tree' (duration: 333.963147ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:47.71128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:47.376779Z","time spent":"334.492898ms","remote":"127.0.0.1:47818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":163,"request content":"key:\"/registry/masterleases/162.253.55.136\" "} {"level":"info","ts":"2026-10-07T04:39:47.961809Z","caller":"traceutil/trace.go:171","msg":"trace[1758703472] transaction","detail":"{read_only:false; response_revision:26418; number_of_response:1; }","duration":"246.128962ms","start":"2026-10-07T04:39:47.715625Z","end":"2026-10-07T04:39:47.961754Z","steps":["trace[1758703472] 'process raft request' (duration: 201.798241ms)","trace[1758703472] 'compare' (duration: 44.034965ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:47.962445Z","caller":"traceutil/trace.go:171","msg":"trace[582611079] linearizableReadLoop","detail":"{readStateIndex:27287; appliedIndex:27284; }","duration":"242.621906ms","start":"2026-10-07T04:39:47.719804Z","end":"2026-10-07T04:39:47.962426Z","steps":["trace[582611079] 'read index received' (duration: 101.437447ms)","trace[582611079] 'applied index is now lower than readState.Index' (duration: 141.183419ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:47.962763Z","caller":"traceutil/trace.go:171","msg":"trace[348986213] transaction","detail":"{read_only:false; response_revision:26419; number_of_response:1; }","duration":"247.061182ms","start":"2026-10-07T04:39:47.715689Z","end":"2026-10-07T04:39:47.96275Z","steps":["trace[348986213] 'process raft request' (duration: 245.953518ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:47.963172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.130008ms","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-10-07T04:39:47.963264Z","caller":"traceutil/trace.go:171","msg":"trace[1115340111] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26420; }","duration":"216.272101ms","start":"2026-10-07T04:39:47.746977Z","end":"2026-10-07T04:39:47.963249Z","steps":["trace[1115340111] 'agreement among raft nodes before linearized reading' (duration: 215.988564ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:47.963661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.853813ms","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-10-07T04:39:47.96372Z","caller":"traceutil/trace.go:171","msg":"trace[988360152] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26420; }","duration":"243.975505ms","start":"2026-10-07T04:39:47.719728Z","end":"2026-10-07T04:39:47.963703Z","steps":["trace[988360152] 'agreement among raft nodes before linearized reading' (duration: 243.188739ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:47.964041Z","caller":"traceutil/trace.go:171","msg":"trace[1429753139] transaction","detail":"{read_only:false; response_revision:26420; number_of_response:1; }","duration":"140.24525ms","start":"2026-10-07T04:39:47.823781Z","end":"2026-10-07T04:39:47.964026Z","steps":["trace[1429753139] 'process raft request' (duration: 138.524972ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:47.964211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.372335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-10-07T04:39:47.964295Z","caller":"traceutil/trace.go:171","msg":"trace[520854320] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26420; }","duration":"114.532628ms","start":"2026-10-07T04:39:47.849735Z","end":"2026-10-07T04:39:47.964267Z","steps":["trace[520854320] 'agreement among raft nodes before linearized reading' (duration: 114.254262ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:48.442894Z","caller":"traceutil/trace.go:171","msg":"trace[710201787] transaction","detail":"{read_only:false; response_revision:26422; number_of_response:1; }","duration":"260.908949ms","start":"2026-10-07T04:39:48.18195Z","end":"2026-10-07T04:39:48.442859Z","steps":["trace[710201787] 'process raft request' (duration: 260.737746ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:48.736282Z","caller":"traceutil/trace.go:171","msg":"trace[563446924] transaction","detail":"{read_only:false; response_revision:26423; number_of_response:1; }","duration":"492.904737ms","start":"2026-10-07T04:39:48.243337Z","end":"2026-10-07T04:39:48.736242Z","steps":["trace[563446924] 'process raft request' (duration: 492.5865ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:48.736381Z","caller":"traceutil/trace.go:171","msg":"trace[1387018479] transaction","detail":"{read_only:false; response_revision:26424; number_of_response:1; }","duration":"481.575434ms","start":"2026-10-07T04:39:48.254765Z","end":"2026-10-07T04:39:48.73634Z","steps":["trace[1387018479] 'process raft request' (duration: 481.38282ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:48.736403Z","caller":"traceutil/trace.go:171","msg":"trace[446060570] transaction","detail":"{read_only:false; response_revision:26426; number_of_response:1; }","duration":"476.764961ms","start":"2026-10-07T04:39:48.259615Z","end":"2026-10-07T04:39:48.73638Z","steps":["trace[446060570] 'process raft request' (duration: 476.684949ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:48.736457Z","caller":"traceutil/trace.go:171","msg":"trace[1314362752] linearizableReadLoop","detail":"{readStateIndex:27295; appliedIndex:27290; }","duration":"425.613563ms","start":"2026-10-07T04:39:48.310814Z","end":"2026-10-07T04:39:48.736428Z","steps":["trace[1314362752] 'read index received' (duration: 132.108215ms)","trace[1314362752] 'applied index is now lower than readState.Index' (duration: 293.504098ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:48.736484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:48.243307Z","time spent":"493.072821ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:39:48.736448Z","caller":"traceutil/trace.go:171","msg":"trace[1543958590] transaction","detail":"{read_only:false; response_revision:26425; number_of_response:1; }","duration":"479.485649ms","start":"2026-10-07T04:39:48.256921Z","end":"2026-10-07T04:39:48.736407Z","steps":["trace[1543958590] 'process raft request' (duration: 479.303366ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:48.736616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:48.254723Z","time spent":"481.752138ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:39:48.736672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.842608ms","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-10-07T04:39:48.736702Z","caller":"traceutil/trace.go:171","msg":"trace[548922756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26427; }","duration":"425.934221ms","start":"2026-10-07T04:39:48.310758Z","end":"2026-10-07T04:39:48.736692Z","steps":["trace[548922756] 'agreement among raft nodes before linearized reading' (duration: 425.758796ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:48.736662Z","caller":"traceutil/trace.go:171","msg":"trace[768490150] transaction","detail":"{read_only:false; response_revision:26427; number_of_response:1; }","duration":"452.876718ms","start":"2026-10-07T04:39:48.283751Z","end":"2026-10-07T04:39:48.736628Z","steps":["trace[768490150] 'process raft request' (duration: 452.589042ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:48.736726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:48.310737Z","time spent":"425.983381ms","remote":"127.0.0.1:48052","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-10-07T04:39:48.736782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:48.256885Z","time spent":"479.710574ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:39:48.736842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:48.283726Z","time spent":"453.0117ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:39:48.736537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:39:48.2596Z","time spent":"476.880514ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:39:48.912596Z","caller":"traceutil/trace.go:171","msg":"trace[1595842113] linearizableReadLoop","detail":"{readStateIndex:27296; appliedIndex:27295; }","duration":"154.22844ms","start":"2026-10-07T04:39:48.758337Z","end":"2026-10-07T04:39:48.912565Z","steps":["trace[1595842113] 'read index received' (duration: 138.483191ms)","trace[1595842113] 'applied index is now lower than readState.Index' (duration: 15.743649ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:48.913134Z","caller":"traceutil/trace.go:171","msg":"trace[533949708] transaction","detail":"{read_only:false; response_revision:26428; number_of_response:1; }","duration":"170.676703ms","start":"2026-10-07T04:39:48.742438Z","end":"2026-10-07T04:39:48.913114Z","steps":["trace[533949708] 'process raft request' (duration: 154.445094ms)","trace[533949708] 'compare' (duration: 14.92709ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:48.913529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.217698ms","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-10-07T04:39:48.91362Z","caller":"traceutil/trace.go:171","msg":"trace[787009864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26428; }","duration":"153.34653ms","start":"2026-10-07T04:39:48.760252Z","end":"2026-10-07T04:39:48.913599Z","steps":["trace[787009864] 'agreement among raft nodes before linearized reading' (duration: 153.096826ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:48.913814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.477447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-07T04:39:48.91385Z","caller":"traceutil/trace.go:171","msg":"trace[1209488941] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26428; }","duration":"155.556578ms","start":"2026-10-07T04:39:48.758284Z","end":"2026-10-07T04:39:48.91384Z","steps":["trace[1209488941] 'agreement among raft nodes before linearized reading' (duration: 155.465556ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:57.004937Z","caller":"traceutil/trace.go:171","msg":"trace[1688088633] transaction","detail":"{read_only:false; response_revision:26495; number_of_response:1; }","duration":"227.405199ms","start":"2026-10-07T04:39:56.7775Z","end":"2026-10-07T04:39:57.004905Z","steps":["trace[1688088633] 'process raft request' (duration: 135.936956ms)","trace[1688088633] 'compare' (duration: 91.065824ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:57.004964Z","caller":"traceutil/trace.go:171","msg":"trace[138692011] transaction","detail":"{read_only:false; response_revision:26496; number_of_response:1; }","duration":"226.697184ms","start":"2026-10-07T04:39:56.778243Z","end":"2026-10-07T04:39:57.00494Z","steps":["trace[138692011] 'process raft request' (duration: 226.565091ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:57.00544Z","caller":"traceutil/trace.go:171","msg":"trace[648172672] transaction","detail":"{read_only:false; response_revision:26497; number_of_response:1; }","duration":"227.115283ms","start":"2026-10-07T04:39:56.778316Z","end":"2026-10-07T04:39:57.005431Z","steps":["trace[648172672] 'process raft request' (duration: 226.587802ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:57.005629Z","caller":"traceutil/trace.go:171","msg":"trace[944895244] linearizableReadLoop","detail":"{readStateIndex:27368; appliedIndex:27364; }","duration":"164.719745ms","start":"2026-10-07T04:39:56.840882Z","end":"2026-10-07T04:39:57.005602Z","steps":["trace[944895244] 'read index received' (duration: 72.68392ms)","trace[944895244] 'applied index is now lower than readState.Index' (duration: 92.034185ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:57.005694Z","caller":"traceutil/trace.go:171","msg":"trace[2012267894] transaction","detail":"{read_only:false; response_revision:26498; number_of_response:1; }","duration":"167.409083ms","start":"2026-10-07T04:39:56.83823Z","end":"2026-10-07T04:39:57.00564Z","steps":["trace[2012267894] 'process raft request' (duration: 167.158627ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:57.00582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.898778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T04:39:57.005859Z","caller":"traceutil/trace.go:171","msg":"trace[137825475] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:26498; }","duration":"165.004931ms","start":"2026-10-07T04:39:56.840844Z","end":"2026-10-07T04:39:57.005849Z","steps":["trace[137825475] 'agreement among raft nodes before linearized reading' (duration: 164.863278ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:57.242368Z","caller":"traceutil/trace.go:171","msg":"trace[1401018135] transaction","detail":"{read_only:false; response_revision:26501; number_of_response:1; }","duration":"228.581335ms","start":"2026-10-07T04:39:57.013761Z","end":"2026-10-07T04:39:57.242342Z","steps":["trace[1401018135] 'process raft request' (duration: 228.535974ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:57.2424Z","caller":"traceutil/trace.go:171","msg":"trace[428520867] transaction","detail":"{read_only:false; response_revision:26500; number_of_response:1; }","duration":"231.532118ms","start":"2026-10-07T04:39:57.010826Z","end":"2026-10-07T04:39:57.242358Z","steps":["trace[428520867] 'process raft request' (duration: 175.425784ms)","trace[428520867] 'compare' (duration: 55.890359ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:57.266545Z","caller":"traceutil/trace.go:171","msg":"trace[222559781] transaction","detail":"{read_only:false; response_revision:26502; number_of_response:1; }","duration":"142.380255ms","start":"2026-10-07T04:39:57.124136Z","end":"2026-10-07T04:39:57.266516Z","steps":["trace[222559781] 'process raft request' (duration: 142.254133ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:57.539018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.539147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:57.539165Z","caller":"traceutil/trace.go:171","msg":"trace[353634941] linearizableReadLoop","detail":"{readStateIndex:27373; appliedIndex:27372; }","duration":"169.687761ms","start":"2026-10-07T04:39:57.369456Z","end":"2026-10-07T04:39:57.539144Z","steps":["trace[353634941] 'read index received' (duration: 48.717295ms)","trace[353634941] 'applied index is now lower than readState.Index' (duration: 120.968846ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:57.539255Z","caller":"traceutil/trace.go:171","msg":"trace[54649185] transaction","detail":"{read_only:false; response_revision:26503; number_of_response:1; }","duration":"271.591237ms","start":"2026-10-07T04:39:57.2676Z","end":"2026-10-07T04:39:57.539191Z","steps":["trace[54649185] 'process raft request' (duration: 150.737185ms)","trace[54649185] 'compare' (duration: 120.380572ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:57.53939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.894903ms","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":"warn","ts":"2026-10-07T04:39:57.539378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.871645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:39:57.539424Z","caller":"traceutil/trace.go:171","msg":"trace[2131247597] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26503; }","duration":"167.939303ms","start":"2026-10-07T04:39:57.371473Z","end":"2026-10-07T04:39:57.539412Z","steps":["trace[2131247597] 'agreement among raft nodes before linearized reading' (duration: 167.79048ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:57.53948Z","caller":"traceutil/trace.go:171","msg":"trace[592974830] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:26503; }","duration":"170.044768ms","start":"2026-10-07T04:39:57.369405Z","end":"2026-10-07T04:39:57.539449Z","steps":["trace[592974830] 'agreement among raft nodes before linearized reading' (duration: 169.851094ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:57.539543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.485752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.136\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-10-07T04:39:57.53964Z","caller":"traceutil/trace.go:171","msg":"trace[1558276896] range","detail":"{range_begin:/registry/masterleases/162.253.55.136; range_end:; response_count:1; response_revision:26503; }","duration":"159.622835ms","start":"2026-10-07T04:39:57.379997Z","end":"2026-10-07T04:39:57.539619Z","steps":["trace[1558276896] 'agreement among raft nodes before linearized reading' (duration: 159.36303ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:57.725538Z","caller":"traceutil/trace.go:171","msg":"trace[1463139708] transaction","detail":"{read_only:false; response_revision:26505; number_of_response:1; }","duration":"175.627418ms","start":"2026-10-07T04:39:57.549881Z","end":"2026-10-07T04:39:57.725508Z","steps":["trace[1463139708] 'process raft request' (duration: 175.482335ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:39:57.725776Z","caller":"traceutil/trace.go:171","msg":"trace[2146863541] transaction","detail":"{read_only:false; response_revision:26504; number_of_response:1; }","duration":"178.875138ms","start":"2026-10-07T04:39:57.546862Z","end":"2026-10-07T04:39:57.725737Z","steps":["trace[2146863541] 'process raft request' (duration: 151.921069ms)","trace[2146863541] 'compare' (duration: 26.202272ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:57.736643Z","caller":"traceutil/trace.go:171","msg":"trace[99480175] transaction","detail":"{read_only:false; response_revision:26506; number_of_response:1; }","duration":"158.307286ms","start":"2026-10-07T04:39:57.57827Z","end":"2026-10-07T04:39:57.736578Z","steps":["trace[99480175] 'process raft request' (duration: 158.153093ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:39:58.022887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.092923ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:39:58.023004Z","caller":"traceutil/trace.go:171","msg":"trace[1923733171] linearizableReadLoop","detail":"{readStateIndex:27379; appliedIndex:27378; }","duration":"206.591553ms","start":"2026-10-07T04:39:57.816395Z","end":"2026-10-07T04:39:58.022986Z","steps":["trace[1923733171] 'read index received' (duration: 75.363267ms)","trace[1923733171] 'applied index is now lower than readState.Index' (duration: 131.227166ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:39:58.023102Z","caller":"traceutil/trace.go:171","msg":"trace[2128343791] transaction","detail":"{read_only:false; response_revision:26508; number_of_response:1; }","duration":"220.048142ms","start":"2026-10-07T04:39:57.802986Z","end":"2026-10-07T04:39:58.023034Z","steps":["trace[2128343791] 'process raft request' (duration: 88.785285ms)","trace[2128343791] 'compare' (duration: 130.850028ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:39:58.023205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.815898ms","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-10-07T04:39:58.023227Z","caller":"traceutil/trace.go:171","msg":"trace[939476068] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:26508; }","duration":"206.879239ms","start":"2026-10-07T04:39:57.816339Z","end":"2026-10-07T04:39:58.023218Z","steps":["trace[939476068] 'agreement among raft nodes before linearized reading' (duration: 206.733736ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:00.239766Z","caller":"traceutil/trace.go:171","msg":"trace[934197760] transaction","detail":"{read_only:false; response_revision:26524; number_of_response:1; }","duration":"133.500445ms","start":"2026-10-07T04:40:00.106236Z","end":"2026-10-07T04:40:00.239736Z","steps":["trace[934197760] 'process raft request' (duration: 133.302841ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:00.271826Z","caller":"traceutil/trace.go:171","msg":"trace[593020199] linearizableReadLoop","detail":"{readStateIndex:27396; appliedIndex:27394; }","duration":"156.796474ms","start":"2026-10-07T04:40:00.115013Z","end":"2026-10-07T04:40:00.27181Z","steps":["trace[593020199] 'read index received' (duration: 124.671355ms)","trace[593020199] 'applied index is now lower than readState.Index' (duration: 32.121349ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:00.271903Z","caller":"traceutil/trace.go:171","msg":"trace[341087244] transaction","detail":"{read_only:false; response_revision:26525; number_of_response:1; }","duration":"164.52722ms","start":"2026-10-07T04:40:00.107367Z","end":"2026-10-07T04:40:00.271894Z","steps":["trace[341087244] 'process raft request' (duration: 153.035154ms)","trace[341087244] 'compare' (duration: 11.309633ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:40:00.272132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.101082ms","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-10-07T04:40:00.272232Z","caller":"traceutil/trace.go:171","msg":"trace[61216606] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26525; }","duration":"157.251915ms","start":"2026-10-07T04:40:00.114959Z","end":"2026-10-07T04:40:00.272211Z","steps":["trace[61216606] 'agreement among raft nodes before linearized reading' (duration: 157.0341ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:00.345174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.393336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:40:00.345259Z","caller":"traceutil/trace.go:171","msg":"trace[616952733] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:26527; }","duration":"225.516539ms","start":"2026-10-07T04:40:00.119719Z","end":"2026-10-07T04:40:00.345236Z","steps":["trace[616952733] 'agreement among raft nodes before linearized reading' (duration: 225.341896ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:00.455888Z","caller":"traceutil/trace.go:171","msg":"trace[591072488] transaction","detail":"{read_only:false; response_revision:26528; number_of_response:1; }","duration":"175.185949ms","start":"2026-10-07T04:40:00.280655Z","end":"2026-10-07T04:40:00.45584Z","steps":["trace[591072488] 'process raft request' (duration: 109.568761ms)","trace[591072488] 'compare' (duration: 65.434434ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:00.510375Z","caller":"traceutil/trace.go:171","msg":"trace[1889034330] linearizableReadLoop","detail":"{readStateIndex:27400; appliedIndex:27398; }","duration":"161.055346ms","start":"2026-10-07T04:40:00.349288Z","end":"2026-10-07T04:40:00.510344Z","steps":["trace[1889034330] 'read index received' (duration: 41.021861ms)","trace[1889034330] 'applied index is now lower than readState.Index' (duration: 120.032045ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:00.510456Z","caller":"traceutil/trace.go:171","msg":"trace[1480443957] transaction","detail":"{read_only:false; response_revision:26529; number_of_response:1; }","duration":"162.117458ms","start":"2026-10-07T04:40:00.348291Z","end":"2026-10-07T04:40:00.510408Z","steps":["trace[1480443957] 'process raft request' (duration: 161.891924ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:00.510701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.347442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/openstack/heat-engine-cleaner\" ","response":"range_response_count:1 size:5540"} {"level":"info","ts":"2026-10-07T04:40:00.510818Z","caller":"traceutil/trace.go:171","msg":"trace[5701426] range","detail":"{range_begin:/registry/cronjobs/openstack/heat-engine-cleaner; range_end:; response_count:1; response_revision:26529; }","duration":"161.529555ms","start":"2026-10-07T04:40:00.349248Z","end":"2026-10-07T04:40:00.510778Z","steps":["trace[5701426] 'agreement among raft nodes before linearized reading' (duration: 161.230819ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:00.679735Z","caller":"traceutil/trace.go:171","msg":"trace[1612950021] linearizableReadLoop","detail":"{readStateIndex:27405; appliedIndex:27403; }","duration":"102.980258ms","start":"2026-10-07T04:40:00.57672Z","end":"2026-10-07T04:40:00.679701Z","steps":["trace[1612950021] 'read index received' (duration: 27.634092ms)","trace[1612950021] 'applied index is now lower than readState.Index' (duration: 75.344846ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:00.679743Z","caller":"traceutil/trace.go:171","msg":"trace[207024387] transaction","detail":"{read_only:false; response_revision:26533; number_of_response:1; }","duration":"124.502931ms","start":"2026-10-07T04:40:00.555192Z","end":"2026-10-07T04:40:00.679695Z","steps":["trace[207024387] 'process raft request' (duration: 49.147134ms)","trace[207024387] 'compare' (duration: 74.767115ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:00.679804Z","caller":"traceutil/trace.go:171","msg":"trace[397282460] transaction","detail":"{read_only:false; response_revision:26534; number_of_response:1; }","duration":"116.52684ms","start":"2026-10-07T04:40:00.563248Z","end":"2026-10-07T04:40:00.679775Z","steps":["trace[397282460] 'process raft request' (duration: 116.317696ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:00.68003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.287786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-engine-cleaner-29855800-xd7q5\" ","response":"range_response_count:1 size:5678"} {"level":"info","ts":"2026-10-07T04:40:00.680112Z","caller":"traceutil/trace.go:171","msg":"trace[1299192508] range","detail":"{range_begin:/registry/pods/openstack/heat-engine-cleaner-29855800-xd7q5; range_end:; response_count:1; response_revision:26534; }","duration":"103.407779ms","start":"2026-10-07T04:40:00.576665Z","end":"2026-10-07T04:40:00.680073Z","steps":["trace[1299192508] 'agreement among raft nodes before linearized reading' (duration: 103.158664ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:00.851486Z","caller":"traceutil/trace.go:171","msg":"trace[424863572] transaction","detail":"{read_only:false; response_revision:26535; number_of_response:1; }","duration":"156.473267ms","start":"2026-10-07T04:40:00.694966Z","end":"2026-10-07T04:40:00.851439Z","steps":["trace[424863572] 'process raft request' (duration: 155.748302ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:01.226536Z","caller":"traceutil/trace.go:171","msg":"trace[677990301] transaction","detail":"{read_only:false; response_revision:26543; number_of_response:1; }","duration":"116.382947ms","start":"2026-10-07T04:40:01.110101Z","end":"2026-10-07T04:40:01.226484Z","steps":["trace[677990301] 'process raft request' (duration: 100.63343ms)","trace[677990301] 'compare' (duration: 15.542113ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:04.848788Z","caller":"traceutil/trace.go:171","msg":"trace[1255717523] transaction","detail":"{read_only:false; response_revision:26574; number_of_response:1; }","duration":"107.577838ms","start":"2026-10-07T04:40:04.741179Z","end":"2026-10-07T04:40:04.848757Z","steps":["trace[1255717523] 'process raft request' (duration: 107.356614ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:05.025733Z","caller":"traceutil/trace.go:171","msg":"trace[1843051332] linearizableReadLoop","detail":"{readStateIndex:27447; appliedIndex:27446; }","duration":"157.024219ms","start":"2026-10-07T04:40:04.868678Z","end":"2026-10-07T04:40:05.025702Z","steps":["trace[1843051332] 'read index received' (duration: 86.71232ms)","trace[1843051332] 'applied index is now lower than readState.Index' (duration: 70.310899ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:05.025821Z","caller":"traceutil/trace.go:171","msg":"trace[1677379735] transaction","detail":"{read_only:false; response_revision:26575; number_of_response:1; }","duration":"159.253377ms","start":"2026-10-07T04:40:04.866527Z","end":"2026-10-07T04:40:05.025781Z","steps":["trace[1677379735] 'process raft request' (duration: 88.907578ms)","trace[1677379735] 'compare' (duration: 70.109514ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:40:05.025986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.357257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-10-07T04:40:05.026033Z","caller":"traceutil/trace.go:171","msg":"trace[311979917] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:26575; }","duration":"157.471399ms","start":"2026-10-07T04:40:04.868551Z","end":"2026-10-07T04:40:05.026022Z","steps":["trace[311979917] 'agreement among raft nodes before linearized reading' (duration: 157.297704ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:05.027373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.603602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/images/\" range_end:\"/registry/openstack.k-orc.cloud/images0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:40:05.027464Z","caller":"traceutil/trace.go:171","msg":"trace[909794389] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/images/; range_end:/registry/openstack.k-orc.cloud/images0; response_count:0; response_revision:26576; }","duration":"143.750255ms","start":"2026-10-07T04:40:04.883693Z","end":"2026-10-07T04:40:05.027443Z","steps":["trace[909794389] 'agreement among raft nodes before linearized reading' (duration: 143.587701ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:05.644465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.473375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:40:05.644755Z","caller":"traceutil/trace.go:171","msg":"trace[1447435314] transaction","detail":"{read_only:false; response_revision:26583; number_of_response:1; }","duration":"350.679514ms","start":"2026-10-07T04:40:05.294029Z","end":"2026-10-07T04:40:05.644708Z","steps":["trace[1447435314] 'process raft request' (duration: 181.847282ms)","trace[1447435314] 'compare' (duration: 168.167798ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:05.644854Z","caller":"traceutil/trace.go:171","msg":"trace[890685511] transaction","detail":"{read_only:false; response_revision:26584; number_of_response:1; }","duration":"298.565946ms","start":"2026-10-07T04:40:05.346265Z","end":"2026-10-07T04:40:05.644831Z","steps":["trace[890685511] 'process raft request' (duration: 298.443393ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:05.644824Z","caller":"traceutil/trace.go:171","msg":"trace[894883662] linearizableReadLoop","detail":"{readStateIndex:27455; appliedIndex:27454; }","duration":"303.584244ms","start":"2026-10-07T04:40:05.341197Z","end":"2026-10-07T04:40:05.644781Z","steps":["trace[894883662] 'read index received' (duration: 134.69752ms)","trace[894883662] 'applied index is now lower than readState.Index' (duration: 168.882504ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:40:05.644917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:40:05.294014Z","time spent":"350.811078ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:40:05.645216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.202727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/heat-engine-cleaner-29855800-xd7q5\" ","response":"range_response_count:1 size:7237"} {"level":"warn","ts":"2026-10-07T04:40:05.645209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.7778ms","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-10-07T04:40:05.645263Z","caller":"traceutil/trace.go:171","msg":"trace[1492491647] range","detail":"{range_begin:/registry/pods/openstack/heat-engine-cleaner-29855800-xd7q5; range_end:; response_count:1; response_revision:26584; }","duration":"208.32311ms","start":"2026-10-07T04:40:05.43692Z","end":"2026-10-07T04:40:05.645243Z","steps":["trace[1492491647] 'agreement among raft nodes before linearized reading' (duration: 208.062034ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:05.645324Z","caller":"traceutil/trace.go:171","msg":"trace[1491304067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26584; }","duration":"352.956024ms","start":"2026-10-07T04:40:05.292329Z","end":"2026-10-07T04:40:05.645285Z","steps":["trace[1491304067] 'agreement among raft nodes before linearized reading' (duration: 352.539315ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:05.645382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:40:05.292283Z","time spent":"353.088955ms","remote":"127.0.0.1:48052","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":"info","ts":"2026-10-07T04:40:05.76196Z","caller":"traceutil/trace.go:171","msg":"trace[857303246] transaction","detail":"{read_only:false; response_revision:26586; number_of_response:1; }","duration":"105.30441ms","start":"2026-10-07T04:40:05.656629Z","end":"2026-10-07T04:40:05.761933Z","steps":["trace[857303246] 'process raft request' (duration: 103.513922ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:06.074005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.331683ms","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-10-07T04:40:06.074108Z","caller":"traceutil/trace.go:171","msg":"trace[1999185394] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:26586; }","duration":"209.534516ms","start":"2026-10-07T04:40:05.864532Z","end":"2026-10-07T04:40:06.074067Z","steps":["trace[1999185394] 'range keys from in-memory index tree' (duration: 208.912883ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:06.074534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.007427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-10-07T04:40:06.074603Z","caller":"traceutil/trace.go:171","msg":"trace[551603597] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:26586; }","duration":"197.102799ms","start":"2026-10-07T04:40:05.877481Z","end":"2026-10-07T04:40:06.074583Z","steps":["trace[551603597] 'range keys from in-memory index tree' (duration: 196.821094ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:07.074842Z","caller":"traceutil/trace.go:171","msg":"trace[1419447172] transaction","detail":"{read_only:false; response_revision:26591; number_of_response:1; }","duration":"304.069335ms","start":"2026-10-07T04:40:06.770743Z","end":"2026-10-07T04:40:07.074812Z","steps":["trace[1419447172] 'process raft request' (duration: 303.807989ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:07.075022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:40:06.770706Z","time spent":"304.219268ms","remote":"127.0.0.1:48028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5599,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:40:07.089099Z","caller":"traceutil/trace.go:171","msg":"trace[1300393803] transaction","detail":"{read_only:false; response_revision:26592; number_of_response:1; }","duration":"233.553692ms","start":"2026-10-07T04:40:06.855472Z","end":"2026-10-07T04:40:07.089026Z","steps":["trace[1300393803] 'process raft request' (duration: 233.302007ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:08.731442Z","caller":"traceutil/trace.go:171","msg":"trace[1219375059] linearizableReadLoop","detail":"{readStateIndex:27478; appliedIndex:27477; }","duration":"120.003555ms","start":"2026-10-07T04:40:08.611406Z","end":"2026-10-07T04:40:08.731409Z","steps":["trace[1219375059] 'read index received' (duration: 119.662237ms)","trace[1219375059] 'applied index is now lower than readState.Index' (duration: 339.908µs)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:08.731513Z","caller":"traceutil/trace.go:171","msg":"trace[674591284] transaction","detail":"{read_only:false; response_revision:26605; number_of_response:1; }","duration":"233.02747ms","start":"2026-10-07T04:40:08.498428Z","end":"2026-10-07T04:40:08.731456Z","steps":["trace[674591284] 'process raft request' (duration: 232.688843ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:08.731666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.22227ms","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":"info","ts":"2026-10-07T04:40:08.731791Z","caller":"traceutil/trace.go:171","msg":"trace[1031166058] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:26605; }","duration":"120.430474ms","start":"2026-10-07T04:40:08.611333Z","end":"2026-10-07T04:40:08.731764Z","steps":["trace[1031166058] 'agreement among raft nodes before linearized reading' (duration: 120.203569ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:09.299793Z","caller":"traceutil/trace.go:171","msg":"trace[1878603059] transaction","detail":"{read_only:false; response_revision:26607; number_of_response:1; }","duration":"197.076789ms","start":"2026-10-07T04:40:09.102674Z","end":"2026-10-07T04:40:09.299751Z","steps":["trace[1878603059] 'process raft request' (duration: 140.593407ms)","trace[1878603059] 'compare' (duration: 56.276497ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:09.314518Z","caller":"traceutil/trace.go:171","msg":"trace[933095439] transaction","detail":"{read_only:false; response_revision:26609; number_of_response:1; }","duration":"210.851334ms","start":"2026-10-07T04:40:09.103629Z","end":"2026-10-07T04:40:09.314481Z","steps":["trace[933095439] 'process raft request' (duration: 210.767722ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:09.314557Z","caller":"traceutil/trace.go:171","msg":"trace[1148295450] transaction","detail":"{read_only:false; response_revision:26608; number_of_response:1; }","duration":"211.000988ms","start":"2026-10-07T04:40:09.103503Z","end":"2026-10-07T04:40:09.314504Z","steps":["trace[1148295450] 'process raft request' (duration: 210.728682ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:09.314766Z","caller":"traceutil/trace.go:171","msg":"trace[803972514] transaction","detail":"{read_only:false; response_revision:26610; number_of_response:1; }","duration":"211.049ms","start":"2026-10-07T04:40:09.103689Z","end":"2026-10-07T04:40:09.314738Z","steps":["trace[803972514] 'process raft request' (duration: 210.753503ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:09.314862Z","caller":"traceutil/trace.go:171","msg":"trace[1870594293] transaction","detail":"{read_only:false; response_revision:26611; number_of_response:1; }","duration":"210.892506ms","start":"2026-10-07T04:40:09.103911Z","end":"2026-10-07T04:40:09.314804Z","steps":["trace[1870594293] 'process raft request' (duration: 210.734042ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:29.376027Z","caller":"traceutil/trace.go:171","msg":"trace[1215257600] transaction","detail":"{read_only:false; response_revision:26764; number_of_response:1; }","duration":"110.4671ms","start":"2026-10-07T04:40:29.265527Z","end":"2026-10-07T04:40:29.375994Z","steps":["trace[1215257600] 'process raft request' (duration: 110.282037ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:29.599213Z","caller":"traceutil/trace.go:171","msg":"trace[1856510921] transaction","detail":"{read_only:false; response_revision:26766; number_of_response:1; }","duration":"195.73325ms","start":"2026-10-07T04:40:29.403444Z","end":"2026-10-07T04:40:29.599177Z","steps":["trace[1856510921] 'process raft request' (duration: 195.390483ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:29.599816Z","caller":"traceutil/trace.go:171","msg":"trace[961286095] transaction","detail":"{read_only:false; response_revision:26767; number_of_response:1; }","duration":"195.511645ms","start":"2026-10-07T04:40:29.404271Z","end":"2026-10-07T04:40:29.599782Z","steps":["trace[961286095] 'process raft request' (duration: 194.854171ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:29.600525Z","caller":"traceutil/trace.go:171","msg":"trace[1216470013] linearizableReadLoop","detail":"{readStateIndex:27646; appliedIndex:27642; }","duration":"111.81671ms","start":"2026-10-07T04:40:29.488681Z","end":"2026-10-07T04:40:29.600498Z","steps":["trace[1216470013] 'read index received' (duration: 110.131123ms)","trace[1216470013] 'applied index is now lower than readState.Index' (duration: 1.684537ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:29.600764Z","caller":"traceutil/trace.go:171","msg":"trace[1545275950] transaction","detail":"{read_only:false; response_revision:26768; number_of_response:1; }","duration":"194.112205ms","start":"2026-10-07T04:40:29.40663Z","end":"2026-10-07T04:40:29.600742Z","steps":["trace[1545275950] 'process raft request' (duration: 193.674486ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:29.600772Z","caller":"traceutil/trace.go:171","msg":"trace[601448440] transaction","detail":"{read_only:false; response_revision:26769; number_of_response:1; }","duration":"190.264523ms","start":"2026-10-07T04:40:29.410468Z","end":"2026-10-07T04:40:29.600732Z","steps":["trace[601448440] 'process raft request' (duration: 189.976026ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:29.600994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.241258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-07T04:40:29.601047Z","caller":"traceutil/trace.go:171","msg":"trace[513890951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26769; }","duration":"112.447752ms","start":"2026-10-07T04:40:29.488583Z","end":"2026-10-07T04:40:29.601031Z","steps":["trace[513890951] 'agreement among raft nodes before linearized reading' (duration: 112.149817ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:29.865005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.112287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:40:29.865335Z","caller":"traceutil/trace.go:171","msg":"trace[196871985] transaction","detail":"{read_only:false; response_revision:26770; number_of_response:1; }","duration":"259.61152ms","start":"2026-10-07T04:40:29.605674Z","end":"2026-10-07T04:40:29.865286Z","steps":["trace[196871985] 'process raft request' (duration: 125.088334ms)","trace[196871985] 'compare' (duration: 133.539045ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:29.865364Z","caller":"traceutil/trace.go:171","msg":"trace[1019637050] transaction","detail":"{read_only:false; response_revision:26771; number_of_response:1; }","duration":"117.289227ms","start":"2026-10-07T04:40:29.748034Z","end":"2026-10-07T04:40:29.865323Z","steps":["trace[1019637050] 'process raft request' (duration: 117.123634ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:30.191243Z","caller":"traceutil/trace.go:171","msg":"trace[781427008] transaction","detail":"{read_only:false; response_revision:26772; number_of_response:1; }","duration":"320.704451ms","start":"2026-10-07T04:40:29.870477Z","end":"2026-10-07T04:40:30.191181Z","steps":["trace[781427008] 'process raft request' (duration: 320.418065ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:30.19148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:40:29.870454Z","time spent":"320.902086ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:40:30.196768Z","caller":"traceutil/trace.go:171","msg":"trace[2056211117] linearizableReadLoop","detail":"{readStateIndex:27650; appliedIndex:27648; }","duration":"249.395143ms","start":"2026-10-07T04:40:29.947342Z","end":"2026-10-07T04:40:30.196737Z","steps":["trace[2056211117] 'read index received' (duration: 243.542337ms)","trace[2056211117] 'applied index is now lower than readState.Index' (duration: 5.851866ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:40:30.196967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.596756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-10-07T04:40:30.196999Z","caller":"traceutil/trace.go:171","msg":"trace[756924615] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:26774; }","duration":"249.697727ms","start":"2026-10-07T04:40:29.947292Z","end":"2026-10-07T04:40:30.19699Z","steps":["trace[756924615] 'agreement among raft nodes before linearized reading' (duration: 249.561645ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:30.197398Z","caller":"traceutil/trace.go:171","msg":"trace[1142417271] transaction","detail":"{read_only:false; response_revision:26773; number_of_response:1; }","duration":"326.23532ms","start":"2026-10-07T04:40:29.87115Z","end":"2026-10-07T04:40:30.197385Z","steps":["trace[1142417271] 'process raft request' (duration: 325.389441ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:30.197515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:40:29.871117Z","time spent":"326.317863ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:40:30.197655Z","caller":"traceutil/trace.go:171","msg":"trace[2096002706] transaction","detail":"{read_only:false; response_revision:26774; number_of_response:1; }","duration":"115.239113ms","start":"2026-10-07T04:40:30.082405Z","end":"2026-10-07T04:40:30.197644Z","steps":["trace[2096002706] 'process raft request' (duration: 114.286672ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:47.895842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.14256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:19029"} {"level":"info","ts":"2026-10-07T04:40:47.895966Z","caller":"traceutil/trace.go:171","msg":"trace[1981626752] transaction","detail":"{read_only:false; response_revision:27053; number_of_response:1; }","duration":"214.242796ms","start":"2026-10-07T04:40:47.681698Z","end":"2026-10-07T04:40:47.895941Z","steps":["trace[1981626752] 'process raft request' (duration: 114.536087ms)","trace[1981626752] 'compare' (duration: 99.196869ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:47.896178Z","caller":"traceutil/trace.go:171","msg":"trace[5080162] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:27052; }","duration":"219.361056ms","start":"2026-10-07T04:40:47.676796Z","end":"2026-10-07T04:40:47.896157Z","steps":["trace[5080162] 'range keys from in-memory index tree' (duration: 217.848255ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:47.953654Z","caller":"traceutil/trace.go:171","msg":"trace[2295847] transaction","detail":"{read_only:false; response_revision:27054; number_of_response:1; }","duration":"268.591302ms","start":"2026-10-07T04:40:47.685027Z","end":"2026-10-07T04:40:47.953618Z","steps":["trace[2295847] 'process raft request' (duration: 268.338167ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:47.95417Z","caller":"traceutil/trace.go:171","msg":"trace[2146112706] transaction","detail":"{read_only:false; response_revision:27055; number_of_response:1; }","duration":"269.001231ms","start":"2026-10-07T04:40:47.685149Z","end":"2026-10-07T04:40:47.95415Z","steps":["trace[2146112706] 'process raft request' (duration: 268.407739ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:47.954401Z","caller":"traceutil/trace.go:171","msg":"trace[877559229] transaction","detail":"{read_only:false; response_revision:27056; number_of_response:1; }","duration":"265.531487ms","start":"2026-10-07T04:40:47.688834Z","end":"2026-10-07T04:40:47.954366Z","steps":["trace[877559229] 'process raft request' (duration: 265.201699ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:48.101483Z","caller":"traceutil/trace.go:171","msg":"trace[422725629] linearizableReadLoop","detail":"{readStateIndex:27939; appliedIndex:27938; }","duration":"146.136336ms","start":"2026-10-07T04:40:47.95531Z","end":"2026-10-07T04:40:48.101447Z","steps":["trace[422725629] 'read index received' (duration: 92.124527ms)","trace[422725629] 'applied index is now lower than readState.Index' (duration: 54.010179ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:48.101833Z","caller":"traceutil/trace.go:171","msg":"trace[248901170] transaction","detail":"{read_only:false; response_revision:27057; number_of_response:1; }","duration":"164.56952ms","start":"2026-10-07T04:40:47.937219Z","end":"2026-10-07T04:40:48.101788Z","steps":["trace[248901170] 'process raft request' (duration: 110.341397ms)","trace[248901170] 'compare' (duration: 53.681472ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:40:48.101958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.142549ms","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-10-07T04:40:48.102069Z","caller":"traceutil/trace.go:171","msg":"trace[1071643518] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:27057; }","duration":"100.266651ms","start":"2026-10-07T04:40:48.00178Z","end":"2026-10-07T04:40:48.102046Z","steps":["trace[1071643518] 'agreement among raft nodes before linearized reading' (duration: 99.981004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:48.102027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.668967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-10-07T04:40:48.102181Z","caller":"traceutil/trace.go:171","msg":"trace[1297020694] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:27057; }","duration":"146.878422ms","start":"2026-10-07T04:40:47.955268Z","end":"2026-10-07T04:40:48.102146Z","steps":["trace[1297020694] 'agreement among raft nodes before linearized reading' (duration: 146.485043ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:48.224635Z","caller":"traceutil/trace.go:171","msg":"trace[1036361616] transaction","detail":"{read_only:false; response_revision:27058; number_of_response:1; }","duration":"116.456678ms","start":"2026-10-07T04:40:48.108134Z","end":"2026-10-07T04:40:48.22459Z","steps":["trace[1036361616] 'process raft request' (duration: 107.639849ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:48.237482Z","caller":"traceutil/trace.go:171","msg":"trace[1359237812] transaction","detail":"{read_only:false; response_revision:27059; number_of_response:1; }","duration":"129.223972ms","start":"2026-10-07T04:40:48.108214Z","end":"2026-10-07T04:40:48.237438Z","steps":["trace[1359237812] 'process raft request' (duration: 129.068449ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:48.63247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.721494ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-07T04:40:48.632692Z","caller":"traceutil/trace.go:171","msg":"trace[1174309856] transaction","detail":"{read_only:false; response_revision:27060; number_of_response:1; }","duration":"320.128039ms","start":"2026-10-07T04:40:48.312515Z","end":"2026-10-07T04:40:48.632643Z","steps":["trace[1174309856] 'process raft request' (duration: 38.117718ms)","trace[1174309856] 'compare' (duration: 281.563871ms)"],"step_count":2} {"level":"info","ts":"2026-10-07T04:40:48.632781Z","caller":"traceutil/trace.go:171","msg":"trace[1095826451] linearizableReadLoop","detail":"{readStateIndex:27943; appliedIndex:27941; }","duration":"230.765012ms","start":"2026-10-07T04:40:48.401995Z","end":"2026-10-07T04:40:48.63276Z","steps":["trace[1095826451] 'read index received' (duration: 224.667271ms)","trace[1095826451] 'applied index is now lower than readState.Index' (duration: 6.096141ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:40:48.632892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:40:48.312487Z","time spent":"320.300313ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-07T04:40:48.63314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.71325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:40:48.633242Z","caller":"traceutil/trace.go:171","msg":"trace[633025982] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:27061; }","duration":"169.853894ms","start":"2026-10-07T04:40:48.463368Z","end":"2026-10-07T04:40:48.633222Z","steps":["trace[633025982] 'agreement among raft nodes before linearized reading' (duration: 169.64394ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:48.633358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.395094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-10-07T04:40:48.633542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.09958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:40:48.633586Z","caller":"traceutil/trace.go:171","msg":"trace[1892925193] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27061; }","duration":"170.157032ms","start":"2026-10-07T04:40:48.46342Z","end":"2026-10-07T04:40:48.633577Z","steps":["trace[1892925193] 'agreement among raft nodes before linearized reading' (duration: 170.08945ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:48.633624Z","caller":"traceutil/trace.go:171","msg":"trace[411458957] transaction","detail":"{read_only:false; response_revision:27061; number_of_response:1; }","duration":"320.742481ms","start":"2026-10-07T04:40:48.312861Z","end":"2026-10-07T04:40:48.633603Z","steps":["trace[411458957] 'process raft request' (duration: 319.821712ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:48.633713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-07T04:40:48.312842Z","time spent":"320.827564ms","remote":"127.0.0.1:48052","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-07T04:40:48.633803Z","caller":"traceutil/trace.go:171","msg":"trace[765978533] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27061; }","duration":"169.800643ms","start":"2026-10-07T04:40:48.463767Z","end":"2026-10-07T04:40:48.633568Z","steps":["trace[765978533] 'agreement among raft nodes before linearized reading' (duration: 169.335483ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:48.634245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.737745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:40:48.634355Z","caller":"traceutil/trace.go:171","msg":"trace[618570986] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27061; }","duration":"170.862907ms","start":"2026-10-07T04:40:48.463466Z","end":"2026-10-07T04:40:48.634329Z","steps":["trace[618570986] 'agreement among raft nodes before linearized reading' (duration: 169.591349ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:48.634692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.699802ms","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-10-07T04:40:48.634805Z","caller":"traceutil/trace.go:171","msg":"trace[664399041] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:27061; }","duration":"232.841636ms","start":"2026-10-07T04:40:48.401931Z","end":"2026-10-07T04:40:48.634773Z","steps":["trace[664399041] 'agreement among raft nodes before linearized reading' (duration: 230.978606ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:48.90921Z","caller":"traceutil/trace.go:171","msg":"trace[1530295938] transaction","detail":"{read_only:false; response_revision:27063; number_of_response:1; }","duration":"264.905735ms","start":"2026-10-07T04:40:48.644253Z","end":"2026-10-07T04:40:48.909158Z","steps":["trace[1530295938] 'process raft request' (duration: 249.961334ms)","trace[1530295938] 'compare' (duration: 14.303077ms)"],"step_count":2} {"level":"warn","ts":"2026-10-07T04:40:49.271367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.085295ms","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":"warn","ts":"2026-10-07T04:40:49.271397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.567711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:40:49.271563Z","caller":"traceutil/trace.go:171","msg":"trace[1049844807] range","detail":"{range_begin:/registry/jobs/openstack/manila-rabbit-init; range_end:; response_count:0; response_revision:27063; }","duration":"184.792305ms","start":"2026-10-07T04:40:49.086747Z","end":"2026-10-07T04:40:49.271539Z","steps":["trace[1049844807] 'range keys from in-memory index tree' (duration: 184.483618ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:49.27163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.455627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:40:49.271664Z","caller":"traceutil/trace.go:171","msg":"trace[1327940461] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:27063; }","duration":"183.541529ms","start":"2026-10-07T04:40:49.088113Z","end":"2026-10-07T04:40:49.271654Z","steps":["trace[1327940461] 'range keys from in-memory index tree' (duration: 183.313944ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:40:49.271471Z","caller":"traceutil/trace.go:171","msg":"trace[5716381] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:27063; }","duration":"112.227958ms","start":"2026-10-07T04:40:49.159217Z","end":"2026-10-07T04:40:49.271445Z","steps":["trace[5716381] 'range keys from in-memory index tree' (duration: 111.88507ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:49.271946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.714602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:40:49.271983Z","caller":"traceutil/trace.go:171","msg":"trace[1559089579] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:27063; }","duration":"183.752363ms","start":"2026-10-07T04:40:49.08822Z","end":"2026-10-07T04:40:49.271973Z","steps":["trace[1559089579] 'range keys from in-memory index tree' (duration: 183.62425ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:49.272755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.908246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/manila-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-10-07T04:40:49.272853Z","caller":"traceutil/trace.go:171","msg":"trace[1378558425] range","detail":"{range_begin:/registry/jobs/openstack/manila-db-sync; range_end:; response_count:0; response_revision:27063; }","duration":"184.786415ms","start":"2026-10-07T04:40:49.088043Z","end":"2026-10-07T04:40:49.272829Z","steps":["trace[1378558425] 'range keys from in-memory index tree' (duration: 183.688931ms)"],"step_count":1} {"level":"warn","ts":"2026-10-07T04:40:49.525694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.302705ms","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-10-07T04:40:49.525794Z","caller":"traceutil/trace.go:171","msg":"trace[515388770] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:27064; }","duration":"123.4776ms","start":"2026-10-07T04:40:49.402293Z","end":"2026-10-07T04:40:49.525771Z","steps":["trace[515388770] 'count revisions from in-memory index tree' (duration: 123.206413ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:41:43.787678Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24703} {"level":"info","ts":"2026-10-07T04:41:43.970465Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24703,"took":"177.58118ms","hash":2082508310,"current-db-size-bytes":58171392,"current-db-size":"58 MB","current-db-size-in-use-bytes":36073472,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-10-07T04:41:43.970588Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2082508310,"revision":24703,"compact-revision":21499} {"level":"info","ts":"2026-10-07T04:43:45.762512Z","caller":"traceutil/trace.go:171","msg":"trace[1218421650] transaction","detail":"{read_only:false; response_revision:28716; number_of_response:1; }","duration":"122.013908ms","start":"2026-10-07T04:43:45.640468Z","end":"2026-10-07T04:43:45.762482Z","steps":["trace[1218421650] 'process raft request' (duration: 121.302502ms)"],"step_count":1} {"level":"info","ts":"2026-10-07T04:44:18.414291Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"308ce60d3e55f7ff","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T04:44:18.421925Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-10-07T04:44:18.422055Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-10-07T04:46:43.79946Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27526} {"level":"info","ts":"2026-10-07T04:46:43.936066Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27526,"took":"131.062782ms","hash":3038245035,"current-db-size-bytes":58171392,"current-db-size":"58 MB","current-db-size-in-use-bytes":37052416,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-10-07T04:46:43.936172Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3038245035,"revision":27526,"compact-revision":24703} {"level":"info","ts":"2026-10-07T04:51:43.809397Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30196} {"level":"info","ts":"2026-10-07T04:51:43.969326Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30196,"took":"154.110774ms","hash":3378788843,"current-db-size-bytes":58171392,"current-db-size":"58 MB","current-db-size-in-use-bytes":30449664,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-07T04:51:43.969412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3378788843,"revision":30196,"compact-revision":27526} {"level":"info","ts":"2026-10-07T04:56:43.824801Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32443} {"level":"info","ts":"2026-10-07T04:56:44.00672Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32443,"took":"175.698247ms","hash":1525792453,"current-db-size-bytes":58171392,"current-db-size":"58 MB","current-db-size-in-use-bytes":30060544,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-07T04:56:44.006817Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1525792453,"revision":32443,"compact-revision":30196} {"level":"info","ts":"2026-10-07T05:01:43.831735Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":34671} {"level":"info","ts":"2026-10-07T05:01:43.96794Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":34671,"took":"132.279977ms","hash":1357677318,"current-db-size-bytes":58171392,"current-db-size":"58 MB","current-db-size-in-use-bytes":31891456,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-07T05:01:43.968033Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1357677318,"revision":34671,"compact-revision":32443} {"level":"info","ts":"2026-10-07T05:05:31.824108Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"308ce60d3e55f7ff","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-10-07T05:05:31.831179Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-10-07T05:05:31.831568Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-10-07T05:06:43.83855Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37030} {"level":"info","ts":"2026-10-07T05:06:43.996848Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37030,"took":"153.943019ms","hash":772153865,"current-db-size-bytes":58597376,"current-db-size":"59 MB","current-db-size-in-use-bytes":33730560,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-10-07T05:06:43.996955Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":772153865,"revision":37030,"compact-revision":34671} {"level":"info","ts":"2026-10-07T05:11:43.846835Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39333} {"level":"info","ts":"2026-10-07T05:11:44.02116Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39333,"took":"168.231774ms","hash":3819603926,"current-db-size-bytes":59023360,"current-db-size":"59 MB","current-db-size-in-use-bytes":31576064,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-10-07T05:11:44.021293Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3819603926,"revision":39333,"compact-revision":37030} {"level":"info","ts":"2026-10-07T05:16:43.854003Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":41562} {"level":"info","ts":"2026-10-07T05:16:43.980954Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":41562,"took":"120.479871ms","hash":1102836756,"current-db-size-bytes":59023360,"current-db-size":"59 MB","current-db-size-in-use-bytes":29532160,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-10-07T05:16:43.981048Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1102836756,"revision":41562,"compact-revision":39333}