{"level":"warn","ts":"2026-03-18T11:49:07.118856Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T11:49:07.118938Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-18T11:49:07.118999Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-18T11:49:07.119011Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-03-18T11:49:07.119027Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-18T11:49:07.119654Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-03-18T11:49:07.119728Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-18T11:49:07.128625Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.670716ms"} {"level":"info","ts":"2026-03-18T11:49:07.137541Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-03-18T11:49:07.137675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-03-18T11:49:07.137714Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-03-18T11:49:07.137738Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-18T11:49:07.137759Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-03-18T11:49:07.137823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-03-18T11:49:07.146313Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-18T11:49:07.151497Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-18T11:49:07.153507Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-18T11:49:07.158574Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-18T11:49:07.159741Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-18T11:49:07.160155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-03-18T11:49:07.160264Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-03-18T11:49:07.160349Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-18T11:49:07.160384Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-18T11:49:07.160402Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-18T11:49:07.162208Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-18T11:49:07.162453Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-18T11:49:07.162495Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-18T11:49:07.162653Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-03-18T11:49:07.162675Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-03-18T11:49:07.738854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-03-18T11:49:07.738943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-18T11:49:07.738981Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-03-18T11:49:07.739006Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-03-18T11:49:07.739018Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-03-18T11:49:07.739034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-03-18T11:49:07.739047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-03-18T11:49:07.740922Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-03-18T11:49:07.740926Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T11:49:07.740983Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T11:49:07.740989Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-18T11:49:07.741368Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-18T11:49:07.741413Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-18T11:49:07.741859Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T11:49:07.742007Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T11:49:07.742044Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-18T11:49:07.743438Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-18T11:49:07.745215Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"warn","ts":"2026-03-18T11:49:49.6352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.815943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/cert-manager/\" range_end:\"/registry/secrets/cert-manager0\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T11:49:49.635363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.796011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-03-18T11:49:49.635391Z","caller":"traceutil/trace.go:171","msg":"trace[541484291] range","detail":"{range_begin:/registry/secrets/cert-manager/; range_end:/registry/secrets/cert-manager0; response_count:0; response_revision:561; }","duration":"279.07944ms","start":"2026-03-18T11:49:49.356278Z","end":"2026-03-18T11:49:49.635358Z","steps":["trace[541484291] 'range keys from in-memory index tree' (duration: 278.689661ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:49:49.635433Z","caller":"traceutil/trace.go:171","msg":"trace[2009752665] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"152.940705ms","start":"2026-03-18T11:49:49.482473Z","end":"2026-03-18T11:49:49.635414Z","steps":["trace[2009752665] 'range keys from in-memory index tree' (duration: 152.549035ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:49:49.92754Z","caller":"traceutil/trace.go:171","msg":"trace[1886796723] linearizableReadLoop","detail":"{readStateIndex:582; appliedIndex:581; }","duration":"234.311507ms","start":"2026-03-18T11:49:49.693206Z","end":"2026-03-18T11:49:49.927517Z","steps":["trace[1886796723] 'read index received' (duration: 234.233255ms)","trace[1886796723] 'applied index is now lower than readState.Index' (duration: 77.032µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:49:49.927616Z","caller":"traceutil/trace.go:171","msg":"trace[814220406] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"274.385864ms","start":"2026-03-18T11:49:49.653184Z","end":"2026-03-18T11:49:49.92757Z","steps":["trace[814220406] 'process raft request' (duration: 274.209309ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:49:49.92776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.537603ms","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-03-18T11:49:49.9278Z","caller":"traceutil/trace.go:171","msg":"trace[41573717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:563; }","duration":"234.630995ms","start":"2026-03-18T11:49:49.693159Z","end":"2026-03-18T11:49:49.92779Z","steps":["trace[41573717] 'agreement among raft nodes before linearized reading' (duration: 234.43994ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:50:10.961249Z","caller":"traceutil/trace.go:171","msg":"trace[1635985248] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"136.396176ms","start":"2026-03-18T11:50:10.824819Z","end":"2026-03-18T11:50:10.961215Z","steps":["trace[1635985248] 'process raft request' (duration: 128.967054ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:52:44.462008Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.238761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-03-18T11:52:44.462007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.903491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T11:52:44.462095Z","caller":"traceutil/trace.go:171","msg":"trace[1058523709] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2328; }","duration":"186.395514ms","start":"2026-03-18T11:52:44.275682Z","end":"2026-03-18T11:52:44.462078Z","steps":["trace[1058523709] 'range keys from in-memory index tree' (duration: 186.074258ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:52:44.462167Z","caller":"traceutil/trace.go:171","msg":"trace[2130096122] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2328; }","duration":"109.094875ms","start":"2026-03-18T11:52:44.353039Z","end":"2026-03-18T11:52:44.462134Z","steps":["trace[2130096122] 'count revisions from in-memory index tree' (duration: 108.805689ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:53:18.567239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.15113ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T11:53:18.567432Z","caller":"traceutil/trace.go:171","msg":"trace[1000038563] transaction","detail":"{read_only:false; response_revision:2515; number_of_response:1; }","duration":"221.118814ms","start":"2026-03-18T11:53:18.346298Z","end":"2026-03-18T11:53:18.567417Z","steps":["trace[1000038563] 'process raft request' (duration: 221.048242ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:53:18.567412Z","caller":"traceutil/trace.go:171","msg":"trace[1699842106] transaction","detail":"{read_only:false; response_revision:2514; number_of_response:1; }","duration":"372.29887ms","start":"2026-03-18T11:53:18.195068Z","end":"2026-03-18T11:53:18.567367Z","steps":["trace[1699842106] 'process raft request' (duration: 67.329462ms)","trace[1699842106] 'compare' (duration: 303.977816ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:53:18.567429Z","caller":"traceutil/trace.go:171","msg":"trace[1607452010] linearizableReadLoop","detail":"{readStateIndex:2614; appliedIndex:2613; }","duration":"327.305306ms","start":"2026-03-18T11:53:18.240096Z","end":"2026-03-18T11:53:18.567401Z","steps":["trace[1607452010] 'read index received' (duration: 22.282627ms)","trace[1607452010] 'applied index is now lower than readState.Index' (duration: 305.020719ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T11:53:18.567556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:53:18.195045Z","time spent":"372.446063ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T11:53:18.567779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.855562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-18T11:53:18.567828Z","caller":"traceutil/trace.go:171","msg":"trace[1671340464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2515; }","duration":"249.955375ms","start":"2026-03-18T11:53:18.31786Z","end":"2026-03-18T11:53:18.567815Z","steps":["trace[1671340464] 'agreement among raft nodes before linearized reading' (duration: 249.684229ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:53:18.567877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.649386ms","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":"info","ts":"2026-03-18T11:53:18.568023Z","caller":"traceutil/trace.go:171","msg":"trace[772035595] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2515; }","duration":"226.83849ms","start":"2026-03-18T11:53:18.341129Z","end":"2026-03-18T11:53:18.567967Z","steps":["trace[772035595] 'agreement among raft nodes before linearized reading' (duration: 226.490082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:53:18.568466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.365109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-03-18T11:53:18.568551Z","caller":"traceutil/trace.go:171","msg":"trace[1642882010] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2515; }","duration":"328.484081ms","start":"2026-03-18T11:53:18.240043Z","end":"2026-03-18T11:53:18.568527Z","steps":["trace[1642882010] 'agreement among raft nodes before linearized reading' (duration: 327.491099ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:53:18.568596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T11:53:18.24002Z","time spent":"328.563553ms","remote":"127.0.0.1:46754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1390,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-03-18T11:57:07.936804Z","caller":"traceutil/trace.go:171","msg":"trace[697611958] linearizableReadLoop","detail":"{readStateIndex:4518; appliedIndex:4517; }","duration":"121.642874ms","start":"2026-03-18T11:57:07.815139Z","end":"2026-03-18T11:57:07.936781Z","steps":["trace[697611958] 'read index received' (duration: 121.493991ms)","trace[697611958] 'applied index is now lower than readState.Index' (duration: 147.923µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:57:07.936891Z","caller":"traceutil/trace.go:171","msg":"trace[145566152] transaction","detail":"{read_only:false; response_revision:4343; number_of_response:1; }","duration":"124.047315ms","start":"2026-03-18T11:57:07.812828Z","end":"2026-03-18T11:57:07.936875Z","steps":["trace[145566152] 'process raft request' (duration: 123.820631ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:57:07.937039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.431904ms","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":"warn","ts":"2026-03-18T11:57:07.937071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.443481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-18T11:57:07.937076Z","caller":"traceutil/trace.go:171","msg":"trace[924596600] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4343; }","duration":"115.510735ms","start":"2026-03-18T11:57:07.821553Z","end":"2026-03-18T11:57:07.937063Z","steps":["trace[924596600] 'agreement among raft nodes before linearized reading' (duration: 115.357731ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:57:07.937093Z","caller":"traceutil/trace.go:171","msg":"trace[1947716814] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4343; }","duration":"112.495422ms","start":"2026-03-18T11:57:07.824591Z","end":"2026-03-18T11:57:07.937086Z","steps":["trace[1947716814] 'agreement among raft nodes before linearized reading' (duration: 112.41624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T11:57:07.937069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.913081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-18T11:57:07.937135Z","caller":"traceutil/trace.go:171","msg":"trace[843644819] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4343; }","duration":"122.029423ms","start":"2026-03-18T11:57:07.81509Z","end":"2026-03-18T11:57:07.937119Z","steps":["trace[843644819] 'agreement among raft nodes before linearized reading' (duration: 121.806028ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T11:57:33.01358Z","caller":"traceutil/trace.go:171","msg":"trace[1485926251] transaction","detail":"{read_only:false; response_revision:4915; number_of_response:1; }","duration":"261.267596ms","start":"2026-03-18T11:57:32.752262Z","end":"2026-03-18T11:57:33.01353Z","steps":["trace[1485926251] 'process raft request' (duration: 175.652225ms)","trace[1485926251] 'compare' (duration: 85.508039ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:57:33.56082Z","caller":"traceutil/trace.go:171","msg":"trace[664824309] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"102.715604ms","start":"2026-03-18T11:57:33.458073Z","end":"2026-03-18T11:57:33.560788Z","steps":["trace[664824309] 'process raft request' (duration: 90.193356ms)","trace[664824309] 'compare' (duration: 12.310734ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T11:59:07.765676Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2761} {"level":"info","ts":"2026-03-18T11:59:07.828275Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2761,"took":"61.12434ms","hash":1594478743,"current-db-size-bytes":41381888,"current-db-size":"41 MB","current-db-size-in-use-bytes":31109120,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-18T11:59:07.828334Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1594478743,"revision":2761,"compact-revision":-1} {"level":"info","ts":"2026-03-18T12:03:35.079514Z","caller":"traceutil/trace.go:171","msg":"trace[1200591985] transaction","detail":"{read_only:false; response_revision:7618; number_of_response:1; }","duration":"307.545757ms","start":"2026-03-18T12:03:34.771934Z","end":"2026-03-18T12:03:35.07948Z","steps":["trace[1200591985] 'process raft request' (duration: 307.362133ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:03:35.079699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:03:34.771907Z","time spent":"307.706931ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:03:35.219536Z","caller":"traceutil/trace.go:171","msg":"trace[1283738759] linearizableReadLoop","detail":"{readStateIndex:7899; appliedIndex:7898; }","duration":"116.357639ms","start":"2026-03-18T12:03:35.103148Z","end":"2026-03-18T12:03:35.219506Z","steps":["trace[1283738759] 'read index received' (duration: 113.035087ms)","trace[1283738759] 'applied index is now lower than readState.Index' (duration: 3.321082ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:03:35.219694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.538092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T12:03:35.219667Z","caller":"traceutil/trace.go:171","msg":"trace[1752525403] transaction","detail":"{read_only:false; response_revision:7619; number_of_response:1; }","duration":"358.328627ms","start":"2026-03-18T12:03:34.861315Z","end":"2026-03-18T12:03:35.219644Z","steps":["trace[1752525403] 'process raft request' (duration: 354.874432ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:03:35.21973Z","caller":"traceutil/trace.go:171","msg":"trace[1843179086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7619; }","duration":"116.627834ms","start":"2026-03-18T12:03:35.10309Z","end":"2026-03-18T12:03:35.219718Z","steps":["trace[1843179086] 'agreement among raft nodes before linearized reading' (duration: 116.557483ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:03:35.219805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:03:34.861285Z","time spent":"358.446519ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:04:07.772883Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5724} {"level":"info","ts":"2026-03-18T12:04:07.863256Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5724,"took":"88.371094ms","hash":1095636776,"current-db-size-bytes":42545152,"current-db-size":"42 MB","current-db-size-in-use-bytes":23289856,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-03-18T12:04:07.863346Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1095636776,"revision":5724,"compact-revision":2761} {"level":"warn","ts":"2026-03-18T12:04:18.320276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.323846ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-18T12:04:18.3204Z","caller":"traceutil/trace.go:171","msg":"trace[1779748370] linearizableReadLoop","detail":"{readStateIndex:8174; appliedIndex:8173; }","duration":"130.007685ms","start":"2026-03-18T12:04:18.190373Z","end":"2026-03-18T12:04:18.32038Z","steps":["trace[1779748370] 'read index received' (duration: 36.701µs)","trace[1779748370] 'applied index is now lower than readState.Index' (duration: 129.969454ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:04:18.320464Z","caller":"traceutil/trace.go:171","msg":"trace[1698584900] transaction","detail":"{read_only:false; response_revision:7882; number_of_response:1; }","duration":"138.795325ms","start":"2026-03-18T12:04:18.181632Z","end":"2026-03-18T12:04:18.320427Z","steps":["trace[1698584900] 'compare' (duration: 133.144252ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:04:18.320656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.184209ms","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":"info","ts":"2026-03-18T12:04:18.320705Z","caller":"traceutil/trace.go:171","msg":"trace[1640079506] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7882; }","duration":"130.359623ms","start":"2026-03-18T12:04:18.19033Z","end":"2026-03-18T12:04:18.32069Z","steps":["trace[1640079506] 'agreement among raft nodes before linearized reading' (duration: 130.100587ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:04:18.686712Z","caller":"traceutil/trace.go:171","msg":"trace[2036975772] linearizableReadLoop","detail":"{readStateIndex:8176; appliedIndex:8175; }","duration":"190.661408ms","start":"2026-03-18T12:04:18.496028Z","end":"2026-03-18T12:04:18.686689Z","steps":["trace[2036975772] 'read index received' (duration: 190.525154ms)","trace[2036975772] 'applied index is now lower than readState.Index' (duration: 135.664µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:04:18.68685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.799601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T12:04:18.686881Z","caller":"traceutil/trace.go:171","msg":"trace[1095953456] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:7884; }","duration":"190.889403ms","start":"2026-03-18T12:04:18.495982Z","end":"2026-03-18T12:04:18.686871Z","steps":["trace[1095953456] 'agreement among raft nodes before linearized reading' (duration: 190.810801ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:04:18.686765Z","caller":"traceutil/trace.go:171","msg":"trace[782154382] transaction","detail":"{read_only:false; response_revision:7884; number_of_response:1; }","duration":"229.077838ms","start":"2026-03-18T12:04:18.45767Z","end":"2026-03-18T12:04:18.686747Z","steps":["trace[782154382] 'process raft request' (duration: 228.887484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:04:28.248443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.955085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-18T12:04:28.248523Z","caller":"traceutil/trace.go:171","msg":"trace[580830171] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:7973; }","duration":"131.084148ms","start":"2026-03-18T12:04:28.117423Z","end":"2026-03-18T12:04:28.248507Z","steps":["trace[580830171] 'count revisions from in-memory index tree' (duration: 130.861273ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:04:28.248733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.040818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T12:04:28.248908Z","caller":"traceutil/trace.go:171","msg":"trace[979521060] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7973; }","duration":"144.251533ms","start":"2026-03-18T12:04:28.104635Z","end":"2026-03-18T12:04:28.248886Z","steps":["trace[979521060] 'range keys from in-memory index tree' (duration: 143.921136ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:04:28.463795Z","caller":"traceutil/trace.go:171","msg":"trace[1750725865] transaction","detail":"{read_only:false; response_revision:7975; number_of_response:1; }","duration":"115.051541ms","start":"2026-03-18T12:04:28.34872Z","end":"2026-03-18T12:04:28.463772Z","steps":["trace[1750725865] 'process raft request' (duration: 114.908668ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:04:28.464304Z","caller":"traceutil/trace.go:171","msg":"trace[912736048] linearizableReadLoop","detail":"{readStateIndex:8273; appliedIndex:8273; }","duration":"106.934214ms","start":"2026-03-18T12:04:28.357342Z","end":"2026-03-18T12:04:28.464277Z","steps":["trace[912736048] 'read index received' (duration: 106.925784ms)","trace[912736048] 'applied index is now lower than readState.Index' (duration: 7.06µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:04:28.46454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.177539ms","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-03-18T12:04:28.464588Z","caller":"traceutil/trace.go:171","msg":"trace[213777533] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:7975; }","duration":"107.262152ms","start":"2026-03-18T12:04:28.357311Z","end":"2026-03-18T12:04:28.464573Z","steps":["trace[213777533] 'agreement among raft nodes before linearized reading' (duration: 107.096928ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:04:28.478248Z","caller":"traceutil/trace.go:171","msg":"trace[1046985436] transaction","detail":"{read_only:false; response_revision:7976; number_of_response:1; }","duration":"118.28442ms","start":"2026-03-18T12:04:28.359952Z","end":"2026-03-18T12:04:28.478237Z","steps":["trace[1046985436] 'process raft request' (duration: 118.204079ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:05:42.989434Z","caller":"traceutil/trace.go:171","msg":"trace[1074401525] transaction","detail":"{read_only:false; response_revision:8676; number_of_response:1; }","duration":"103.633365ms","start":"2026-03-18T12:05:42.885779Z","end":"2026-03-18T12:05:42.989412Z","steps":["trace[1074401525] 'process raft request' (duration: 103.38823ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:08:01.661825Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-18T12:08:01.666631Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-18T12:08:01.666702Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-18T12:09:07.780306Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7781} {"level":"info","ts":"2026-03-18T12:09:07.843826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7781,"took":"60.283459ms","hash":828102582,"current-db-size-bytes":42545152,"current-db-size":"42 MB","current-db-size-in-use-bytes":27914240,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-18T12:09:07.843889Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":828102582,"revision":7781,"compact-revision":5724} {"level":"info","ts":"2026-03-18T12:09:10.328373Z","caller":"traceutil/trace.go:171","msg":"trace[96488979] transaction","detail":"{read_only:false; response_revision:10329; number_of_response:1; }","duration":"123.142775ms","start":"2026-03-18T12:09:10.20519Z","end":"2026-03-18T12:09:10.328333Z","steps":["trace[96488979] 'process raft request' (duration: 33.393529ms)","trace[96488979] 'compare' (duration: 89.209234ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:14:07.786042Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10313} {"level":"info","ts":"2026-03-18T12:14:07.863034Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10313,"took":"73.742825ms","hash":2044821637,"current-db-size-bytes":42545152,"current-db-size":"42 MB","current-db-size-in-use-bytes":24309760,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-18T12:14:07.863089Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2044821637,"revision":10313,"compact-revision":7781} {"level":"info","ts":"2026-03-18T12:15:19.69119Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000003329.wal"} {"level":"info","ts":"2026-03-18T12:15:52.32081Z","caller":"traceutil/trace.go:171","msg":"trace[628490073] transaction","detail":"{read_only:false; response_revision:12819; number_of_response:1; }","duration":"112.224578ms","start":"2026-03-18T12:15:52.208565Z","end":"2026-03-18T12:15:52.32079Z","steps":["trace[628490073] 'process raft request' (duration: 103.639575ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:15:53.477632Z","caller":"traceutil/trace.go:171","msg":"trace[483660076] transaction","detail":"{read_only:false; response_revision:12821; number_of_response:1; }","duration":"303.149119ms","start":"2026-03-18T12:15:53.174429Z","end":"2026-03-18T12:15:53.477579Z","steps":["trace[483660076] 'process raft request' (duration: 302.944765ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:15:53.47784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:15:53.174406Z","time spent":"303.343094ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:16:02.206146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.052183ms","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-03-18T12:16:02.206239Z","caller":"traceutil/trace.go:171","msg":"trace[51079115] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:12980; }","duration":"103.196906ms","start":"2026-03-18T12:16:02.103024Z","end":"2026-03-18T12:16:02.206221Z","steps":["trace[51079115] 'range keys from in-memory index tree' (duration: 102.91288ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:16:02.206295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.158765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T12:16:02.206368Z","caller":"traceutil/trace.go:171","msg":"trace[1110858735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:12980; }","duration":"102.261646ms","start":"2026-03-18T12:16:02.104085Z","end":"2026-03-18T12:16:02.206347Z","steps":["trace[1110858735] 'range keys from in-memory index tree' (duration: 102.094793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:16:02.20636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.529119ms","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":"info","ts":"2026-03-18T12:16:02.206448Z","caller":"traceutil/trace.go:171","msg":"trace[1173951147] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:12980; }","duration":"107.660281ms","start":"2026-03-18T12:16:02.098765Z","end":"2026-03-18T12:16:02.206426Z","steps":["trace[1173951147] 'range keys from in-memory index tree' (duration: 107.297424ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:16:02.206908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.101227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:17 size:158278"} {"level":"info","ts":"2026-03-18T12:16:02.206945Z","caller":"traceutil/trace.go:171","msg":"trace[570571865] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:17; response_revision:12980; }","duration":"176.177729ms","start":"2026-03-18T12:16:02.03076Z","end":"2026-03-18T12:16:02.206938Z","steps":["trace[570571865] 'range keys from in-memory index tree' (duration: 175.292431ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:16:02.356208Z","caller":"traceutil/trace.go:171","msg":"trace[824643105] transaction","detail":"{read_only:false; response_revision:12981; number_of_response:1; }","duration":"145.18923ms","start":"2026-03-18T12:16:02.210994Z","end":"2026-03-18T12:16:02.356184Z","steps":["trace[824643105] 'process raft request' (duration: 145.012426ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:16:02.533113Z","caller":"traceutil/trace.go:171","msg":"trace[213105171] transaction","detail":"{read_only:false; response_revision:12982; number_of_response:1; }","duration":"322.067432ms","start":"2026-03-18T12:16:02.211014Z","end":"2026-03-18T12:16:02.533082Z","steps":["trace[213105171] 'process raft request' (duration: 231.526037ms)","trace[213105171] 'compare' (duration: 90.326231ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:16:02.53318Z","caller":"traceutil/trace.go:171","msg":"trace[867245334] transaction","detail":"{read_only:false; response_revision:12984; number_of_response:1; }","duration":"174.023583ms","start":"2026-03-18T12:16:02.359124Z","end":"2026-03-18T12:16:02.533147Z","steps":["trace[867245334] 'process raft request' (duration: 173.950941ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:16:02.533225Z","caller":"traceutil/trace.go:171","msg":"trace[835985503] transaction","detail":"{read_only:false; response_revision:12983; number_of_response:1; }","duration":"321.52831ms","start":"2026-03-18T12:16:02.211672Z","end":"2026-03-18T12:16:02.533201Z","steps":["trace[835985503] 'process raft request' (duration: 321.339336ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:16:02.533254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:16:02.210999Z","time spent":"322.180204ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:16:02.533376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:16:02.211662Z","time spent":"321.647873ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:17:39.790646Z","caller":"traceutil/trace.go:171","msg":"trace[1207756704] transaction","detail":"{read_only:false; response_revision:14002; number_of_response:1; }","duration":"112.741399ms","start":"2026-03-18T12:17:39.677873Z","end":"2026-03-18T12:17:39.790614Z","steps":["trace[1207756704] 'process raft request' (duration: 112.555425ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:17:40.162142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.183452ms","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-03-18T12:17:40.162211Z","caller":"traceutil/trace.go:171","msg":"trace[557304497] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14002; }","duration":"186.296434ms","start":"2026-03-18T12:17:39.975898Z","end":"2026-03-18T12:17:40.162195Z","steps":["trace[557304497] 'range keys from in-memory index tree' (duration: 186.054219ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:17:40.162227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.424385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-18T12:17:40.162263Z","caller":"traceutil/trace.go:171","msg":"trace[1382865937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14002; }","duration":"185.497627ms","start":"2026-03-18T12:17:39.976756Z","end":"2026-03-18T12:17:40.162253Z","steps":["trace[1382865937] 'range keys from in-memory index tree' (duration: 185.322573ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:17:40.16236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.052797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-03-18T12:17:40.16238Z","caller":"traceutil/trace.go:171","msg":"trace[176917390] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14002; }","duration":"169.114558ms","start":"2026-03-18T12:17:39.993259Z","end":"2026-03-18T12:17:40.162373Z","steps":["trace[176917390] 'range keys from in-memory index tree' (duration: 168.922314ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:17:40.31498Z","caller":"traceutil/trace.go:171","msg":"trace[1118069236] transaction","detail":"{read_only:false; response_revision:14003; number_of_response:1; }","duration":"148.51394ms","start":"2026-03-18T12:17:40.166448Z","end":"2026-03-18T12:17:40.314962Z","steps":["trace[1118069236] 'process raft request' (duration: 148.359807ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:17:40.570191Z","caller":"traceutil/trace.go:171","msg":"trace[1417133463] linearizableReadLoop","detail":"{readStateIndex:14503; appliedIndex:14501; }","duration":"403.005435ms","start":"2026-03-18T12:17:40.167158Z","end":"2026-03-18T12:17:40.570164Z","steps":["trace[1417133463] 'read index received' (duration: 147.655151ms)","trace[1417133463] 'applied index is now lower than readState.Index' (duration: 255.349484ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:17:40.570389Z","caller":"traceutil/trace.go:171","msg":"trace[1482548157] transaction","detail":"{read_only:false; response_revision:14004; number_of_response:1; }","duration":"403.380262ms","start":"2026-03-18T12:17:40.166996Z","end":"2026-03-18T12:17:40.570376Z","steps":["trace[1482548157] 'process raft request' (duration: 348.114156ms)","trace[1482548157] 'compare' (duration: 54.887678ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:17:40.570486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:17:40.166976Z","time spent":"403.447494ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:17:40.571387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.21458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:80 size:777270"} {"level":"info","ts":"2026-03-18T12:17:40.571443Z","caller":"traceutil/trace.go:171","msg":"trace[126771315] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:80; response_revision:14004; }","duration":"404.296011ms","start":"2026-03-18T12:17:40.167135Z","end":"2026-03-18T12:17:40.571431Z","steps":["trace[126771315] 'agreement among raft nodes before linearized reading' (duration: 403.525735ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:17:40.571479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:17:40.167127Z","time spent":"404.341863ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":80,"response size":777291,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-03-18T12:17:40.583501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.806879ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T12:17:40.58358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.746106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-03-18T12:17:40.583597Z","caller":"traceutil/trace.go:171","msg":"trace[1598099466] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:14005; }","duration":"268.931082ms","start":"2026-03-18T12:17:40.314642Z","end":"2026-03-18T12:17:40.583574Z","steps":["trace[1598099466] 'agreement among raft nodes before linearized reading' (duration: 268.782649ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:17:40.583632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.67564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T12:17:40.583655Z","caller":"traceutil/trace.go:171","msg":"trace[1869450974] transaction","detail":"{read_only:false; response_revision:14005; number_of_response:1; }","duration":"255.014416ms","start":"2026-03-18T12:17:40.328629Z","end":"2026-03-18T12:17:40.583644Z","steps":["trace[1869450974] 'process raft request' (duration: 254.499535ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:17:40.583718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.093895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-03-18T12:17:40.583678Z","caller":"traceutil/trace.go:171","msg":"trace[482145123] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:14005; }","duration":"341.771312ms","start":"2026-03-18T12:17:40.241892Z","end":"2026-03-18T12:17:40.583663Z","steps":["trace[482145123] 'agreement among raft nodes before linearized reading' (duration: 341.66687ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:17:40.583759Z","caller":"traceutil/trace.go:171","msg":"trace[931242024] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14005; }","duration":"269.169537ms","start":"2026-03-18T12:17:40.314581Z","end":"2026-03-18T12:17:40.58375Z","steps":["trace[931242024] 'agreement among raft nodes before linearized reading' (duration: 269.072475ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:17:40.583809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.032964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-18T12:17:40.583509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.17135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-9h7zn\" ","response":"range_response_count:1 size:22125"} {"level":"info","ts":"2026-03-18T12:17:40.583842Z","caller":"traceutil/trace.go:171","msg":"trace[1743037234] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:14005; }","duration":"269.068345ms","start":"2026-03-18T12:17:40.314767Z","end":"2026-03-18T12:17:40.583835Z","steps":["trace[1743037234] 'agreement among raft nodes before linearized reading' (duration: 269.021574ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:17:40.583853Z","caller":"traceutil/trace.go:171","msg":"trace[1191758467] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-9h7zn; range_end:; response_count:1; response_revision:14005; }","duration":"374.561728ms","start":"2026-03-18T12:17:40.209283Z","end":"2026-03-18T12:17:40.583845Z","steps":["trace[1191758467] 'agreement among raft nodes before linearized reading' (duration: 374.055827ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:17:40.583859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.496552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-18T12:17:40.583889Z","caller":"traceutil/trace.go:171","msg":"trace[182427858] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:14005; }","duration":"268.560303ms","start":"2026-03-18T12:17:40.31532Z","end":"2026-03-18T12:17:40.58388Z","steps":["trace[182427858] 'agreement among raft nodes before linearized reading' (duration: 268.521742ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:17:40.583893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:17:40.209272Z","time spent":"374.60758ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22146,"request content":"key:\"/registry/pods/openstack/nova-compute-default-9h7zn\" "} {"level":"info","ts":"2026-03-18T12:17:40.58363Z","caller":"traceutil/trace.go:171","msg":"trace[1793951161] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14005; }","duration":"275.828698ms","start":"2026-03-18T12:17:40.307787Z","end":"2026-03-18T12:17:40.583615Z","steps":["trace[1793951161] 'agreement among raft nodes before linearized reading' (duration: 275.696996ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:17:40.583955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:17:40.241876Z","time spent":"341.962356ms","remote":"127.0.0.1:56942","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":26,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-03-18T12:17:40.807413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.796618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-03-18T12:17:40.807549Z","caller":"traceutil/trace.go:171","msg":"trace[105687011] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:14006; }","duration":"126.967272ms","start":"2026-03-18T12:17:40.680559Z","end":"2026-03-18T12:17:40.807526Z","steps":["trace[105687011] 'range keys from in-memory index tree' (duration: 126.603194ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:17:41.062285Z","caller":"traceutil/trace.go:171","msg":"trace[497563877] transaction","detail":"{read_only:false; response_revision:14009; number_of_response:1; }","duration":"117.623242ms","start":"2026-03-18T12:17:40.944636Z","end":"2026-03-18T12:17:41.062259Z","steps":["trace[497563877] 'process raft request' (duration: 91.15874ms)","trace[497563877] 'compare' (duration: 26.310499ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:19:07.79438Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12204} {"level":"info","ts":"2026-03-18T12:19:07.87249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12204,"took":"75.292992ms","hash":4006769192,"current-db-size-bytes":42545152,"current-db-size":"42 MB","current-db-size-in-use-bytes":27947008,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-18T12:19:07.872553Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4006769192,"revision":12204,"compact-revision":10313} {"level":"info","ts":"2026-03-18T12:23:25.181937Z","caller":"traceutil/trace.go:171","msg":"trace[1559033906] linearizableReadLoop","detail":"{readStateIndex:17038; appliedIndex:17037; }","duration":"303.878265ms","start":"2026-03-18T12:23:24.878013Z","end":"2026-03-18T12:23:25.181891Z","steps":["trace[1559033906] 'read index received' (duration: 209.773247ms)","trace[1559033906] 'applied index is now lower than readState.Index' (duration: 94.103748ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:23:25.181952Z","caller":"traceutil/trace.go:171","msg":"trace[513922663] transaction","detail":"{read_only:false; response_revision:16453; number_of_response:1; }","duration":"346.884554ms","start":"2026-03-18T12:23:24.835018Z","end":"2026-03-18T12:23:25.181902Z","steps":["trace[513922663] 'process raft request' (duration: 252.783435ms)","trace[513922663] 'compare' (duration: 93.737031ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:23:25.18218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.250767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-03-18T12:23:25.182184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:23:24.835004Z","time spent":"347.048968ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:23:25.182211Z","caller":"traceutil/trace.go:171","msg":"trace[1428129260] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:16453; }","duration":"158.302248ms","start":"2026-03-18T12:23:25.0239Z","end":"2026-03-18T12:23:25.182202Z","steps":["trace[1428129260] 'agreement among raft nodes before linearized reading' (duration: 158.175055ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:23:25.182266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.167081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-03-18T12:23:25.182353Z","caller":"traceutil/trace.go:171","msg":"trace[1873925386] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:16453; }","duration":"304.363576ms","start":"2026-03-18T12:23:24.877974Z","end":"2026-03-18T12:23:25.182338Z","steps":["trace[1873925386] 'agreement among raft nodes before linearized reading' (duration: 304.029199ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:23:25.182397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:23:24.877958Z","time spent":"304.428137ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-03-18T12:23:25.692899Z","caller":"traceutil/trace.go:171","msg":"trace[1348256097] linearizableReadLoop","detail":"{readStateIndex:17040; appliedIndex:17039; }","duration":"436.923465ms","start":"2026-03-18T12:23:25.255944Z","end":"2026-03-18T12:23:25.692868Z","steps":["trace[1348256097] 'read index received' (duration: 436.663159ms)","trace[1348256097] 'applied index is now lower than readState.Index' (duration: 259.206µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:23:25.693074Z","caller":"traceutil/trace.go:171","msg":"trace[23305002] transaction","detail":"{read_only:false; response_revision:16455; number_of_response:1; }","duration":"472.545456ms","start":"2026-03-18T12:23:25.22045Z","end":"2026-03-18T12:23:25.692996Z","steps":["trace[23305002] 'process raft request' (duration: 472.251179ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:23:25.693153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.174662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-18T12:23:25.693217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:23:25.220418Z","time spent":"472.73117ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":20981,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:23:25.69327Z","caller":"traceutil/trace.go:171","msg":"trace[1249654588] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:16455; }","duration":"437.324065ms","start":"2026-03-18T12:23:25.255923Z","end":"2026-03-18T12:23:25.693247Z","steps":["trace[1249654588] 'agreement among raft nodes before linearized reading' (duration: 437.114379ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:23:25.693324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:23:25.255915Z","time spent":"437.399606ms","remote":"127.0.0.1:47010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-03-18T12:24:07.802772Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14542} {"level":"info","ts":"2026-03-18T12:24:07.905893Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14542,"took":"99.023627ms","hash":2129232011,"current-db-size-bytes":43388928,"current-db-size":"43 MB","current-db-size-in-use-bytes":30494720,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-18T12:24:07.906296Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2129232011,"revision":14542,"compact-revision":12204} {"level":"warn","ts":"2026-03-18T12:27:58.686293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.497098ms","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-03-18T12:27:58.686367Z","caller":"traceutil/trace.go:171","msg":"trace[100296480] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18518; }","duration":"438.626531ms","start":"2026-03-18T12:27:58.247723Z","end":"2026-03-18T12:27:58.686349Z","steps":["trace[100296480] 'range keys from in-memory index tree' (duration: 438.312235ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:27:58.686396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:27:58.247699Z","time spent":"438.690673ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T12:28:24.644908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.99893ms","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-03-18T12:28:24.644989Z","caller":"traceutil/trace.go:171","msg":"trace[944676086] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18636; }","duration":"278.144122ms","start":"2026-03-18T12:28:24.366824Z","end":"2026-03-18T12:28:24.644968Z","steps":["trace[944676086] 'range keys from in-memory index tree' (duration: 277.819145ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:28:24.665305Z","caller":"traceutil/trace.go:171","msg":"trace[1903772320] transaction","detail":"{read_only:false; response_revision:18637; number_of_response:1; }","duration":"160.474617ms","start":"2026-03-18T12:28:24.504809Z","end":"2026-03-18T12:28:24.665284Z","steps":["trace[1903772320] 'process raft request' (duration: 160.276203ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:28:29.789559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.585471ms","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-03-18T12:28:29.789656Z","caller":"traceutil/trace.go:171","msg":"trace[717496423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18660; }","duration":"131.731385ms","start":"2026-03-18T12:28:29.657899Z","end":"2026-03-18T12:28:29.78963Z","steps":["trace[717496423] 'range keys from in-memory index tree' (duration: 131.409868ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:28:46.110733Z","caller":"traceutil/trace.go:171","msg":"trace[1750529385] transaction","detail":"{read_only:false; response_revision:18736; number_of_response:1; }","duration":"205.877714ms","start":"2026-03-18T12:28:45.904828Z","end":"2026-03-18T12:28:46.110705Z","steps":["trace[1750529385] 'process raft request' (duration: 205.72257ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:28:51.340279Z","caller":"traceutil/trace.go:171","msg":"trace[1803025203] transaction","detail":"{read_only:false; response_revision:18761; number_of_response:1; }","duration":"105.134859ms","start":"2026-03-18T12:28:51.235068Z","end":"2026-03-18T12:28:51.340203Z","steps":["trace[1803025203] 'process raft request' (duration: 51.548518ms)","trace[1803025203] 'compare' (duration: 53.280704ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:28:51.437696Z","caller":"traceutil/trace.go:171","msg":"trace[1274591165] transaction","detail":"{read_only:false; response_revision:18762; number_of_response:1; }","duration":"161.437058ms","start":"2026-03-18T12:28:51.276217Z","end":"2026-03-18T12:28:51.437654Z","steps":["trace[1274591165] 'process raft request' (duration: 161.270634ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:28:52.011131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.046512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-03-18T12:28:52.011206Z","caller":"traceutil/trace.go:171","msg":"trace[833474131] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18763; }","duration":"171.157264ms","start":"2026-03-18T12:28:51.840028Z","end":"2026-03-18T12:28:52.011185Z","steps":["trace[833474131] 'range keys from in-memory index tree' (duration: 169.971458ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:28:52.234024Z","caller":"traceutil/trace.go:171","msg":"trace[1061301245] transaction","detail":"{read_only:false; response_revision:18764; number_of_response:1; }","duration":"218.688816ms","start":"2026-03-18T12:28:52.015306Z","end":"2026-03-18T12:28:52.233995Z","steps":["trace[1061301245] 'process raft request' (duration: 218.506773ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:28:52.234061Z","caller":"traceutil/trace.go:171","msg":"trace[1957134086] linearizableReadLoop","detail":"{readStateIndex:19431; appliedIndex:19431; }","duration":"218.304229ms","start":"2026-03-18T12:28:52.015704Z","end":"2026-03-18T12:28:52.234008Z","steps":["trace[1957134086] 'read index received' (duration: 218.280968ms)","trace[1957134086] 'applied index is now lower than readState.Index' (duration: 20.421µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:28:52.234286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.557114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-03-18T12:28:52.234362Z","caller":"traceutil/trace.go:171","msg":"trace[538036362] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:18764; }","duration":"218.676247ms","start":"2026-03-18T12:28:52.015667Z","end":"2026-03-18T12:28:52.234343Z","steps":["trace[538036362] 'agreement among raft nodes before linearized reading' (duration: 218.457142ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:28:52.23461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.793196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:28:52.23466Z","caller":"traceutil/trace.go:171","msg":"trace[1820449385] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:18764; }","duration":"185.863697ms","start":"2026-03-18T12:28:52.048784Z","end":"2026-03-18T12:28:52.234647Z","steps":["trace[1820449385] 'agreement among raft nodes before linearized reading' (duration: 185.698594ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:28:52.235016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.047807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-18T12:28:52.235208Z","caller":"traceutil/trace.go:171","msg":"trace[1198739092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:18764; }","duration":"105.1816ms","start":"2026-03-18T12:28:52.1299Z","end":"2026-03-18T12:28:52.235082Z","steps":["trace[1198739092] 'agreement among raft nodes before linearized reading' (duration: 104.793942ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:28:52.524001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.313807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:28:52.524169Z","caller":"traceutil/trace.go:171","msg":"trace[1785281254] linearizableReadLoop","detail":"{readStateIndex:19433; appliedIndex:19431; }","duration":"224.837437ms","start":"2026-03-18T12:28:52.29931Z","end":"2026-03-18T12:28:52.524148Z","steps":["trace[1785281254] 'read index received' (duration: 46.309465ms)","trace[1785281254] 'applied index is now lower than readState.Index' (duration: 178.527172ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:28:52.524432Z","caller":"traceutil/trace.go:171","msg":"trace[1517048578] transaction","detail":"{read_only:false; response_revision:18765; number_of_response:1; }","duration":"283.406295ms","start":"2026-03-18T12:28:52.241004Z","end":"2026-03-18T12:28:52.52441Z","steps":["trace[1517048578] 'process raft request' (duration: 104.602097ms)","trace[1517048578] 'compare' (duration: 178.101993ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:28:52.52487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.786923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-03-18T12:28:52.524847Z","caller":"traceutil/trace.go:171","msg":"trace[1776281234] transaction","detail":"{read_only:false; response_revision:18766; number_of_response:1; }","duration":"283.603209ms","start":"2026-03-18T12:28:52.241194Z","end":"2026-03-18T12:28:52.524797Z","steps":["trace[1776281234] 'process raft request' (duration: 282.906334ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:28:52.524928Z","caller":"traceutil/trace.go:171","msg":"trace[769408388] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:18766; }","duration":"221.840254ms","start":"2026-03-18T12:28:52.303069Z","end":"2026-03-18T12:28:52.524909Z","steps":["trace[769408388] 'agreement among raft nodes before linearized reading' (duration: 221.669151ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:28:52.52505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.738586ms","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-03-18T12:28:52.525081Z","caller":"traceutil/trace.go:171","msg":"trace[839226922] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:18766; }","duration":"225.799528ms","start":"2026-03-18T12:28:52.299273Z","end":"2026-03-18T12:28:52.525073Z","steps":["trace[839226922] 'agreement among raft nodes before linearized reading' (duration: 225.752847ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:29:07.810887Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16968} {"level":"info","ts":"2026-03-18T12:29:07.922311Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16968,"took":"108.144142ms","hash":2486748,"current-db-size-bytes":43810816,"current-db-size":"44 MB","current-db-size-in-use-bytes":25718784,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-18T12:29:07.922381Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2486748,"revision":16968,"compact-revision":14542} {"level":"info","ts":"2026-03-18T12:29:51.501447Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-18T12:29:51.506915Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-03-18T12:29:51.507086Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-03-18T12:34:07.815608Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18837} {"level":"info","ts":"2026-03-18T12:34:07.920249Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18837,"took":"100.559551ms","hash":3062524796,"current-db-size-bytes":46120960,"current-db-size":"46 MB","current-db-size-in-use-bytes":37916672,"current-db-size-in-use":"38 MB"} {"level":"info","ts":"2026-03-18T12:34:07.920322Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3062524796,"revision":18837,"compact-revision":16968} {"level":"warn","ts":"2026-03-18T12:36:05.335672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.468574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:36:05.335783Z","caller":"traceutil/trace.go:171","msg":"trace[334097820] linearizableReadLoop","detail":"{readStateIndex:23822; appliedIndex:23821; }","duration":"414.528627ms","start":"2026-03-18T12:36:04.921237Z","end":"2026-03-18T12:36:05.335765Z","steps":["trace[334097820] 'read index received' (duration: 272.200246ms)","trace[334097820] 'applied index is now lower than readState.Index' (duration: 142.326841ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:36:05.335911Z","caller":"traceutil/trace.go:171","msg":"trace[678149610] transaction","detail":"{read_only:false; response_revision:23029; number_of_response:1; }","duration":"431.462237ms","start":"2026-03-18T12:36:04.90442Z","end":"2026-03-18T12:36:05.335882Z","steps":["trace[678149610] 'process raft request' (duration: 288.693357ms)","trace[678149610] 'compare' (duration: 142.26829ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:36:05.336049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:04.904391Z","time spent":"431.58636ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:05.336104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.506787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-5b9cc65d95-65kdm\" ","response":"range_response_count:1 size:8652"} {"level":"info","ts":"2026-03-18T12:36:05.336204Z","caller":"traceutil/trace.go:171","msg":"trace[1147317659] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-5b9cc65d95-65kdm; range_end:; response_count:1; response_revision:23029; }","duration":"454.63232ms","start":"2026-03-18T12:36:04.881531Z","end":"2026-03-18T12:36:05.336163Z","steps":["trace[1147317659] 'agreement among raft nodes before linearized reading' (duration: 454.407195ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:05.336245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:04.881508Z","time spent":"454.727662ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":8673,"request content":"key:\"/registry/pods/openstack/magnum-api-5b9cc65d95-65kdm\" "} {"level":"info","ts":"2026-03-18T12:36:05.447986Z","caller":"traceutil/trace.go:171","msg":"trace[1670089653] transaction","detail":"{read_only:false; response_revision:23031; number_of_response:1; }","duration":"484.110738ms","start":"2026-03-18T12:36:04.963832Z","end":"2026-03-18T12:36:05.447942Z","steps":["trace[1670089653] 'process raft request' (duration: 484.003446ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:05.44801Z","caller":"traceutil/trace.go:171","msg":"trace[439529322] transaction","detail":"{read_only:false; response_revision:23030; number_of_response:1; }","duration":"521.885853ms","start":"2026-03-18T12:36:04.926082Z","end":"2026-03-18T12:36:05.447968Z","steps":["trace[439529322] 'process raft request' (duration: 521.546585ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:05.448155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:04.963806Z","time spent":"484.255352ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:05.448207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:04.926063Z","time spent":"522.037335ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:05.448224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.640145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:05.448284Z","caller":"traceutil/trace.go:171","msg":"trace[526676828] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23031; }","duration":"341.791538ms","start":"2026-03-18T12:36:05.106473Z","end":"2026-03-18T12:36:05.448265Z","steps":["trace[526676828] 'agreement among raft nodes before linearized reading' (duration: 341.703596ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:05.448326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:05.106449Z","time spent":"341.868179ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T12:36:05.448303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.444652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:05.448406Z","caller":"traceutil/trace.go:171","msg":"trace[1896168113] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:23031; }","duration":"319.620235ms","start":"2026-03-18T12:36:05.128769Z","end":"2026-03-18T12:36:05.448389Z","steps":["trace[1896168113] 'agreement among raft nodes before linearized reading' (duration: 319.402451ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:05.448449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.598917ms","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-03-18T12:36:05.448455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:05.128704Z","time spent":"319.735708ms","remote":"127.0.0.1:51250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-03-18T12:36:05.448441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.23252ms","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-03-18T12:36:05.448499Z","caller":"traceutil/trace.go:171","msg":"trace[90820159] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23031; }","duration":"127.681038ms","start":"2026-03-18T12:36:05.320802Z","end":"2026-03-18T12:36:05.448483Z","steps":["trace[90820159] 'agreement among raft nodes before linearized reading' (duration: 127.550166ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:05.44853Z","caller":"traceutil/trace.go:171","msg":"trace[20553242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23031; }","duration":"438.394424ms","start":"2026-03-18T12:36:05.010115Z","end":"2026-03-18T12:36:05.44851Z","steps":["trace[20553242] 'agreement among raft nodes before linearized reading' (duration: 438.096378ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:05.44858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:05.010095Z","time spent":"438.475976ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-18T12:36:05.44867Z","caller":"traceutil/trace.go:171","msg":"trace[1545136916] linearizableReadLoop","detail":"{readStateIndex:23824; appliedIndex:23822; }","duration":"112.114917ms","start":"2026-03-18T12:36:05.335807Z","end":"2026-03-18T12:36:05.447922Z","steps":["trace[1545136916] 'read index received' (duration: 111.875192ms)","trace[1545136916] 'applied index is now lower than readState.Index' (duration: 237.675µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:36:05.448871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.32962ms","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-03-18T12:36:05.448917Z","caller":"traceutil/trace.go:171","msg":"trace[163527769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23031; }","duration":"513.396761ms","start":"2026-03-18T12:36:04.93551Z","end":"2026-03-18T12:36:05.448906Z","steps":["trace[163527769] 'agreement among raft nodes before linearized reading' (duration: 513.266829ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:05.448946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:04.935494Z","time spent":"513.445983ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-18T12:36:05.518734Z","caller":"traceutil/trace.go:171","msg":"trace[1455909696] transaction","detail":"{read_only:false; response_revision:23032; number_of_response:1; }","duration":"181.325932ms","start":"2026-03-18T12:36:05.337383Z","end":"2026-03-18T12:36:05.518709Z","steps":["trace[1455909696] 'process raft request' (duration: 180.873832ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:05.518802Z","caller":"traceutil/trace.go:171","msg":"trace[883550195] transaction","detail":"{read_only:false; response_revision:23033; number_of_response:1; }","duration":"170.567002ms","start":"2026-03-18T12:36:05.348192Z","end":"2026-03-18T12:36:05.518759Z","steps":["trace[883550195] 'process raft request' (duration: 170.210344ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:05.519007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.072149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-03-18T12:36:05.519091Z","caller":"traceutil/trace.go:171","msg":"trace[878000337] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:23034; }","duration":"145.177961ms","start":"2026-03-18T12:36:05.373892Z","end":"2026-03-18T12:36:05.51907Z","steps":["trace[878000337] 'agreement among raft nodes before linearized reading' (duration: 144.917696ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:05.731526Z","caller":"traceutil/trace.go:171","msg":"trace[505030681] transaction","detail":"{read_only:false; response_revision:23036; number_of_response:1; }","duration":"278.418078ms","start":"2026-03-18T12:36:05.453089Z","end":"2026-03-18T12:36:05.731507Z","steps":["trace[505030681] 'process raft request' (duration: 278.357897ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:05.731558Z","caller":"traceutil/trace.go:171","msg":"trace[322156322] linearizableReadLoop","detail":"{readStateIndex:23830; appliedIndex:23827; }","duration":"212.784351ms","start":"2026-03-18T12:36:05.518757Z","end":"2026-03-18T12:36:05.731542Z","steps":["trace[322156322] 'read index received' (duration: 176.690052ms)","trace[322156322] 'applied index is now lower than readState.Index' (duration: 36.093179ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:36:05.731558Z","caller":"traceutil/trace.go:171","msg":"trace[208197068] transaction","detail":"{read_only:false; response_revision:23035; number_of_response:1; }","duration":"278.587981ms","start":"2026-03-18T12:36:05.452936Z","end":"2026-03-18T12:36:05.731524Z","steps":["trace[208197068] 'process raft request' (duration: 242.506183ms)","trace[208197068] 'compare' (duration: 35.626669ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:36:05.731678Z","caller":"traceutil/trace.go:171","msg":"trace[1082968269] transaction","detail":"{read_only:false; response_revision:23037; number_of_response:1; }","duration":"278.585371ms","start":"2026-03-18T12:36:05.453082Z","end":"2026-03-18T12:36:05.731667Z","steps":["trace[1082968269] 'process raft request' (duration: 278.400467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:05.731977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.74257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:05.732002Z","caller":"traceutil/trace.go:171","msg":"trace[1344441102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23037; }","duration":"282.798962ms","start":"2026-03-18T12:36:05.449197Z","end":"2026-03-18T12:36:05.731996Z","steps":["trace[1344441102] 'agreement among raft nodes before linearized reading' (duration: 282.740491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:05.975284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.175645ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:36:05.975588Z","caller":"traceutil/trace.go:171","msg":"trace[2056300152] transaction","detail":"{read_only:false; response_revision:23038; number_of_response:1; }","duration":"451.023014ms","start":"2026-03-18T12:36:05.524532Z","end":"2026-03-18T12:36:05.975555Z","steps":["trace[2056300152] 'process raft request' (duration: 341.336598ms)","trace[2056300152] 'compare' (duration: 109.038832ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:36:05.97566Z","caller":"traceutil/trace.go:171","msg":"trace[126069807] transaction","detail":"{read_only:false; response_revision:23039; number_of_response:1; }","duration":"450.970633ms","start":"2026-03-18T12:36:05.524668Z","end":"2026-03-18T12:36:05.975639Z","steps":["trace[126069807] 'process raft request' (duration: 450.8116ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:05.975739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:05.52452Z","time spent":"451.130737ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:05.975762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:05.524662Z","time spent":"451.057215ms","remote":"127.0.0.1:46812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1168,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:05.976088Z","caller":"traceutil/trace.go:171","msg":"trace[175660373] transaction","detail":"{read_only:false; response_revision:23040; number_of_response:1; }","duration":"450.175735ms","start":"2026-03-18T12:36:05.525881Z","end":"2026-03-18T12:36:05.976057Z","steps":["trace[175660373] 'process raft request' (duration: 449.700545ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:05.97618Z","caller":"traceutil/trace.go:171","msg":"trace[1693454958] transaction","detail":"{read_only:false; response_revision:23041; number_of_response:1; }","duration":"449.038801ms","start":"2026-03-18T12:36:05.527129Z","end":"2026-03-18T12:36:05.976168Z","steps":["trace[1693454958] 'process raft request' (duration: 448.810207ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:05.976264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:05.52712Z","time spent":"449.107334ms","remote":"127.0.0.1:46736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":653,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:05.976281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:05.52587Z","time spent":"450.332908ms","remote":"127.0.0.1:46856","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:05.976408Z","caller":"traceutil/trace.go:171","msg":"trace[1663851102] transaction","detail":"{read_only:false; response_revision:23042; number_of_response:1; }","duration":"446.999758ms","start":"2026-03-18T12:36:05.529395Z","end":"2026-03-18T12:36:05.976394Z","steps":["trace[1663851102] 'process raft request' (duration: 446.704591ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:05.9766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:05.529386Z","time spent":"447.164082ms","remote":"127.0.0.1:47058","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7536,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:05.978614Z","caller":"traceutil/trace.go:171","msg":"trace[824336303] transaction","detail":"{read_only:false; response_revision:23043; number_of_response:1; }","duration":"393.716263ms","start":"2026-03-18T12:36:05.584769Z","end":"2026-03-18T12:36:05.978485Z","steps":["trace[824336303] 'process raft request' (duration: 391.575728ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:05.978985Z","caller":"traceutil/trace.go:171","msg":"trace[200319034] linearizableReadLoop","detail":"{readStateIndex:23836; appliedIndex:23830; }","duration":"247.367847ms","start":"2026-03-18T12:36:05.731592Z","end":"2026-03-18T12:36:05.97896Z","steps":["trace[200319034] 'read index received' (duration: 134.157337ms)","trace[200319034] 'applied index is now lower than readState.Index' (duration: 113.20848ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:36:05.97921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.416037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinehealthchecks/\" range_end:\"/registry/cluster.x-k8s.io/machinehealthchecks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:05.97926Z","caller":"traceutil/trace.go:171","msg":"trace[622508238] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinehealthchecks/; range_end:/registry/cluster.x-k8s.io/machinehealthchecks0; response_count:0; response_revision:23044; }","duration":"169.5045ms","start":"2026-03-18T12:36:05.809741Z","end":"2026-03-18T12:36:05.979246Z","steps":["trace[622508238] 'agreement among raft nodes before linearized reading' (duration: 169.393187ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:05.978831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:05.58474Z","time spent":"393.965628ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:05.979592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.36131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-2f7b3a52-e415-4159-9f60-f10f88a606b6\" ","response":"range_response_count:1 size:1276"} {"level":"info","ts":"2026-03-18T12:36:05.979634Z","caller":"traceutil/trace.go:171","msg":"trace[2010044657] range","detail":"{range_begin:/registry/persistentvolumes/pvc-2f7b3a52-e415-4159-9f60-f10f88a606b6; range_end:; response_count:1; response_revision:23044; }","duration":"457.433042ms","start":"2026-03-18T12:36:05.522187Z","end":"2026-03-18T12:36:05.97962Z","steps":["trace[2010044657] 'agreement among raft nodes before linearized reading' (duration: 456.917781ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:05.979675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:05.522169Z","time spent":"457.497573ms","remote":"127.0.0.1:46722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1297,"request content":"key:\"/registry/persistentvolumes/pvc-2f7b3a52-e415-4159-9f60-f10f88a606b6\" "} {"level":"info","ts":"2026-03-18T12:36:06.276836Z","caller":"traceutil/trace.go:171","msg":"trace[1078617910] transaction","detail":"{read_only:false; response_revision:23045; number_of_response:1; }","duration":"290.508416ms","start":"2026-03-18T12:36:05.986293Z","end":"2026-03-18T12:36:06.276801Z","steps":["trace[1078617910] 'process raft request' (duration: 284.376086ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:06.340871Z","caller":"traceutil/trace.go:171","msg":"trace[1656539124] linearizableReadLoop","detail":"{readStateIndex:23839; appliedIndex:23837; }","duration":"130.504869ms","start":"2026-03-18T12:36:06.210335Z","end":"2026-03-18T12:36:06.340839Z","steps":["trace[1656539124] 'read index received' (duration: 60.233493ms)","trace[1656539124] 'applied index is now lower than readState.Index' (duration: 70.264266ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:36:06.34152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.802675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-03-18T12:36:06.341601Z","caller":"traceutil/trace.go:171","msg":"trace[876940397] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23045; }","duration":"131.279726ms","start":"2026-03-18T12:36:06.210302Z","end":"2026-03-18T12:36:06.341582Z","steps":["trace[876940397] 'agreement among raft nodes before linearized reading' (duration: 130.682503ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:07.490054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.324386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:07.490158Z","caller":"traceutil/trace.go:171","msg":"trace[1551746739] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23046; }","duration":"386.482519ms","start":"2026-03-18T12:36:07.103653Z","end":"2026-03-18T12:36:07.490135Z","steps":["trace[1551746739] 'range keys from in-memory index tree' (duration: 386.194413ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:07.490202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:07.103633Z","time spent":"386.560231ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T12:36:07.490501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.935036ms","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-03-18T12:36:07.490608Z","caller":"traceutil/trace.go:171","msg":"trace[240488108] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:23046; }","duration":"385.072778ms","start":"2026-03-18T12:36:07.105513Z","end":"2026-03-18T12:36:07.490586Z","steps":["trace[240488108] 'count revisions from in-memory index tree' (duration: 384.861715ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:07.490844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:07.105504Z","time spent":"385.138651ms","remote":"127.0.0.1:47090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-03-18T12:36:07.795344Z","caller":"traceutil/trace.go:171","msg":"trace[798217859] linearizableReadLoop","detail":"{readStateIndex:23841; appliedIndex:23840; }","duration":"452.507805ms","start":"2026-03-18T12:36:07.342806Z","end":"2026-03-18T12:36:07.795314Z","steps":["trace[798217859] 'read index received' (duration: 452.391633ms)","trace[798217859] 'applied index is now lower than readState.Index' (duration: 114.942µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:36:07.795395Z","caller":"traceutil/trace.go:171","msg":"trace[571326080] transaction","detail":"{read_only:false; response_revision:23047; number_of_response:1; }","duration":"452.648849ms","start":"2026-03-18T12:36:07.342698Z","end":"2026-03-18T12:36:07.795347Z","steps":["trace[571326080] 'process raft request' (duration: 452.431325ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:07.795509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"452.672159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:07.795557Z","caller":"traceutil/trace.go:171","msg":"trace[1152029181] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:23047; }","duration":"452.781581ms","start":"2026-03-18T12:36:07.342762Z","end":"2026-03-18T12:36:07.795543Z","steps":["trace[1152029181] 'agreement among raft nodes before linearized reading' (duration: 452.681539ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:07.795575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:07.342673Z","time spent":"452.810382ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:07.79559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:07.342741Z","time spent":"452.841573ms","remote":"127.0.0.1:56972","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"info","ts":"2026-03-18T12:36:07.814259Z","caller":"traceutil/trace.go:171","msg":"trace[47145616] transaction","detail":"{read_only:false; response_revision:23048; number_of_response:1; }","duration":"430.355514ms","start":"2026-03-18T12:36:07.38388Z","end":"2026-03-18T12:36:07.814236Z","steps":["trace[47145616] 'process raft request' (duration: 430.16469ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:07.814431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:07.383786Z","time spent":"430.539757ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:07.814575Z","caller":"traceutil/trace.go:171","msg":"trace[548213131] transaction","detail":"{read_only:false; response_revision:23049; number_of_response:1; }","duration":"361.044798ms","start":"2026-03-18T12:36:07.453509Z","end":"2026-03-18T12:36:07.814553Z","steps":["trace[548213131] 'process raft request' (duration: 360.687671ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:07.814674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:07.453491Z","time spent":"361.1373ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:07.814708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.511513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-03-18T12:36:07.814755Z","caller":"traceutil/trace.go:171","msg":"trace[855314476] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:23051; }","duration":"388.591455ms","start":"2026-03-18T12:36:07.426148Z","end":"2026-03-18T12:36:07.814739Z","steps":["trace[855314476] 'agreement among raft nodes before linearized reading' (duration: 388.417151ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:07.814784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:07.426133Z","time spent":"388.646036ms","remote":"127.0.0.1:46730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-03-18T12:36:07.814863Z","caller":"traceutil/trace.go:171","msg":"trace[1356757924] transaction","detail":"{read_only:false; response_revision:23050; number_of_response:1; }","duration":"290.733271ms","start":"2026-03-18T12:36:07.524098Z","end":"2026-03-18T12:36:07.814831Z","steps":["trace[1356757924] 'process raft request' (duration: 290.323352ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:07.814949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.045375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:07.814975Z","caller":"traceutil/trace.go:171","msg":"trace[727608532] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:23051; }","duration":"127.102966ms","start":"2026-03-18T12:36:07.687864Z","end":"2026-03-18T12:36:07.814967Z","steps":["trace[727608532] 'agreement among raft nodes before linearized reading' (duration: 127.052455ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:07.815006Z","caller":"traceutil/trace.go:171","msg":"trace[264928335] transaction","detail":"{read_only:false; response_revision:23051; number_of_response:1; }","duration":"290.013705ms","start":"2026-03-18T12:36:07.524978Z","end":"2026-03-18T12:36:07.814992Z","steps":["trace[264928335] 'process raft request' (duration: 289.526065ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:07.815119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.676571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-03-18T12:36:07.815023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.776945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-18T12:36:07.815139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.759016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-03-18T12:36:07.815186Z","caller":"traceutil/trace.go:171","msg":"trace[1293601374] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:23051; }","duration":"271.802767ms","start":"2026-03-18T12:36:07.543374Z","end":"2026-03-18T12:36:07.815177Z","steps":["trace[1293601374] 'agreement among raft nodes before linearized reading' (duration: 271.676035ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:07.815147Z","caller":"traceutil/trace.go:171","msg":"trace[1177883380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23051; }","duration":"361.753112ms","start":"2026-03-18T12:36:07.453386Z","end":"2026-03-18T12:36:07.815139Z","steps":["trace[1177883380] 'agreement among raft nodes before linearized reading' (duration: 361.686681ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:07.815185Z","caller":"traceutil/trace.go:171","msg":"trace[1094724264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23051; }","duration":"323.945778ms","start":"2026-03-18T12:36:07.491212Z","end":"2026-03-18T12:36:07.815158Z","steps":["trace[1094724264] 'agreement among raft nodes before linearized reading' (duration: 323.776804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:07.815341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:07.491195Z","time spent":"324.140033ms","remote":"127.0.0.1:46604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T12:36:07.81534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:07.453362Z","time spent":"361.964817ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T12:36:08.17219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.792573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:36:08.172463Z","caller":"traceutil/trace.go:171","msg":"trace[1130776812] linearizableReadLoop","detail":"{readStateIndex:23850; appliedIndex:23846; }","duration":"221.469195ms","start":"2026-03-18T12:36:07.950977Z","end":"2026-03-18T12:36:08.172446Z","steps":["trace[1130776812] 'read index received' (duration: 99.354656ms)","trace[1130776812] 'applied index is now lower than readState.Index' (duration: 122.113399ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:36:08.172515Z","caller":"traceutil/trace.go:171","msg":"trace[200972343] transaction","detail":"{read_only:false; response_revision:23054; number_of_response:1; }","duration":"349.347708ms","start":"2026-03-18T12:36:07.823158Z","end":"2026-03-18T12:36:08.172506Z","steps":["trace[200972343] 'process raft request' (duration: 349.130613ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:08.172544Z","caller":"traceutil/trace.go:171","msg":"trace[2086081606] transaction","detail":"{read_only:false; response_revision:23055; number_of_response:1; }","duration":"349.376739ms","start":"2026-03-18T12:36:07.823157Z","end":"2026-03-18T12:36:08.172533Z","steps":["trace[2086081606] 'process raft request' (duration: 349.213745ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:08.172611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:07.823143Z","time spent":"349.434891ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":543,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:08.172635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:07.823148Z","time spent":"349.40009ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:08.172442Z","caller":"traceutil/trace.go:171","msg":"trace[1636389552] transaction","detail":"{read_only:false; response_revision:23053; number_of_response:1; }","duration":"349.333087ms","start":"2026-03-18T12:36:07.823079Z","end":"2026-03-18T12:36:08.172412Z","steps":["trace[1636389552] 'process raft request' (duration: 227.236268ms)","trace[1636389552] 'compare' (duration: 121.288702ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:36:08.17285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:07.822946Z","time spent":"349.87733ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:08.173125Z","caller":"traceutil/trace.go:171","msg":"trace[363504703] transaction","detail":"{read_only:false; response_revision:23056; number_of_response:1; }","duration":"349.950791ms","start":"2026-03-18T12:36:07.823163Z","end":"2026-03-18T12:36:08.173113Z","steps":["trace[363504703] 'process raft request' (duration: 349.248855ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:08.173195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.224133ms","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-03-18T12:36:08.174741Z","caller":"traceutil/trace.go:171","msg":"trace[1129096464] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectrealms/; range_end:/registry/ceph.rook.io/cephobjectrealms0; response_count:0; response_revision:23056; }","duration":"223.813276ms","start":"2026-03-18T12:36:07.950915Z","end":"2026-03-18T12:36:08.174728Z","steps":["trace[1129096464] 'agreement among raft nodes before linearized reading' (duration: 222.241793ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:08.174862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:07.823141Z","time spent":"350.84401ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:08.531413Z","caller":"traceutil/trace.go:171","msg":"trace[780793911] linearizableReadLoop","detail":"{readStateIndex:23852; appliedIndex:23850; }","duration":"358.883582ms","start":"2026-03-18T12:36:08.172502Z","end":"2026-03-18T12:36:08.531386Z","steps":["trace[780793911] 'read index received' (duration: 334.118144ms)","trace[780793911] 'applied index is now lower than readState.Index' (duration: 24.763938ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:36:08.531557Z","caller":"traceutil/trace.go:171","msg":"trace[439660247] transaction","detail":"{read_only:false; response_revision:23058; number_of_response:1; }","duration":"542.200146ms","start":"2026-03-18T12:36:07.989327Z","end":"2026-03-18T12:36:08.531527Z","steps":["trace[439660247] 'process raft request' (duration: 541.986372ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:08.531579Z","caller":"traceutil/trace.go:171","msg":"trace[1261901229] transaction","detail":"{read_only:false; response_revision:23057; number_of_response:1; }","duration":"546.119938ms","start":"2026-03-18T12:36:07.985427Z","end":"2026-03-18T12:36:08.531547Z","steps":["trace[1261901229] 'process raft request' (duration: 521.31809ms)","trace[1261901229] 'compare' (duration: 24.40762ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:36:08.531626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.615075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:08.531689Z","caller":"traceutil/trace.go:171","msg":"trace[39494286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23058; }","duration":"427.732008ms","start":"2026-03-18T12:36:08.103942Z","end":"2026-03-18T12:36:08.531674Z","steps":["trace[39494286] 'agreement among raft nodes before linearized reading' (duration: 427.617216ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:08.531712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:07.985399Z","time spent":"546.243561ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:08.531752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:08.103921Z","time spent":"427.81847ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T12:36:08.531787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.643082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:08.531822Z","caller":"traceutil/trace.go:171","msg":"trace[926874274] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:23058; }","duration":"254.729584ms","start":"2026-03-18T12:36:08.277081Z","end":"2026-03-18T12:36:08.531811Z","steps":["trace[926874274] 'agreement among raft nodes before linearized reading' (duration: 254.660963ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:08.531828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.756249ms","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-03-18T12:36:08.531885Z","caller":"traceutil/trace.go:171","msg":"trace[646985291] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23058; }","duration":"551.858561ms","start":"2026-03-18T12:36:07.980011Z","end":"2026-03-18T12:36:08.53187Z","steps":["trace[646985291] 'agreement among raft nodes before linearized reading' (duration: 551.707358ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:08.531923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:07.979993Z","time spent":"551.924312ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-18T12:36:08.531697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:07.989313Z","time spent":"542.304748ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:08.532073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.82406ms","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:6"} {"level":"info","ts":"2026-03-18T12:36:08.532105Z","caller":"traceutil/trace.go:171","msg":"trace[1759914472] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:23058; }","duration":"489.895092ms","start":"2026-03-18T12:36:08.042203Z","end":"2026-03-18T12:36:08.532098Z","steps":["trace[1759914472] 'agreement among raft nodes before linearized reading' (duration: 489.840401ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:08.532127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:08.042179Z","time spent":"489.941723ms","remote":"127.0.0.1:56958","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-03-18T12:36:08.971338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.326175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusters/\" range_end:\"/registry/cluster.x-k8s.io/clusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:08.971419Z","caller":"traceutil/trace.go:171","msg":"trace[826328332] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusters/; range_end:/registry/cluster.x-k8s.io/clusters0; response_count:0; response_revision:23059; }","duration":"230.457297ms","start":"2026-03-18T12:36:08.740942Z","end":"2026-03-18T12:36:08.9714Z","steps":["trace[826328332] 'count revisions from in-memory index tree' (duration: 230.257114ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:09.372433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.936453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:36:09.372564Z","caller":"traceutil/trace.go:171","msg":"trace[240312854] transaction","detail":"{read_only:false; response_revision:23061; number_of_response:1; }","duration":"237.878326ms","start":"2026-03-18T12:36:09.134659Z","end":"2026-03-18T12:36:09.372538Z","steps":["trace[240312854] 'process raft request' (duration: 118.717748ms)","trace[240312854] 'compare' (duration: 118.744799ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:36:10.013643Z","caller":"traceutil/trace.go:171","msg":"trace[1911653412] transaction","detail":"{read_only:false; response_revision:23064; number_of_response:1; }","duration":"191.244673ms","start":"2026-03-18T12:36:09.822377Z","end":"2026-03-18T12:36:10.013622Z","steps":["trace[1911653412] 'process raft request' (duration: 191.166151ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:10.013681Z","caller":"traceutil/trace.go:171","msg":"trace[142326243] transaction","detail":"{read_only:false; response_revision:23063; number_of_response:1; }","duration":"191.875066ms","start":"2026-03-18T12:36:09.82177Z","end":"2026-03-18T12:36:10.013645Z","steps":["trace[142326243] 'process raft request' (duration: 191.087979ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:10.013896Z","caller":"traceutil/trace.go:171","msg":"trace[1513455554] transaction","detail":"{read_only:false; response_revision:23065; number_of_response:1; }","duration":"189.23253ms","start":"2026-03-18T12:36:09.824638Z","end":"2026-03-18T12:36:10.013871Z","steps":["trace[1513455554] 'process raft request' (duration: 188.951094ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:10.013837Z","caller":"traceutil/trace.go:171","msg":"trace[1120840591] linearizableReadLoop","detail":"{readStateIndex:23859; appliedIndex:23855; }","duration":"178.529471ms","start":"2026-03-18T12:36:09.835263Z","end":"2026-03-18T12:36:10.013793Z","steps":["trace[1120840591] 'read index received' (duration: 1.708476ms)","trace[1120840591] 'applied index is now lower than readState.Index' (duration: 176.817535ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:36:10.014067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.786207ms","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-03-18T12:36:10.014108Z","caller":"traceutil/trace.go:171","msg":"trace[652521146] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23065; }","duration":"178.9037ms","start":"2026-03-18T12:36:09.835192Z","end":"2026-03-18T12:36:10.014096Z","steps":["trace[652521146] 'agreement among raft nodes before linearized reading' (duration: 178.744316ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:21.127969Z","caller":"traceutil/trace.go:171","msg":"trace[887393798] linearizableReadLoop","detail":"{readStateIndex:23937; appliedIndex:23936; }","duration":"458.655257ms","start":"2026-03-18T12:36:20.669297Z","end":"2026-03-18T12:36:21.127952Z","steps":["trace[887393798] 'read index received' (duration: 458.497773ms)","trace[887393798] 'applied index is now lower than readState.Index' (duration: 156.764µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:36:21.128144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.82791ms","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-03-18T12:36:21.128171Z","caller":"traceutil/trace.go:171","msg":"trace[625292823] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23141; }","duration":"458.899712ms","start":"2026-03-18T12:36:20.669261Z","end":"2026-03-18T12:36:21.128161Z","steps":["trace[625292823] 'agreement among raft nodes before linearized reading' (duration: 458.778389ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:21.128197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:20.669247Z","time spent":"458.945583ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-03-18T12:36:21.128152Z","caller":"traceutil/trace.go:171","msg":"trace[747055784] transaction","detail":"{read_only:false; response_revision:23141; number_of_response:1; }","duration":"553.76771ms","start":"2026-03-18T12:36:20.574319Z","end":"2026-03-18T12:36:21.128087Z","steps":["trace[747055784] 'process raft request' (duration: 553.517945ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:21.128242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.192701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-18T12:36:21.128309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.674474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-03-18T12:36:21.128337Z","caller":"traceutil/trace.go:171","msg":"trace[1786413148] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:23141; }","duration":"307.347494ms","start":"2026-03-18T12:36:20.820965Z","end":"2026-03-18T12:36:21.128313Z","steps":["trace[1786413148] 'agreement among raft nodes before linearized reading' (duration: 307.16905ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:21.128379Z","caller":"traceutil/trace.go:171","msg":"trace[1692877546] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:23141; }","duration":"154.802146ms","start":"2026-03-18T12:36:20.97356Z","end":"2026-03-18T12:36:21.128362Z","steps":["trace[1692877546] 'agreement among raft nodes before linearized reading' (duration: 154.597102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:21.128356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:20.574297Z","time spent":"553.946935ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:21.128393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:20.820946Z","time spent":"307.434556ms","remote":"127.0.0.1:47888","response type":"/etcdserverpb.KV/Range","request count":0,"request size":128,"response count":0,"response size":27,"request content":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true "} {"level":"info","ts":"2026-03-18T12:36:21.744139Z","caller":"traceutil/trace.go:171","msg":"trace[114644889] linearizableReadLoop","detail":"{readStateIndex:23942; appliedIndex:23941; }","duration":"143.558147ms","start":"2026-03-18T12:36:21.60056Z","end":"2026-03-18T12:36:21.744118Z","steps":["trace[114644889] 'read index received' (duration: 143.278981ms)","trace[114644889] 'applied index is now lower than readState.Index' (duration: 277.996µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:36:21.744166Z","caller":"traceutil/trace.go:171","msg":"trace[96924026] transaction","detail":"{read_only:false; response_revision:23145; number_of_response:1; }","duration":"275.966036ms","start":"2026-03-18T12:36:21.468165Z","end":"2026-03-18T12:36:21.744131Z","steps":["trace[96924026] 'process raft request' (duration: 275.793143ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:21.744363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.785711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:21.744399Z","caller":"traceutil/trace.go:171","msg":"trace[443457861] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:23145; }","duration":"143.890654ms","start":"2026-03-18T12:36:21.600498Z","end":"2026-03-18T12:36:21.744389Z","steps":["trace[443457861] 'agreement among raft nodes before linearized reading' (duration: 143.773871ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:22.094571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.1071ms","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-03-18T12:36:22.095548Z","caller":"traceutil/trace.go:171","msg":"trace[387224193] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23145; }","duration":"262.126512ms","start":"2026-03-18T12:36:21.833397Z","end":"2026-03-18T12:36:22.095524Z","steps":["trace[387224193] 'range keys from in-memory index tree' (duration: 261.005348ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:22.551409Z","caller":"traceutil/trace.go:171","msg":"trace[1669616736] transaction","detail":"{read_only:false; response_revision:23146; number_of_response:1; }","duration":"663.052608ms","start":"2026-03-18T12:36:21.88833Z","end":"2026-03-18T12:36:22.551383Z","steps":["trace[1669616736] 'process raft request' (duration: 662.867944ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:22.551567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:21.888312Z","time spent":"663.16868ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:22.581025Z","caller":"traceutil/trace.go:171","msg":"trace[535163492] transaction","detail":"{read_only:false; response_revision:23147; number_of_response:1; }","duration":"530.207889ms","start":"2026-03-18T12:36:22.05079Z","end":"2026-03-18T12:36:22.580998Z","steps":["trace[535163492] 'process raft request' (duration: 529.996105ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:22.581189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.050766Z","time spent":"530.329782ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:22.581347Z","caller":"traceutil/trace.go:171","msg":"trace[1799454418] transaction","detail":"{read_only:false; response_revision:23148; number_of_response:1; }","duration":"502.327716ms","start":"2026-03-18T12:36:22.078997Z","end":"2026-03-18T12:36:22.581325Z","steps":["trace[1799454418] 'process raft request' (duration: 501.931298ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:22.581475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.078983Z","time spent":"502.421998ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:22.58163Z","caller":"traceutil/trace.go:171","msg":"trace[1233771895] linearizableReadLoop","detail":"{readStateIndex:23946; appliedIndex:23942; }","duration":"482.407972ms","start":"2026-03-18T12:36:22.099211Z","end":"2026-03-18T12:36:22.581619Z","steps":["trace[1233771895] 'read index received' (duration: 451.992494ms)","trace[1233771895] 'applied index is now lower than readState.Index' (duration: 30.414338ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:36:22.581762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.546565ms","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-03-18T12:36:22.581808Z","caller":"traceutil/trace.go:171","msg":"trace[1305793815] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23149; }","duration":"482.620067ms","start":"2026-03-18T12:36:22.099176Z","end":"2026-03-18T12:36:22.581796Z","steps":["trace[1305793815] 'agreement among raft nodes before linearized reading' (duration: 482.549345ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:22.581842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.099146Z","time spent":"482.689618ms","remote":"127.0.0.1:46754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-03-18T12:36:22.581852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.159712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:22.581886Z","caller":"traceutil/trace.go:171","msg":"trace[1033259649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23149; }","duration":"478.237704ms","start":"2026-03-18T12:36:22.103639Z","end":"2026-03-18T12:36:22.581876Z","steps":["trace[1033259649] 'agreement among raft nodes before linearized reading' (duration: 478.176082ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:22.581921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.103624Z","time spent":"478.291404ms","remote":"127.0.0.1:46604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T12:36:22.582015Z","caller":"traceutil/trace.go:171","msg":"trace[1733926583] transaction","detail":"{read_only:false; response_revision:23149; number_of_response:1; }","duration":"502.904128ms","start":"2026-03-18T12:36:22.0791Z","end":"2026-03-18T12:36:22.582004Z","steps":["trace[1733926583] 'process raft request' (duration: 502.151632ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:22.582079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.079095Z","time spent":"502.951199ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:22.582196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.156356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-03-18T12:36:22.582098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.286744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:22.58229Z","caller":"traceutil/trace.go:171","msg":"trace[1437356259] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:23149; }","duration":"200.500539ms","start":"2026-03-18T12:36:22.38176Z","end":"2026-03-18T12:36:22.58226Z","steps":["trace[1437356259] 'agreement among raft nodes before linearized reading' (duration: 200.308845ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:22.582364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.256396ms","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-03-18T12:36:22.582391Z","caller":"traceutil/trace.go:171","msg":"trace[1558467677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23149; }","duration":"325.315997ms","start":"2026-03-18T12:36:22.257068Z","end":"2026-03-18T12:36:22.582384Z","steps":["trace[1558467677] 'agreement among raft nodes before linearized reading' (duration: 325.248006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:22.582416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.257042Z","time spent":"325.368878ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T12:36:22.582616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.676069ms","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-03-18T12:36:22.582654Z","caller":"traceutil/trace.go:171","msg":"trace[430298294] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23149; }","duration":"391.750391ms","start":"2026-03-18T12:36:22.190892Z","end":"2026-03-18T12:36:22.582643Z","steps":["trace[430298294] 'agreement among raft nodes before linearized reading' (duration: 391.640678ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:22.582685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.190869Z","time spent":"391.809692ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-03-18T12:36:22.582234Z","caller":"traceutil/trace.go:171","msg":"trace[1777351486] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23149; }","duration":"311.228667ms","start":"2026-03-18T12:36:22.270996Z","end":"2026-03-18T12:36:22.582225Z","steps":["trace[1777351486] 'agreement among raft nodes before linearized reading' (duration: 311.120825ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:22.58308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.270975Z","time spent":"312.097237ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T12:36:22.582298Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.174344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-03-18T12:36:22.583416Z","caller":"traceutil/trace.go:171","msg":"trace[2105754115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23149; }","duration":"326.33029ms","start":"2026-03-18T12:36:22.257072Z","end":"2026-03-18T12:36:22.583402Z","steps":["trace[2105754115] 'agreement among raft nodes before linearized reading' (duration: 325.178404ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:22.58347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.257052Z","time spent":"326.404691ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T12:36:23.113488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.768668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:36:23.113655Z","caller":"traceutil/trace.go:171","msg":"trace[349553843] linearizableReadLoop","detail":"{readStateIndex:23947; appliedIndex:23946; }","duration":"525.626902ms","start":"2026-03-18T12:36:22.588011Z","end":"2026-03-18T12:36:23.113638Z","steps":["trace[349553843] 'read index received' (duration: 345.735082ms)","trace[349553843] 'applied index is now lower than readState.Index' (duration: 179.88981ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:36:23.113694Z","caller":"traceutil/trace.go:171","msg":"trace[619377] transaction","detail":"{read_only:false; response_revision:23150; number_of_response:1; }","duration":"526.074752ms","start":"2026-03-18T12:36:22.587581Z","end":"2026-03-18T12:36:23.113655Z","steps":["trace[619377] 'process raft request' (duration: 346.061419ms)","trace[619377] 'compare' (duration: 179.671786ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:36:23.113837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.816156ms","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-03-18T12:36:23.11392Z","caller":"traceutil/trace.go:171","msg":"trace[932880232] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:23150; }","duration":"525.913739ms","start":"2026-03-18T12:36:22.587994Z","end":"2026-03-18T12:36:23.113908Z","steps":["trace[932880232] 'agreement among raft nodes before linearized reading' (duration: 525.740515ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:23.11399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.587983Z","time spent":"525.995011ms","remote":"127.0.0.1:46754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-03-18T12:36:23.113852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.587555Z","time spent":"526.215625ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:23.580946Z","caller":"traceutil/trace.go:171","msg":"trace[1797010132] transaction","detail":"{read_only:false; response_revision:23151; number_of_response:1; }","duration":"992.76879ms","start":"2026-03-18T12:36:22.588149Z","end":"2026-03-18T12:36:23.580918Z","steps":["trace[1797010132] 'process raft request' (duration: 992.562725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:23.581114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.588138Z","time spent":"992.893523ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:23.581336Z","caller":"traceutil/trace.go:171","msg":"trace[1017701493] transaction","detail":"{read_only:false; response_revision:23152; number_of_response:1; }","duration":"988.893377ms","start":"2026-03-18T12:36:22.592408Z","end":"2026-03-18T12:36:23.581302Z","steps":["trace[1017701493] 'process raft request' (duration: 988.463448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:23.581532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.799182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:23.581567Z","caller":"traceutil/trace.go:171","msg":"trace[821341516] transaction","detail":"{read_only:false; response_revision:23153; number_of_response:1; }","duration":"988.776334ms","start":"2026-03-18T12:36:22.592778Z","end":"2026-03-18T12:36:23.581554Z","steps":["trace[821341516] 'process raft request' (duration: 988.390526ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:23.581588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"993.558016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-03-18T12:36:23.581635Z","caller":"traceutil/trace.go:171","msg":"trace[825050791] transaction","detail":"{read_only:false; response_revision:23154; number_of_response:1; }","duration":"923.578536ms","start":"2026-03-18T12:36:22.658038Z","end":"2026-03-18T12:36:23.581616Z","steps":["trace[825050791] 'process raft request' (duration: 923.27756ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:23.581651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.592767Z","time spent":"988.850395ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:23.581658Z","caller":"traceutil/trace.go:171","msg":"trace[1051374245] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23154; }","duration":"476.926205ms","start":"2026-03-18T12:36:23.104679Z","end":"2026-03-18T12:36:23.581605Z","steps":["trace[1051374245] 'agreement among raft nodes before linearized reading' (duration: 476.735881ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:23.58173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.658014Z","time spent":"923.667618ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:23.581779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"731.930385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-18T12:36:23.581819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.47271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:23.581648Z","caller":"traceutil/trace.go:171","msg":"trace[1612434068] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23154; }","duration":"993.664059ms","start":"2026-03-18T12:36:22.587969Z","end":"2026-03-18T12:36:23.581633Z","steps":["trace[1612434068] 'agreement among raft nodes before linearized reading' (duration: 993.467655ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:23.581925Z","caller":"traceutil/trace.go:171","msg":"trace[1887306155] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:23154; }","duration":"781.619903ms","start":"2026-03-18T12:36:22.800286Z","end":"2026-03-18T12:36:23.581905Z","steps":["trace[1887306155] 'agreement among raft nodes before linearized reading' (duration: 781.44094ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:23.58175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:23.10466Z","time spent":"477.079328ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-03-18T12:36:23.582022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.800267Z","time spent":"781.736175ms","remote":"127.0.0.1:47634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"warn","ts":"2026-03-18T12:36:23.582014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.587956Z","time spent":"994.008636ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-03-18T12:36:23.581846Z","caller":"traceutil/trace.go:171","msg":"trace[462729533] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:23154; }","duration":"732.119109ms","start":"2026-03-18T12:36:22.84971Z","end":"2026-03-18T12:36:23.58183Z","steps":["trace[462729533] 'agreement among raft nodes before linearized reading' (duration: 731.961885ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:23.581642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.592397Z","time spent":"989.06814ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:23.581401Z","caller":"traceutil/trace.go:171","msg":"trace[408536684] linearizableReadLoop","detail":"{readStateIndex:23951; appliedIndex:23947; }","duration":"467.676718ms","start":"2026-03-18T12:36:23.113705Z","end":"2026-03-18T12:36:23.581382Z","steps":["trace[408536684] 'read index received' (duration: 467.006564ms)","trace[408536684] 'applied index is now lower than readState.Index' (duration: 668.864µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:36:23.58221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:22.849686Z","time spent":"732.496617ms","remote":"127.0.0.1:39934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true "} {"level":"info","ts":"2026-03-18T12:36:23.632608Z","caller":"traceutil/trace.go:171","msg":"trace[1248793264] transaction","detail":"{read_only:false; response_revision:23155; number_of_response:1; }","duration":"499.113918ms","start":"2026-03-18T12:36:23.133476Z","end":"2026-03-18T12:36:23.63259Z","steps":["trace[1248793264] 'process raft request' (duration: 498.952804ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:23.632735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:23.133452Z","time spent":"499.212969ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:23.632828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.281292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-18T12:36:23.63274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.781926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-03-18T12:36:23.632867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.528864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:23.632924Z","caller":"traceutil/trace.go:171","msg":"trace[980586401] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:23155; }","duration":"245.433776ms","start":"2026-03-18T12:36:23.387469Z","end":"2026-03-18T12:36:23.632902Z","steps":["trace[980586401] 'agreement among raft nodes before linearized reading' (duration: 245.243672ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:23.632935Z","caller":"traceutil/trace.go:171","msg":"trace[787128290] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23155; }","duration":"473.008421ms","start":"2026-03-18T12:36:23.159905Z","end":"2026-03-18T12:36:23.632914Z","steps":["trace[787128290] 'agreement among raft nodes before linearized reading' (duration: 472.691575ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:23.632985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:23.159892Z","time spent":"473.078682ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-18T12:36:23.633008Z","caller":"traceutil/trace.go:171","msg":"trace[1354484991] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:23155; }","duration":"513.734139ms","start":"2026-03-18T12:36:23.119205Z","end":"2026-03-18T12:36:23.632939Z","steps":["trace[1354484991] 'agreement among raft nodes before linearized reading' (duration: 513.443663ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:23.633135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.984849ms","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-03-18T12:36:23.633138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:23.119182Z","time spent":"513.908522ms","remote":"127.0.0.1:46812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-03-18T12:36:23.633184Z","caller":"traceutil/trace.go:171","msg":"trace[666433697] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23155; }","duration":"479.065821ms","start":"2026-03-18T12:36:23.154105Z","end":"2026-03-18T12:36:23.633171Z","steps":["trace[666433697] 'agreement among raft nodes before linearized reading' (duration: 478.950068ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:23.633224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:23.154087Z","time spent":"479.128832ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-18T12:36:23.633155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.483677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"info","ts":"2026-03-18T12:36:23.633357Z","caller":"traceutil/trace.go:171","msg":"trace[1078817622] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:23155; }","duration":"454.723733ms","start":"2026-03-18T12:36:23.178604Z","end":"2026-03-18T12:36:23.633328Z","steps":["trace[1078817622] 'agreement among raft nodes before linearized reading' (duration: 454.14471ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:23.633459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:23.178587Z","time spent":"454.854395ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-03-18T12:36:24.275486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.117977ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:24.275603Z","caller":"traceutil/trace.go:171","msg":"trace[1214978452] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:23155; }","duration":"575.248399ms","start":"2026-03-18T12:36:23.700337Z","end":"2026-03-18T12:36:24.275585Z","steps":["trace[1214978452] 'range keys from in-memory index tree' (duration: 575.101106ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:24.276073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.398496ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:36:24.276234Z","caller":"traceutil/trace.go:171","msg":"trace[2088895615] transaction","detail":"{read_only:false; response_revision:23156; number_of_response:1; }","duration":"638.843143ms","start":"2026-03-18T12:36:23.637358Z","end":"2026-03-18T12:36:24.276201Z","steps":["trace[2088895615] 'process raft request' (duration: 300.203302ms)","trace[2088895615] 'compare' (duration: 337.860545ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:36:24.276321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:23.637336Z","time spent":"638.947905ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":542,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:24.363841Z","caller":"traceutil/trace.go:171","msg":"trace[1527353130] transaction","detail":"{read_only:false; response_revision:23157; number_of_response:1; }","duration":"725.865756ms","start":"2026-03-18T12:36:23.637946Z","end":"2026-03-18T12:36:24.363811Z","steps":["trace[1527353130] 'process raft request' (duration: 725.59309ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:24.364078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:23.637939Z","time spent":"725.991979ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:24.364762Z","caller":"traceutil/trace.go:171","msg":"trace[11343656] linearizableReadLoop","detail":"{readStateIndex:23955; appliedIndex:23952; }","duration":"724.899966ms","start":"2026-03-18T12:36:23.639829Z","end":"2026-03-18T12:36:24.364729Z","steps":["trace[11343656] 'read index received' (duration: 297.74441ms)","trace[11343656] 'applied index is now lower than readState.Index' (duration: 427.153856ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:36:24.365057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.191372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-18T12:36:24.365108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.022163ms","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-03-18T12:36:24.365159Z","caller":"traceutil/trace.go:171","msg":"trace[867388334] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:23158; }","duration":"725.330914ms","start":"2026-03-18T12:36:23.639803Z","end":"2026-03-18T12:36:24.365134Z","steps":["trace[867388334] 'agreement among raft nodes before linearized reading' (duration: 725.154701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:24.365151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.707618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:24.365187Z","caller":"traceutil/trace.go:171","msg":"trace[344286028] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:23158; }","duration":"721.111345ms","start":"2026-03-18T12:36:23.644053Z","end":"2026-03-18T12:36:24.365165Z","steps":["trace[344286028] 'agreement among raft nodes before linearized reading' (duration: 720.978102ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:24.365184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"525.113142ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:36:24.365174Z","caller":"traceutil/trace.go:171","msg":"trace[1410560812] transaction","detail":"{read_only:false; response_revision:23158; number_of_response:1; }","duration":"726.625773ms","start":"2026-03-18T12:36:23.638485Z","end":"2026-03-18T12:36:24.365111Z","steps":["trace[1410560812] 'process raft request' (duration: 725.264933ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:24.365243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:23.644048Z","time spent":"721.180057ms","remote":"127.0.0.1:47158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5673,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-03-18T12:36:24.365212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:23.639796Z","time spent":"725.404807ms","remote":"127.0.0.1:46812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-03-18T12:36:24.365247Z","caller":"traceutil/trace.go:171","msg":"trace[1444432801] range","detail":"{range_begin:/registry/rabbitmq.com/bindings/; range_end:/registry/rabbitmq.com/bindings0; response_count:0; response_revision:23158; }","duration":"525.214544ms","start":"2026-03-18T12:36:23.840015Z","end":"2026-03-18T12:36:24.365229Z","steps":["trace[1444432801] 'agreement among raft nodes before linearized reading' (duration: 525.114662ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:24.365309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:23.839995Z","time spent":"525.300046ms","remote":"127.0.0.1:56928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/bindings/\" range_end:\"/registry/rabbitmq.com/bindings0\" count_only:true "} {"level":"info","ts":"2026-03-18T12:36:24.365227Z","caller":"traceutil/trace.go:171","msg":"trace[386527293] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:23158; }","duration":"103.834421ms","start":"2026-03-18T12:36:24.26137Z","end":"2026-03-18T12:36:24.365205Z","steps":["trace[386527293] 'agreement among raft nodes before linearized reading' (duration: 103.687398ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:24.365405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:23.638476Z","time spent":"726.811947ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:24.608199Z","caller":"traceutil/trace.go:171","msg":"trace[681643355] transaction","detail":"{read_only:false; response_revision:23159; number_of_response:1; }","duration":"215.09831ms","start":"2026-03-18T12:36:24.393073Z","end":"2026-03-18T12:36:24.608171Z","steps":["trace[681643355] 'process raft request' (duration: 201.072322ms)","trace[681643355] 'compare' (duration: 13.594028ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:36:25.486247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.959102ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:36:25.486671Z","caller":"traceutil/trace.go:171","msg":"trace[48025294] transaction","detail":"{read_only:false; response_revision:23161; number_of_response:1; }","duration":"899.81607ms","start":"2026-03-18T12:36:24.586821Z","end":"2026-03-18T12:36:25.486637Z","steps":["trace[48025294] 'process raft request' (duration: 899.567185ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:25.486699Z","caller":"traceutil/trace.go:171","msg":"trace[2014020802] transaction","detail":"{read_only:false; response_revision:23162; number_of_response:1; }","duration":"899.570375ms","start":"2026-03-18T12:36:24.587102Z","end":"2026-03-18T12:36:25.486673Z","steps":["trace[2014020802] 'process raft request' (duration: 899.419222ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:25.486807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:24.587076Z","time spent":"899.680997ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:25.486837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:24.586806Z","time spent":"899.942713ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:25.48668Z","caller":"traceutil/trace.go:171","msg":"trace[410390287] transaction","detail":"{read_only:false; response_revision:23160; number_of_response:1; }","duration":"929.455881ms","start":"2026-03-18T12:36:24.557181Z","end":"2026-03-18T12:36:25.486637Z","steps":["trace[410390287] 'process raft request' (duration: 694.993179ms)","trace[410390287] 'compare' (duration: 233.502542ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:36:25.486912Z","caller":"traceutil/trace.go:171","msg":"trace[601886684] linearizableReadLoop","detail":"{readStateIndex:23960; appliedIndex:23956; }","duration":"382.149666ms","start":"2026-03-18T12:36:25.104729Z","end":"2026-03-18T12:36:25.486879Z","steps":["trace[601886684] 'read index received' (duration: 147.49084ms)","trace[601886684] 'applied index is now lower than readState.Index' (duration: 234.657226ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:36:25.48695Z","caller":"traceutil/trace.go:171","msg":"trace[1301849822] transaction","detail":"{read_only:false; response_revision:23163; number_of_response:1; }","duration":"898.046532ms","start":"2026-03-18T12:36:24.588865Z","end":"2026-03-18T12:36:25.486912Z","steps":["trace[1301849822] 'process raft request' (duration: 897.753006ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:25.487094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.364372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-18T12:36:25.487148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:24.588848Z","time spent":"898.170234ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:36:25.487157Z","caller":"traceutil/trace.go:171","msg":"trace[1825558522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23163; }","duration":"382.461313ms","start":"2026-03-18T12:36:25.104677Z","end":"2026-03-18T12:36:25.487139Z","steps":["trace[1825558522] 'agreement among raft nodes before linearized reading' (duration: 382.30237ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:25.487238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:24.557157Z","time spent":"929.784379ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:25.487296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:25.104656Z","time spent":"382.628447ms","remote":"127.0.0.1:46608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-18T12:36:25.497306Z","caller":"traceutil/trace.go:171","msg":"trace[544515916] transaction","detail":"{read_only:false; response_revision:23164; number_of_response:1; }","duration":"315.200402ms","start":"2026-03-18T12:36:25.182085Z","end":"2026-03-18T12:36:25.497286Z","steps":["trace[544515916] 'process raft request' (duration: 314.976578ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:25.49734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.315945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-18T12:36:25.497371Z","caller":"traceutil/trace.go:171","msg":"trace[1981221767] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:23164; }","duration":"316.392577ms","start":"2026-03-18T12:36:25.18097Z","end":"2026-03-18T12:36:25.497363Z","steps":["trace[1981221767] 'agreement among raft nodes before linearized reading' (duration: 316.307935ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:25.497365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.261885ms","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-03-18T12:36:25.497435Z","caller":"traceutil/trace.go:171","msg":"trace[387738923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23164; }","duration":"379.367778ms","start":"2026-03-18T12:36:25.118049Z","end":"2026-03-18T12:36:25.497417Z","steps":["trace[387738923] 'agreement among raft nodes before linearized reading' (duration: 379.155624ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:25.497394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:25.180947Z","time spent":"316.441898ms","remote":"127.0.0.1:47010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":52,"response size":29,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-03-18T12:36:25.497476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:25.118031Z","time spent":"379.436479ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T12:36:25.497417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:36:25.18206Z","time spent":"315.306364ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:36:30.538451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.524703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-58304a2a-5402-4d5a-8b18-1d57e9105f34\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-03-18T12:36:30.538538Z","caller":"traceutil/trace.go:171","msg":"trace[1527305586] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-58304a2a-5402-4d5a-8b18-1d57e9105f34; range_end:; response_count:1; response_revision:23255; }","duration":"148.683966ms","start":"2026-03-18T12:36:30.389837Z","end":"2026-03-18T12:36:30.538505Z","steps":["trace[1527305586] 'range keys from in-memory index tree' (duration: 148.341509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:30.538587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.404346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-03-18T12:36:30.538597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.896512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-03-18T12:36:30.538616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.736592ms","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-03-18T12:36:30.538654Z","caller":"traceutil/trace.go:171","msg":"trace[2134206962] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23255; }","duration":"153.51922ms","start":"2026-03-18T12:36:30.385117Z","end":"2026-03-18T12:36:30.538636Z","steps":["trace[2134206962] 'range keys from in-memory index tree' (duration: 153.163851ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:30.538692Z","caller":"traceutil/trace.go:171","msg":"trace[2119459318] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:23255; }","duration":"112.030006ms","start":"2026-03-18T12:36:30.42663Z","end":"2026-03-18T12:36:30.53866Z","steps":["trace[2119459318] 'count revisions from in-memory index tree' (duration: 111.78811ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:30.538708Z","caller":"traceutil/trace.go:171","msg":"trace[1607181766] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23255; }","duration":"152.889646ms","start":"2026-03-18T12:36:30.385794Z","end":"2026-03-18T12:36:30.538684Z","steps":["trace[1607181766] 'range keys from in-memory index tree' (duration: 152.502486ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:30.655062Z","caller":"traceutil/trace.go:171","msg":"trace[1624619694] transaction","detail":"{read_only:false; response_revision:23257; number_of_response:1; }","duration":"112.804841ms","start":"2026-03-18T12:36:30.542229Z","end":"2026-03-18T12:36:30.655034Z","steps":["trace[1624619694] 'process raft request' (duration: 112.608777ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:30.65524Z","caller":"traceutil/trace.go:171","msg":"trace[812684644] transaction","detail":"{read_only:false; response_revision:23258; number_of_response:1; }","duration":"112.644558ms","start":"2026-03-18T12:36:30.542566Z","end":"2026-03-18T12:36:30.65521Z","steps":["trace[812684644] 'process raft request' (duration: 112.424354ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:31.1279Z","caller":"traceutil/trace.go:171","msg":"trace[1218005839] linearizableReadLoop","detail":"{readStateIndex:24058; appliedIndex:24057; }","duration":"154.100211ms","start":"2026-03-18T12:36:30.97377Z","end":"2026-03-18T12:36:31.127871Z","steps":["trace[1218005839] 'read index received' (duration: 153.946738ms)","trace[1218005839] 'applied index is now lower than readState.Index' (duration: 152.393µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:36:31.128085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.293955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-03-18T12:36:31.128116Z","caller":"traceutil/trace.go:171","msg":"trace[366513469] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:23259; }","duration":"154.385257ms","start":"2026-03-18T12:36:30.973722Z","end":"2026-03-18T12:36:31.128108Z","steps":["trace[366513469] 'agreement among raft nodes before linearized reading' (duration: 154.248954ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:31.128271Z","caller":"traceutil/trace.go:171","msg":"trace[1065978338] transaction","detail":"{read_only:false; response_revision:23259; number_of_response:1; }","duration":"184.460718ms","start":"2026-03-18T12:36:30.943767Z","end":"2026-03-18T12:36:31.128228Z","steps":["trace[1065978338] 'process raft request' (duration: 183.851615ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:36:36.805491Z","caller":"traceutil/trace.go:171","msg":"trace[1308509968] transaction","detail":"{read_only:false; response_revision:23316; number_of_response:1; }","duration":"118.151156ms","start":"2026-03-18T12:36:36.6873Z","end":"2026-03-18T12:36:36.805452Z","steps":["trace[1308509968] 'process raft request' (duration: 117.991633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:36:36.971287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.873016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:36:36.971461Z","caller":"traceutil/trace.go:171","msg":"trace[2060610461] transaction","detail":"{read_only:false; response_revision:23317; number_of_response:1; }","duration":"282.156178ms","start":"2026-03-18T12:36:36.689269Z","end":"2026-03-18T12:36:36.971425Z","steps":["trace[2060610461] 'process raft request' (duration: 136.009236ms)","trace[2060610461] 'compare' (duration: 145.56259ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:05.47906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.538408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-03-18T12:37:05.479101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.643822ms","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-03-18T12:37:05.479162Z","caller":"traceutil/trace.go:171","msg":"trace[1553306679] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23540; }","duration":"377.745654ms","start":"2026-03-18T12:37:05.1014Z","end":"2026-03-18T12:37:05.479146Z","steps":["trace[1553306679] 'range keys from in-memory index tree' (duration: 377.457648ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:05.479161Z","caller":"traceutil/trace.go:171","msg":"trace[1252725570] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23540; }","duration":"384.699532ms","start":"2026-03-18T12:37:05.094441Z","end":"2026-03-18T12:37:05.47914Z","steps":["trace[1252725570] 'range keys from in-memory index tree' (duration: 384.314524ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:05.479198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:05.101384Z","time spent":"377.803745ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-03-18T12:37:05.47921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:05.09442Z","time spent":"384.780893ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-03-18T12:37:05.479279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.023798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:37:05.47936Z","caller":"traceutil/trace.go:171","msg":"trace[1463147595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23540; }","duration":"130.380876ms","start":"2026-03-18T12:37:05.348958Z","end":"2026-03-18T12:37:05.479339Z","steps":["trace[1463147595] 'range keys from in-memory index tree' (duration: 129.952237ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:05.631172Z","caller":"traceutil/trace.go:171","msg":"trace[1713505323] linearizableReadLoop","detail":"{readStateIndex:24347; appliedIndex:24346; }","duration":"145.58977ms","start":"2026-03-18T12:37:05.485557Z","end":"2026-03-18T12:37:05.631147Z","steps":["trace[1713505323] 'read index received' (duration: 145.359965ms)","trace[1713505323] 'applied index is now lower than readState.Index' (duration: 228.905µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:37:05.631286Z","caller":"traceutil/trace.go:171","msg":"trace[1556704884] transaction","detail":"{read_only:false; response_revision:23541; number_of_response:1; }","duration":"146.109431ms","start":"2026-03-18T12:37:05.485134Z","end":"2026-03-18T12:37:05.631243Z","steps":["trace[1556704884] 'process raft request' (duration: 145.837405ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:05.631404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.818116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18646"} {"level":"info","ts":"2026-03-18T12:37:05.631467Z","caller":"traceutil/trace.go:171","msg":"trace[1818935000] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:23541; }","duration":"145.911298ms","start":"2026-03-18T12:37:05.485535Z","end":"2026-03-18T12:37:05.631447Z","steps":["trace[1818935000] 'agreement among raft nodes before linearized reading' (duration: 145.720413ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:05.947736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.60183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:37:05.948001Z","caller":"traceutil/trace.go:171","msg":"trace[1826105977] transaction","detail":"{read_only:false; response_revision:23543; number_of_response:1; }","duration":"299.623951ms","start":"2026-03-18T12:37:05.648343Z","end":"2026-03-18T12:37:05.947967Z","steps":["trace[1826105977] 'process raft request' (duration: 145.673312ms)","trace[1826105977] 'compare' (duration: 153.334615ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:37:05.948331Z","caller":"traceutil/trace.go:171","msg":"trace[1139753871] linearizableReadLoop","detail":"{readStateIndex:24351; appliedIndex:24348; }","duration":"263.894491ms","start":"2026-03-18T12:37:05.684414Z","end":"2026-03-18T12:37:05.948308Z","steps":["trace[1139753871] 'read index received' (duration: 109.618575ms)","trace[1139753871] 'applied index is now lower than readState.Index' (duration: 154.274696ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:37:05.948448Z","caller":"traceutil/trace.go:171","msg":"trace[308472971] transaction","detail":"{read_only:false; response_revision:23544; number_of_response:1; }","duration":"299.722872ms","start":"2026-03-18T12:37:05.648709Z","end":"2026-03-18T12:37:05.948432Z","steps":["trace[308472971] 'process raft request' (duration: 299.141049ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:05.948613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.190047ms","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-03-18T12:37:05.948578Z","caller":"traceutil/trace.go:171","msg":"trace[1701308192] transaction","detail":"{read_only:false; response_revision:23545; number_of_response:1; }","duration":"299.005038ms","start":"2026-03-18T12:37:05.649516Z","end":"2026-03-18T12:37:05.948521Z","steps":["trace[1701308192] 'process raft request' (duration: 298.580589ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:05.948666Z","caller":"traceutil/trace.go:171","msg":"trace[76171617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23545; }","duration":"264.268809ms","start":"2026-03-18T12:37:05.684384Z","end":"2026-03-18T12:37:05.948653Z","steps":["trace[76171617] 'agreement among raft nodes before linearized reading' (duration: 264.108586ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:06.12681Z","caller":"traceutil/trace.go:171","msg":"trace[302591790] transaction","detail":"{read_only:false; response_revision:23546; number_of_response:1; }","duration":"394.585663ms","start":"2026-03-18T12:37:05.732174Z","end":"2026-03-18T12:37:06.12676Z","steps":["trace[302591790] 'process raft request' (duration: 362.9624ms)","trace[302591790] 'compare' (duration: 31.428949ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:06.126952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:05.732154Z","time spent":"394.726236ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:37:06.127149Z","caller":"traceutil/trace.go:171","msg":"trace[1772723117] transaction","detail":"{read_only:false; response_revision:23548; number_of_response:1; }","duration":"173.465684ms","start":"2026-03-18T12:37:05.953654Z","end":"2026-03-18T12:37:06.12712Z","steps":["trace[1772723117] 'process raft request' (duration: 173.400352ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:06.127286Z","caller":"traceutil/trace.go:171","msg":"trace[1760395888] transaction","detail":"{read_only:false; response_revision:23547; number_of_response:1; }","duration":"337.751673ms","start":"2026-03-18T12:37:05.789524Z","end":"2026-03-18T12:37:06.127275Z","steps":["trace[1760395888] 'process raft request' (duration: 337.1943ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:06.127316Z","caller":"traceutil/trace.go:171","msg":"trace[1691380314] linearizableReadLoop","detail":"{readStateIndex:24353; appliedIndex:24351; }","duration":"178.916551ms","start":"2026-03-18T12:37:05.948389Z","end":"2026-03-18T12:37:06.127305Z","steps":["trace[1691380314] 'read index received' (duration: 146.714474ms)","trace[1691380314] 'applied index is now lower than readState.Index' (duration: 32.201147ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:06.12734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:05.789489Z","time spent":"337.824365ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:37:06.127397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"395.926902ms","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-03-18T12:37:06.127449Z","caller":"traceutil/trace.go:171","msg":"trace[284300283] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23548; }","duration":"396.037424ms","start":"2026-03-18T12:37:05.731402Z","end":"2026-03-18T12:37:06.127439Z","steps":["trace[284300283] 'agreement among raft nodes before linearized reading' (duration: 395.947172ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:06.127495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:05.731382Z","time spent":"396.105085ms","remote":"127.0.0.1:47010","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-03-18T12:37:06.127479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.969452ms","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-03-18T12:37:06.12757Z","caller":"traceutil/trace.go:171","msg":"trace[406981625] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:23548; }","duration":"318.120495ms","start":"2026-03-18T12:37:05.809431Z","end":"2026-03-18T12:37:06.127552Z","steps":["trace[406981625] 'agreement among raft nodes before linearized reading' (duration: 317.923131ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:06.127614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:05.80942Z","time spent":"318.184376ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-18T12:37:06.1276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.11052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-03-18T12:37:06.127651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.542481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-03-18T12:37:06.127673Z","caller":"traceutil/trace.go:171","msg":"trace[970859256] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23548; }","duration":"330.216222ms","start":"2026-03-18T12:37:05.797441Z","end":"2026-03-18T12:37:06.127657Z","steps":["trace[970859256] 'agreement among raft nodes before linearized reading' (duration: 330.009978ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:06.127683Z","caller":"traceutil/trace.go:171","msg":"trace[1752513448] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23548; }","duration":"331.602762ms","start":"2026-03-18T12:37:05.796073Z","end":"2026-03-18T12:37:06.127675Z","steps":["trace[1752513448] 'agreement among raft nodes before linearized reading' (duration: 331.49484ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:06.12771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:05.796061Z","time spent":"331.644473ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-18T12:37:06.127712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:05.797431Z","time spent":"330.274504ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-18T12:37:06.127725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.940149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:37:06.127798Z","caller":"traceutil/trace.go:171","msg":"trace[1890884328] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:23548; }","duration":"193.045681ms","start":"2026-03-18T12:37:05.934734Z","end":"2026-03-18T12:37:06.127779Z","steps":["trace[1890884328] 'agreement among raft nodes before linearized reading' (duration: 192.908419ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:06.562627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.692929ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:37:06.562922Z","caller":"traceutil/trace.go:171","msg":"trace[451421743] transaction","detail":"{read_only:false; response_revision:23550; number_of_response:1; }","duration":"431.611531ms","start":"2026-03-18T12:37:06.131286Z","end":"2026-03-18T12:37:06.562897Z","steps":["trace[451421743] 'process raft request' (duration: 431.450838ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:06.562966Z","caller":"traceutil/trace.go:171","msg":"trace[592215619] transaction","detail":"{read_only:false; response_revision:23549; number_of_response:1; }","duration":"431.854557ms","start":"2026-03-18T12:37:06.131055Z","end":"2026-03-18T12:37:06.56291Z","steps":["trace[592215619] 'process raft request' (duration: 134.79005ms)","trace[592215619] 'compare' (duration: 296.211369ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:06.563026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:06.131272Z","time spent":"431.692913ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:37:06.562983Z","caller":"traceutil/trace.go:171","msg":"trace[1560230398] transaction","detail":"{read_only:false; response_revision:23551; number_of_response:1; }","duration":"431.05909ms","start":"2026-03-18T12:37:06.131887Z","end":"2026-03-18T12:37:06.562946Z","steps":["trace[1560230398] 'process raft request' (duration: 430.929468ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:06.56314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:06.131041Z","time spent":"432.010709ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:37:06.563216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:06.131879Z","time spent":"431.251774ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:37:06.57806Z","caller":"traceutil/trace.go:171","msg":"trace[1827608805] linearizableReadLoop","detail":"{readStateIndex:24357; appliedIndex:24354; }","duration":"443.515495ms","start":"2026-03-18T12:37:06.134524Z","end":"2026-03-18T12:37:06.578039Z","steps":["trace[1827608805] 'read index received' (duration: 131.310687ms)","trace[1827608805] 'applied index is now lower than readState.Index' (duration: 312.203518ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:06.578246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.704149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-03-18T12:37:06.578284Z","caller":"traceutil/trace.go:171","msg":"trace[1122408684] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23551; }","duration":"443.789291ms","start":"2026-03-18T12:37:06.134485Z","end":"2026-03-18T12:37:06.578274Z","steps":["trace[1122408684] 'agreement among raft nodes before linearized reading' (duration: 443.633467ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:06.578317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:06.134475Z","time spent":"443.834633ms","remote":"127.0.0.1:46740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-03-18T12:37:07.272013Z","caller":"traceutil/trace.go:171","msg":"trace[710027581] linearizableReadLoop","detail":"{readStateIndex:24359; appliedIndex:24358; }","duration":"166.73008ms","start":"2026-03-18T12:37:07.105255Z","end":"2026-03-18T12:37:07.271985Z","steps":["trace[710027581] 'read index received' (duration: 166.498795ms)","trace[710027581] 'applied index is now lower than readState.Index' (duration: 230.345µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:07.272193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.865393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:37:07.272233Z","caller":"traceutil/trace.go:171","msg":"trace[430905535] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23552; }","duration":"167.003706ms","start":"2026-03-18T12:37:07.105217Z","end":"2026-03-18T12:37:07.27222Z","steps":["trace[430905535] 'agreement among raft nodes before linearized reading' (duration: 166.867773ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:07.27214Z","caller":"traceutil/trace.go:171","msg":"trace[1920222805] transaction","detail":"{read_only:false; response_revision:23552; number_of_response:1; }","duration":"300.849876ms","start":"2026-03-18T12:37:06.971242Z","end":"2026-03-18T12:37:07.272092Z","steps":["trace[1920222805] 'process raft request' (duration: 300.56185ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:07.273481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:06.971224Z","time spent":"301.05495ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:37:07.566039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.968978ms","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-03-18T12:37:07.566095Z","caller":"traceutil/trace.go:171","msg":"trace[755672738] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:23552; }","duration":"176.070479ms","start":"2026-03-18T12:37:07.390011Z","end":"2026-03-18T12:37:07.566081Z","steps":["trace[755672738] 'range keys from in-memory index tree' (duration: 175.819074ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:07.773722Z","caller":"traceutil/trace.go:171","msg":"trace[957131570] transaction","detail":"{read_only:false; response_revision:23553; number_of_response:1; }","duration":"135.216969ms","start":"2026-03-18T12:37:07.638479Z","end":"2026-03-18T12:37:07.773696Z","steps":["trace[957131570] 'process raft request' (duration: 135.064666ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:07.844215Z","caller":"traceutil/trace.go:171","msg":"trace[135762552] transaction","detail":"{read_only:false; response_revision:23554; number_of_response:1; }","duration":"186.793877ms","start":"2026-03-18T12:37:07.657393Z","end":"2026-03-18T12:37:07.844186Z","steps":["trace[135762552] 'process raft request' (duration: 186.637874ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:08.185026Z","caller":"traceutil/trace.go:171","msg":"trace[320750875] transaction","detail":"{read_only:false; response_revision:23557; number_of_response:1; }","duration":"230.173441ms","start":"2026-03-18T12:37:07.954826Z","end":"2026-03-18T12:37:08.185Z","steps":["trace[320750875] 'process raft request' (duration: 230.1242ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:08.18508Z","caller":"traceutil/trace.go:171","msg":"trace[1100081526] transaction","detail":"{read_only:false; response_revision:23556; number_of_response:1; }","duration":"230.295873ms","start":"2026-03-18T12:37:07.954765Z","end":"2026-03-18T12:37:08.185061Z","steps":["trace[1100081526] 'process raft request' (duration: 230.029748ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:08.49342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.501684ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:37:08.493578Z","caller":"traceutil/trace.go:171","msg":"trace[787566487] transaction","detail":"{read_only:false; response_revision:23560; number_of_response:1; }","duration":"283.60906ms","start":"2026-03-18T12:37:08.209941Z","end":"2026-03-18T12:37:08.49355Z","steps":["trace[787566487] 'process raft request' (duration: 63.885741ms)","trace[787566487] 'compare' (duration: 219.383222ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:37:08.759639Z","caller":"traceutil/trace.go:171","msg":"trace[1455544983] transaction","detail":"{read_only:false; response_revision:23561; number_of_response:1; }","duration":"186.84029ms","start":"2026-03-18T12:37:08.572774Z","end":"2026-03-18T12:37:08.759615Z","steps":["trace[1455544983] 'process raft request' (duration: 105.445856ms)","trace[1455544983] 'compare' (duration: 81.251531ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:09.06907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.904231ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:37:09.069209Z","caller":"traceutil/trace.go:171","msg":"trace[1394158056] linearizableReadLoop","detail":"{readStateIndex:24370; appliedIndex:24368; }","duration":"366.174458ms","start":"2026-03-18T12:37:08.703018Z","end":"2026-03-18T12:37:09.069193Z","steps":["trace[1394158056] 'read index received' (duration: 178.016451ms)","trace[1394158056] 'applied index is now lower than readState.Index' (duration: 188.156947ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:37:09.069326Z","caller":"traceutil/trace.go:171","msg":"trace[1981982225] transaction","detail":"{read_only:false; response_revision:23563; number_of_response:1; }","duration":"494.178944ms","start":"2026-03-18T12:37:08.575139Z","end":"2026-03-18T12:37:09.069318Z","steps":["trace[1981982225] 'process raft request' (duration: 494.003871ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:09.069327Z","caller":"traceutil/trace.go:171","msg":"trace[1190547507] transaction","detail":"{read_only:false; response_revision:23562; number_of_response:1; }","duration":"494.314817ms","start":"2026-03-18T12:37:08.574994Z","end":"2026-03-18T12:37:09.069309Z","steps":["trace[1190547507] 'process raft request' (duration: 306.088829ms)","trace[1190547507] 'compare' (duration: 187.776909ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:09.069373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:08.574986Z","time spent":"494.362838ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:37:09.069434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.417523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:37:09.069458Z","caller":"traceutil/trace.go:171","msg":"trace[948893622] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:23563; }","duration":"366.447144ms","start":"2026-03-18T12:37:08.703004Z","end":"2026-03-18T12:37:09.069452Z","steps":["trace[948893622] 'agreement among raft nodes before linearized reading' (duration: 366.383863ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:09.06948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:08.702953Z","time spent":"366.521086ms","remote":"127.0.0.1:56914","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true "} {"level":"warn","ts":"2026-03-18T12:37:09.069372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:08.575118Z","time spent":"494.228625ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:37:09.388182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.71592ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:37:09.388503Z","caller":"traceutil/trace.go:171","msg":"trace[1468372541] transaction","detail":"{read_only:false; response_revision:23564; number_of_response:1; }","duration":"250.345321ms","start":"2026-03-18T12:37:09.13811Z","end":"2026-03-18T12:37:09.388455Z","steps":["trace[1468372541] 'process raft request' (duration: 83.240283ms)","trace[1468372541] 'compare' (duration: 166.605277ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:09.388575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.226526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-03-18T12:37:09.388672Z","caller":"traceutil/trace.go:171","msg":"trace[90013075] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:23564; }","duration":"248.37013ms","start":"2026-03-18T12:37:09.14028Z","end":"2026-03-18T12:37:09.38865Z","steps":["trace[90013075] 'agreement among raft nodes before linearized reading' (duration: 248.116914ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:09.389187Z","caller":"traceutil/trace.go:171","msg":"trace[1257546861] linearizableReadLoop","detail":"{readStateIndex:24371; appliedIndex:24370; }","duration":"247.956051ms","start":"2026-03-18T12:37:09.140316Z","end":"2026-03-18T12:37:09.388272Z","steps":["trace[1257546861] 'read index received' (duration: 81.017215ms)","trace[1257546861] 'applied index is now lower than readState.Index' (duration: 166.937276ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:09.389572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.524285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-03-18T12:37:09.389662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.44441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:130034"} {"level":"info","ts":"2026-03-18T12:37:09.389685Z","caller":"traceutil/trace.go:171","msg":"trace[857454000] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:23564; }","duration":"111.631967ms","start":"2026-03-18T12:37:09.277989Z","end":"2026-03-18T12:37:09.389621Z","steps":["trace[857454000] 'agreement among raft nodes before linearized reading' (duration: 111.430773ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:09.389746Z","caller":"traceutil/trace.go:171","msg":"trace[1249346251] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:23564; }","duration":"170.575262ms","start":"2026-03-18T12:37:09.219157Z","end":"2026-03-18T12:37:09.389732Z","steps":["trace[1249346251] 'agreement among raft nodes before linearized reading' (duration: 170.267626ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:09.389985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.157417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-03-18T12:37:09.390043Z","caller":"traceutil/trace.go:171","msg":"trace[1602560878] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:23564; }","duration":"227.244679ms","start":"2026-03-18T12:37:09.162783Z","end":"2026-03-18T12:37:09.390028Z","steps":["trace[1602560878] 'agreement among raft nodes before linearized reading' (duration: 226.639157ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:09.678327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.635559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:37:09.678407Z","caller":"traceutil/trace.go:171","msg":"trace[702849918] linearizableReadLoop","detail":"{readStateIndex:24373; appliedIndex:24371; }","duration":"283.60038ms","start":"2026-03-18T12:37:09.394794Z","end":"2026-03-18T12:37:09.678395Z","steps":["trace[702849918] 'read index received' (duration: 74.010496ms)","trace[702849918] 'applied index is now lower than readState.Index' (duration: 209.588984ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:37:09.678498Z","caller":"traceutil/trace.go:171","msg":"trace[2048245983] transaction","detail":"{read_only:false; response_revision:23566; number_of_response:1; }","duration":"284.037779ms","start":"2026-03-18T12:37:09.394408Z","end":"2026-03-18T12:37:09.678446Z","steps":["trace[2048245983] 'process raft request' (duration: 155.211245ms)","trace[2048245983] 'compare' (duration: 128.533587ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:09.678992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.205172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:90 size:117047"} {"level":"info","ts":"2026-03-18T12:37:09.679029Z","caller":"traceutil/trace.go:171","msg":"trace[1723709570] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:90; response_revision:23566; }","duration":"284.300114ms","start":"2026-03-18T12:37:09.394721Z","end":"2026-03-18T12:37:09.679021Z","steps":["trace[1723709570] 'agreement among raft nodes before linearized reading' (duration: 283.719621ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:09.748858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.676439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephcosidrivers/\" range_end:\"/registry/ceph.rook.io/cephcosidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:37:09.74894Z","caller":"traceutil/trace.go:171","msg":"trace[1482732147] range","detail":"{range_begin:/registry/ceph.rook.io/cephcosidrivers/; range_end:/registry/ceph.rook.io/cephcosidrivers0; response_count:0; response_revision:23567; }","duration":"212.817482ms","start":"2026-03-18T12:37:09.5361Z","end":"2026-03-18T12:37:09.748917Z","steps":["trace[1482732147] 'agreement among raft nodes before linearized reading' (duration: 212.648638ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:09.748958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.115606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"warn","ts":"2026-03-18T12:37:09.748992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.042431ms","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-03-18T12:37:09.749059Z","caller":"traceutil/trace.go:171","msg":"trace[508895968] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:23567; }","duration":"350.229008ms","start":"2026-03-18T12:37:09.398806Z","end":"2026-03-18T12:37:09.749035Z","steps":["trace[508895968] 'agreement among raft nodes before linearized reading' (duration: 349.985003ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:09.749138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.139705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephbucketnotifications/\" range_end:\"/registry/ceph.rook.io/cephbucketnotifications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-18T12:37:09.749154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:09.398724Z","time spent":"350.413963ms","remote":"127.0.0.1:46672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-03-18T12:37:09.749179Z","caller":"traceutil/trace.go:171","msg":"trace[932377283] range","detail":"{range_begin:/registry/ceph.rook.io/cephbucketnotifications/; range_end:/registry/ceph.rook.io/cephbucketnotifications0; response_count:0; response_revision:23567; }","duration":"288.222737ms","start":"2026-03-18T12:37:09.460946Z","end":"2026-03-18T12:37:09.749168Z","steps":["trace[932377283] 'agreement among raft nodes before linearized reading' (duration: 288.157586ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:09.749077Z","caller":"traceutil/trace.go:171","msg":"trace[1640802473] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23567; }","duration":"332.189824ms","start":"2026-03-18T12:37:09.416864Z","end":"2026-03-18T12:37:09.749054Z","steps":["trace[1640802473] 'agreement among raft nodes before linearized reading' (duration: 331.906758ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:09.749413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:09.416843Z","time spent":"332.555762ms","remote":"127.0.0.1:46730","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1875,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-03-18T12:37:09.96925Z","caller":"traceutil/trace.go:171","msg":"trace[1288147534] transaction","detail":"{read_only:false; response_revision:23568; number_of_response:1; }","duration":"187.988863ms","start":"2026-03-18T12:37:09.781236Z","end":"2026-03-18T12:37:09.969225Z","steps":["trace[1288147534] 'process raft request' (duration: 187.785259ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:10.014289Z","caller":"traceutil/trace.go:171","msg":"trace[1179099618] transaction","detail":"{read_only:false; response_revision:23569; number_of_response:1; }","duration":"164.678996ms","start":"2026-03-18T12:37:09.849564Z","end":"2026-03-18T12:37:10.014243Z","steps":["trace[1179099618] 'process raft request' (duration: 164.543004ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:10.298316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.745057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:37:10.298869Z","caller":"traceutil/trace.go:171","msg":"trace[561739812] transaction","detail":"{read_only:false; response_revision:23571; number_of_response:1; }","duration":"107.579562ms","start":"2026-03-18T12:37:10.191267Z","end":"2026-03-18T12:37:10.298846Z","steps":["trace[561739812] 'process raft request' (duration: 107.50454ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:10.298904Z","caller":"traceutil/trace.go:171","msg":"trace[351925064] linearizableReadLoop","detail":"{readStateIndex:24377; appliedIndex:24376; }","duration":"208.522701ms","start":"2026-03-18T12:37:10.090359Z","end":"2026-03-18T12:37:10.298881Z","steps":["trace[351925064] 'read index received' (duration: 28.16733ms)","trace[351925064] 'applied index is now lower than readState.Index' (duration: 180.353521ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:37:10.298997Z","caller":"traceutil/trace.go:171","msg":"trace[1375096346] transaction","detail":"{read_only:false; response_revision:23570; number_of_response:1; }","duration":"266.924335ms","start":"2026-03-18T12:37:10.032046Z","end":"2026-03-18T12:37:10.29897Z","steps":["trace[1375096346] 'process raft request' (duration: 86.417241ms)","trace[1375096346] 'compare' (duration: 179.542313ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:10.299177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.798217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-03-18T12:37:10.299259Z","caller":"traceutil/trace.go:171","msg":"trace[2028601008] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:23571; }","duration":"208.93269ms","start":"2026-03-18T12:37:10.090306Z","end":"2026-03-18T12:37:10.299239Z","steps":["trace[2028601008] 'agreement among raft nodes before linearized reading' (duration: 208.704995ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:10.626573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.510492ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:37:10.626833Z","caller":"traceutil/trace.go:171","msg":"trace[1155375345] transaction","detail":"{read_only:false; response_revision:23572; number_of_response:1; }","duration":"432.714015ms","start":"2026-03-18T12:37:10.194086Z","end":"2026-03-18T12:37:10.6268Z","steps":["trace[1155375345] 'process raft request' (duration: 253.900067ms)","trace[1155375345] 'compare' (duration: 178.333808ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:37:10.626908Z","caller":"traceutil/trace.go:171","msg":"trace[1894946301] transaction","detail":"{read_only:false; response_revision:23573; number_of_response:1; }","duration":"417.501181ms","start":"2026-03-18T12:37:10.209375Z","end":"2026-03-18T12:37:10.626876Z","steps":["trace[1894946301] 'process raft request' (duration: 417.260056ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:10.626925Z","caller":"traceutil/trace.go:171","msg":"trace[1397383988] transaction","detail":"{read_only:false; response_revision:23574; number_of_response:1; }","duration":"415.59891ms","start":"2026-03-18T12:37:10.211296Z","end":"2026-03-18T12:37:10.626895Z","steps":["trace[1397383988] 'process raft request' (duration: 415.392956ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:10.626731Z","caller":"traceutil/trace.go:171","msg":"trace[801117317] linearizableReadLoop","detail":"{readStateIndex:24381; appliedIndex:24378; }","duration":"327.704979ms","start":"2026-03-18T12:37:10.299015Z","end":"2026-03-18T12:37:10.62672Z","steps":["trace[801117317] 'read index received' (duration: 148.973152ms)","trace[801117317] 'applied index is now lower than readState.Index' (duration: 178.731017ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:10.627009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:10.209365Z","time spent":"417.589023ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":605,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:37:10.627011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:10.194077Z","time spent":"432.847108ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-18T12:37:10.627022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-18T12:37:10.627048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:10.211201Z","time spent":"415.784474ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:37:10.627078Z","caller":"traceutil/trace.go:171","msg":"trace[2079164402] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:23574; }","duration":"479.578883ms","start":"2026-03-18T12:37:10.147481Z","end":"2026-03-18T12:37:10.62706Z","steps":["trace[2079164402] 'agreement among raft nodes before linearized reading' (duration: 479.469421ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:10.627124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:10.147459Z","time spent":"479.656094ms","remote":"127.0.0.1:45140","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":0,"response size":27,"request content":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true "} {"level":"info","ts":"2026-03-18T12:37:10.649183Z","caller":"traceutil/trace.go:171","msg":"trace[410253566] transaction","detail":"{read_only:false; response_revision:23575; number_of_response:1; }","duration":"113.674792ms","start":"2026-03-18T12:37:10.535486Z","end":"2026-03-18T12:37:10.649161Z","steps":["trace[410253566] 'process raft request' (duration: 113.509868ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:10.649279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.616759ms","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-03-18T12:37:10.649333Z","caller":"traceutil/trace.go:171","msg":"trace[1339720167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23575; }","duration":"151.714511ms","start":"2026-03-18T12:37:10.497606Z","end":"2026-03-18T12:37:10.649321Z","steps":["trace[1339720167] 'agreement among raft nodes before linearized reading' (duration: 151.516117ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:10.94858Z","caller":"traceutil/trace.go:171","msg":"trace[1643114633] transaction","detail":"{read_only:false; response_revision:23577; number_of_response:1; }","duration":"119.664268ms","start":"2026-03-18T12:37:10.828893Z","end":"2026-03-18T12:37:10.948558Z","steps":["trace[1643114633] 'process raft request' (duration: 119.518725ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:11.16446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.572153ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-03-18T12:37:11.384726Z","caller":"traceutil/trace.go:171","msg":"trace[1150850423] linearizableReadLoop","detail":"{readStateIndex:24388; appliedIndex:24386; }","duration":"139.681935ms","start":"2026-03-18T12:37:11.245022Z","end":"2026-03-18T12:37:11.384704Z","steps":["trace[1150850423] 'read index received' (duration: 139.427969ms)","trace[1150850423] 'applied index is now lower than readState.Index' (duration: 253.286µs)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:37:11.385025Z","caller":"traceutil/trace.go:171","msg":"trace[1706727256] transaction","detail":"{read_only:false; response_revision:23579; number_of_response:1; }","duration":"216.840827ms","start":"2026-03-18T12:37:11.168174Z","end":"2026-03-18T12:37:11.385015Z","steps":["trace[1706727256] 'process raft request' (duration: 216.366757ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:11.385282Z","caller":"traceutil/trace.go:171","msg":"trace[701141916] transaction","detail":"{read_only:false; response_revision:23580; number_of_response:1; }","duration":"216.447379ms","start":"2026-03-18T12:37:11.168823Z","end":"2026-03-18T12:37:11.385271Z","steps":["trace[701141916] 'process raft request' (duration: 215.851667ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:11.385468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.465154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:532"} {"level":"warn","ts":"2026-03-18T12:37:11.385434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.264873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:423"} {"level":"info","ts":"2026-03-18T12:37:11.385566Z","caller":"traceutil/trace.go:171","msg":"trace[2044436454] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:23580; }","duration":"137.447458ms","start":"2026-03-18T12:37:11.24809Z","end":"2026-03-18T12:37:11.385538Z","steps":["trace[2044436454] 'agreement among raft nodes before linearized reading' (duration: 137.068219ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:11.385497Z","caller":"traceutil/trace.go:171","msg":"trace[1734179835] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:23580; }","duration":"211.557875ms","start":"2026-03-18T12:37:11.173933Z","end":"2026-03-18T12:37:11.385491Z","steps":["trace[1734179835] 'agreement among raft nodes before linearized reading' (duration: 211.393951ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:11.554623Z","caller":"traceutil/trace.go:171","msg":"trace[1184277830] transaction","detail":"{read_only:false; response_revision:23581; number_of_response:1; }","duration":"164.847261ms","start":"2026-03-18T12:37:11.389748Z","end":"2026-03-18T12:37:11.554595Z","steps":["trace[1184277830] 'process raft request' (duration: 84.401427ms)","trace[1184277830] 'compare' (duration: 79.655667ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:11.811508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.073896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/flavors/\" range_end:\"/registry/openstack.k-orc.cloud/flavors0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:37:11.811575Z","caller":"traceutil/trace.go:171","msg":"trace[748345886] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/flavors/; range_end:/registry/openstack.k-orc.cloud/flavors0; response_count:0; response_revision:23582; }","duration":"111.177378ms","start":"2026-03-18T12:37:11.70038Z","end":"2026-03-18T12:37:11.811558Z","steps":["trace[748345886] 'count revisions from in-memory index tree' (duration: 111.012894ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:12.260434Z","caller":"traceutil/trace.go:171","msg":"trace[1260941654] linearizableReadLoop","detail":"{readStateIndex:24392; appliedIndex:24391; }","duration":"202.81325ms","start":"2026-03-18T12:37:12.057598Z","end":"2026-03-18T12:37:12.260411Z","steps":["trace[1260941654] 'read index received' (duration: 121.006448ms)","trace[1260941654] 'applied index is now lower than readState.Index' (duration: 81.805762ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:37:12.26052Z","caller":"traceutil/trace.go:171","msg":"trace[548160653] transaction","detail":"{read_only:false; response_revision:23584; number_of_response:1; }","duration":"240.708476ms","start":"2026-03-18T12:37:12.019788Z","end":"2026-03-18T12:37:12.260496Z","steps":["trace[548160653] 'process raft request' (duration: 158.838673ms)","trace[548160653] 'compare' (duration: 81.672199ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:12.260545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.931592ms","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-03-18T12:37:12.260727Z","caller":"traceutil/trace.go:171","msg":"trace[1011745505] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:23584; }","duration":"203.181127ms","start":"2026-03-18T12:37:12.057535Z","end":"2026-03-18T12:37:12.260716Z","steps":["trace[1011745505] 'agreement among raft nodes before linearized reading' (duration: 202.948542ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:12.475593Z","caller":"traceutil/trace.go:171","msg":"trace[103081990] transaction","detail":"{read_only:false; response_revision:23585; number_of_response:1; }","duration":"168.462308ms","start":"2026-03-18T12:37:12.307105Z","end":"2026-03-18T12:37:12.475567Z","steps":["trace[103081990] 'process raft request' (duration: 93.146684ms)","trace[103081990] 'compare' (duration: 75.203351ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:37:12.533561Z","caller":"traceutil/trace.go:171","msg":"trace[1437373420] transaction","detail":"{read_only:false; response_revision:23587; number_of_response:1; }","duration":"173.364232ms","start":"2026-03-18T12:37:12.360168Z","end":"2026-03-18T12:37:12.533532Z","steps":["trace[1437373420] 'process raft request' (duration: 173.25764ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:12.53361Z","caller":"traceutil/trace.go:171","msg":"trace[582116532] transaction","detail":"{read_only:false; response_revision:23586; number_of_response:1; }","duration":"221.442846ms","start":"2026-03-18T12:37:12.312134Z","end":"2026-03-18T12:37:12.533577Z","steps":["trace[582116532] 'process raft request' (duration: 221.15926ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:12.533714Z","caller":"traceutil/trace.go:171","msg":"trace[1376495948] linearizableReadLoop","detail":"{readStateIndex:24396; appliedIndex:24393; }","duration":"115.287995ms","start":"2026-03-18T12:37:12.418407Z","end":"2026-03-18T12:37:12.533695Z","steps":["trace[1376495948] 'read index received' (duration: 114.783934ms)","trace[1376495948] 'applied index is now lower than readState.Index' (duration: 503.081µs)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:12.533897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.476269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-03-18T12:37:12.53393Z","caller":"traceutil/trace.go:171","msg":"trace[873965455] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:23588; }","duration":"115.55363ms","start":"2026-03-18T12:37:12.418365Z","end":"2026-03-18T12:37:12.533919Z","steps":["trace[873965455] 'agreement among raft nodes before linearized reading' (duration: 115.390097ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:37:12.53405Z","caller":"traceutil/trace.go:171","msg":"trace[138470438] transaction","detail":"{read_only:false; response_revision:23588; number_of_response:1; }","duration":"168.173662ms","start":"2026-03-18T12:37:12.365865Z","end":"2026-03-18T12:37:12.534038Z","steps":["trace[138470438] 'process raft request' (duration: 167.605889ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:13.009213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.637476ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:37:13.009444Z","caller":"traceutil/trace.go:171","msg":"trace[1900582246] linearizableReadLoop","detail":"{readStateIndex:24399; appliedIndex:24396; }","duration":"326.007582ms","start":"2026-03-18T12:37:12.683382Z","end":"2026-03-18T12:37:13.00939Z","steps":["trace[1900582246] 'read index received' (duration: 16.795287ms)","trace[1900582246] 'applied index is now lower than readState.Index' (duration: 309.211305ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:37:13.009541Z","caller":"traceutil/trace.go:171","msg":"trace[1765143761] transaction","detail":"{read_only:false; response_revision:23591; number_of_response:1; }","duration":"375.865204ms","start":"2026-03-18T12:37:12.633664Z","end":"2026-03-18T12:37:13.009529Z","steps":["trace[1765143761] 'process raft request' (duration: 375.67509ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:13.00964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.819527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-03-18T12:37:13.009706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:12.633653Z","time spent":"375.962346ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:37:13.00973Z","caller":"traceutil/trace.go:171","msg":"trace[1931974259] range","detail":"{range_begin:/registry/ipam.cluster.x-k8s.io/ipaddressclaims/; range_end:/registry/ipam.cluster.x-k8s.io/ipaddressclaims0; response_count:0; response_revision:23591; }","duration":"309.973391ms","start":"2026-03-18T12:37:12.699738Z","end":"2026-03-18T12:37:13.009711Z","steps":["trace[1931974259] 'agreement among raft nodes before linearized reading' (duration: 309.816727ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:13.009771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:12.699718Z","time spent":"310.043692ms","remote":"127.0.0.1:47860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":0,"response size":27,"request content":"key:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims/\" range_end:\"/registry/ipam.cluster.x-k8s.io/ipaddressclaims0\" count_only:true "} {"level":"info","ts":"2026-03-18T12:37:13.009785Z","caller":"traceutil/trace.go:171","msg":"trace[599354502] transaction","detail":"{read_only:false; response_revision:23590; number_of_response:1; }","duration":"376.533688ms","start":"2026-03-18T12:37:12.633117Z","end":"2026-03-18T12:37:13.009651Z","steps":["trace[599354502] 'process raft request' (duration: 273.32685ms)","trace[599354502] 'compare' (duration: 102.173627ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:13.009935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.535173ms","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-03-18T12:37:13.010039Z","caller":"traceutil/trace.go:171","msg":"trace[10952806] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:23591; }","duration":"326.674896ms","start":"2026-03-18T12:37:12.683352Z","end":"2026-03-18T12:37:13.010027Z","steps":["trace[10952806] 'agreement among raft nodes before linearized reading' (duration: 326.526823ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:13.010063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:12.683339Z","time spent":"326.717267ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-18T12:37:13.009964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.87865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:37:13.010133Z","caller":"traceutil/trace.go:171","msg":"trace[1851962812] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:23591; }","duration":"226.066743ms","start":"2026-03-18T12:37:12.784051Z","end":"2026-03-18T12:37:13.010118Z","steps":["trace[1851962812] 'agreement among raft nodes before linearized reading' (duration: 225.884669ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:13.009965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-18T12:37:12.633085Z","time spent":"376.769423ms","remote":"127.0.0.1:46808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-18T12:37:13.183713Z","caller":"traceutil/trace.go:171","msg":"trace[2104721258] transaction","detail":"{read_only:false; response_revision:23593; number_of_response:1; }","duration":"169.125731ms","start":"2026-03-18T12:37:13.014561Z","end":"2026-03-18T12:37:13.183687Z","steps":["trace[2104721258] 'process raft request' (duration: 164.216438ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:37:13.634578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.862209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:37:13.634694Z","caller":"traceutil/trace.go:171","msg":"trace[1602099445] linearizableReadLoop","detail":"{readStateIndex:24403; appliedIndex:24401; }","duration":"184.719513ms","start":"2026-03-18T12:37:13.449957Z","end":"2026-03-18T12:37:13.634676Z","steps":["trace[1602099445] 'read index received' (duration: 11.479275ms)","trace[1602099445] 'applied index is now lower than readState.Index' (duration: 173.238978ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:37:13.634785Z","caller":"traceutil/trace.go:171","msg":"trace[1034518110] transaction","detail":"{read_only:false; response_revision:23595; number_of_response:1; }","duration":"242.845201ms","start":"2026-03-18T12:37:13.391902Z","end":"2026-03-18T12:37:13.634748Z","steps":["trace[1034518110] 'process raft request' (duration: 140.697646ms)","trace[1034518110] 'compare' (duration: 101.687915ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:37:13.634854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.877057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-03-18T12:37:13.634922Z","caller":"traceutil/trace.go:171","msg":"trace[228040173] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:23595; }","duration":"184.99497ms","start":"2026-03-18T12:37:13.44991Z","end":"2026-03-18T12:37:13.634905Z","steps":["trace[228040173] 'agreement among raft nodes before linearized reading' (duration: 184.848366ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:38:29.840757Z","caller":"traceutil/trace.go:171","msg":"trace[290246191] transaction","detail":"{read_only:false; response_revision:24320; number_of_response:1; }","duration":"121.942587ms","start":"2026-03-18T12:38:29.718784Z","end":"2026-03-18T12:38:29.840726Z","steps":["trace[290246191] 'process raft request' (duration: 121.750663ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:38:45.210559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.853931ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-03-18T12:38:45.210694Z","caller":"traceutil/trace.go:171","msg":"trace[1119291298] linearizableReadLoop","detail":"{readStateIndex:25292; appliedIndex:25291; }","duration":"154.803396ms","start":"2026-03-18T12:38:45.05587Z","end":"2026-03-18T12:38:45.210673Z","steps":["trace[1119291298] 'read index received' (duration: 4.619868ms)","trace[1119291298] 'applied index is now lower than readState.Index' (duration: 150.181568ms)"],"step_count":2} {"level":"warn","ts":"2026-03-18T12:38:45.210799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.919998ms","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-03-18T12:38:45.210848Z","caller":"traceutil/trace.go:171","msg":"trace[1451287144] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-user; range_end:; response_count:0; response_revision:24464; }","duration":"154.96921ms","start":"2026-03-18T12:38:45.055865Z","end":"2026-03-18T12:38:45.210834Z","steps":["trace[1451287144] 'agreement among raft nodes before linearized reading' (duration: 154.886518ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:38:45.210796Z","caller":"traceutil/trace.go:171","msg":"trace[985589024] transaction","detail":"{read_only:false; response_revision:24464; number_of_response:1; }","duration":"169.009709ms","start":"2026-03-18T12:38:45.041735Z","end":"2026-03-18T12:38:45.210745Z","steps":["trace[985589024] 'process raft request' (duration: 18.823481ms)","trace[985589024] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/capi-system/controller-leader-election-capi; req_size:518; } (duration: 149.305489ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:38:45.23345Z","caller":"traceutil/trace.go:171","msg":"trace[1584318665] transaction","detail":"{read_only:false; response_revision:24465; number_of_response:1; }","duration":"100.872768ms","start":"2026-03-18T12:38:45.13255Z","end":"2026-03-18T12:38:45.233422Z","steps":["trace[1584318665] 'process raft request' (duration: 100.687545ms)"],"step_count":1} {"level":"warn","ts":"2026-03-18T12:38:45.233509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.566771ms","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-03-18T12:38:45.233583Z","caller":"traceutil/trace.go:171","msg":"trace[282529777] range","detail":"{range_begin:/registry/jobs/openstack/manila-ks-endpoints; range_end:; response_count:0; response_revision:24465; }","duration":"177.658922ms","start":"2026-03-18T12:38:45.055904Z","end":"2026-03-18T12:38:45.233563Z","steps":["trace[282529777] 'agreement among raft nodes before linearized reading' (duration: 177.479829ms)"],"step_count":1} {"level":"info","ts":"2026-03-18T12:39:07.820385Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21920} {"level":"info","ts":"2026-03-18T12:39:07.968484Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21920,"took":"144.217622ms","hash":83557872,"current-db-size-bytes":48652288,"current-db-size":"49 MB","current-db-size-in-use-bytes":33169408,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-03-18T12:39:07.968554Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":83557872,"revision":21920,"compact-revision":18837} {"level":"info","ts":"2026-03-18T12:41:16.688817Z","caller":"traceutil/trace.go:171","msg":"trace[1478602476] transaction","detail":"{read_only:false; response_revision:25921; number_of_response:1; }","duration":"170.408619ms","start":"2026-03-18T12:41:16.518376Z","end":"2026-03-18T12:41:16.688785Z","steps":["trace[1478602476] 'process raft request' (duration: 146.548351ms)","trace[1478602476] 'compare' (duration: 23.277456ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:44:07.829765Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24660} {"level":"info","ts":"2026-03-18T12:44:07.960001Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24660,"took":"126.142846ms","hash":2866599501,"current-db-size-bytes":48652288,"current-db-size":"49 MB","current-db-size-in-use-bytes":33193984,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-03-18T12:44:07.960055Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2866599501,"revision":24660,"compact-revision":21920} {"level":"info","ts":"2026-03-18T12:48:45.380406Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-18T12:48:45.385683Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-03-18T12:48:45.385848Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-03-18T12:49:07.836503Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27115} {"level":"info","ts":"2026-03-18T12:49:07.97884Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27115,"took":"138.243025ms","hash":452611995,"current-db-size-bytes":48652288,"current-db-size":"49 MB","current-db-size-in-use-bytes":27951104,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-18T12:49:07.978887Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":452611995,"revision":27115,"compact-revision":24660} {"level":"info","ts":"2026-03-18T12:53:14.936823Z","caller":"traceutil/trace.go:171","msg":"trace[649238225] transaction","detail":"{read_only:false; response_revision:30941; number_of_response:1; }","duration":"166.137709ms","start":"2026-03-18T12:53:14.770632Z","end":"2026-03-18T12:53:14.936769Z","steps":["trace[649238225] 'process raft request' (duration: 149.687509ms)","trace[649238225] 'compare' (duration: 16.112493ms)"],"step_count":2} {"level":"info","ts":"2026-03-18T12:54:07.842232Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29187} {"level":"info","ts":"2026-03-18T12:54:07.954096Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29187,"took":"107.805485ms","hash":1146549708,"current-db-size-bytes":48652288,"current-db-size":"49 MB","current-db-size-in-use-bytes":28286976,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-18T12:54:07.954163Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1146549708,"revision":29187,"compact-revision":27115} {"level":"info","ts":"2026-03-18T12:59:07.848249Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31356} {"level":"info","ts":"2026-03-18T12:59:07.984792Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31356,"took":"133.770418ms","hash":3968614280,"current-db-size-bytes":49917952,"current-db-size":"50 MB","current-db-size-in-use-bytes":29347840,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-03-18T12:59:07.984863Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3968614280,"revision":31356,"compact-revision":29187} {"level":"info","ts":"2026-03-18T13:03:27.677901Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000008e84.wal"} {"level":"info","ts":"2026-03-18T13:04:07.854602Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33455} {"level":"info","ts":"2026-03-18T13:04:07.99791Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33455,"took":"138.501938ms","hash":1678647152,"current-db-size-bytes":52449280,"current-db-size":"52 MB","current-db-size-in-use-bytes":29585408,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-03-18T13:04:07.997986Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1678647152,"revision":33455,"compact-revision":31356} {"level":"info","ts":"2026-03-18T13:09:07.860564Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35597} {"level":"info","ts":"2026-03-18T13:09:07.984055Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35597,"took":"119.223008ms","hash":1900340278,"current-db-size-bytes":52449280,"current-db-size":"52 MB","current-db-size-in-use-bytes":28430336,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-03-18T13:09:07.984114Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1900340278,"revision":35597,"compact-revision":33455}