{"level":"warn","ts":"2026-08-20T01:14:43.185316Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-20T01:14:43.185398Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38: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.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-08-20T01:14:43.185461Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-20T01:14:43.185474Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-08-20T01:14:43.185495Z","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-08-20T01:14:43.186089Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-08-20T01:14:43.186163Z","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.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-08-20T01:14:43.188961Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.583521ms"} {"level":"info","ts":"2026-08-20T01:14:43.195884Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-08-20T01:14:43.196047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-08-20T01:14:43.196102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-08-20T01:14:43.196141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-20T01:14:43.196174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-08-20T01:14:43.196258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-08-20T01:14:43.201544Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-20T01:14:43.202933Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-20T01:14:43.204075Z","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-08-20T01:14:43.206425Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-20T01:14:43.206618Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-20T01:14:43.206773Z","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-08-20T01:14:43.206977Z","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-08-20T01:14:43.207028Z","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-08-20T01:14:43.212245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-08-20T01:14:43.212752Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-08-20T01:14:43.21431Z","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-08-20T01:14:43.214406Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-08-20T01:14:43.214424Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-08-20T01:14:43.214559Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-20T01:14:43.21459Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-20T01:14:43.897267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-08-20T01:14:43.897356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-20T01:14:43.897405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-08-20T01:14:43.897426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-08-20T01:14:43.897438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-08-20T01:14:43.897454Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-08-20T01:14:43.897467Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-08-20T01:14:43.909837Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:14:43.910721Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-08-20T01:14:43.91073Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T01:14:43.910763Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-20T01:14:43.911065Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-20T01:14:43.911099Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-20T01:14:43.911255Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:14:43.911376Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:14:43.911421Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-20T01:14:43.914063Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-08-20T01:14:43.914641Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-20T01:14:55.860127Z","caller":"traceutil/trace.go:171","msg":"trace[1292737887] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"411.755496ms","start":"2026-08-20T01:14:55.448347Z","end":"2026-08-20T01:14:55.860102Z","steps":["trace[1292737887] 'process raft request' (duration: 411.551111ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:14:55.861021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:14:55.448323Z","time spent":"411.896329ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:14:55.862973Z","caller":"traceutil/trace.go:171","msg":"trace[47354561] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"244.352291ms","start":"2026-08-20T01:14:55.618572Z","end":"2026-08-20T01:14:55.862924Z","steps":["trace[47354561] 'process raft request' (duration: 244.112285ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:19.851314Z","caller":"traceutil/trace.go:171","msg":"trace[157236995] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"102.398124ms","start":"2026-08-20T01:15:19.748865Z","end":"2026-08-20T01:15:19.851264Z","steps":["trace[157236995] 'process raft request' (duration: 95.487125ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:19.856502Z","caller":"traceutil/trace.go:171","msg":"trace[1068932427] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"101.720086ms","start":"2026-08-20T01:15:19.754759Z","end":"2026-08-20T01:15:19.856479Z","steps":["trace[1068932427] 'process raft request' (duration: 91.955978ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:25.776739Z","caller":"traceutil/trace.go:171","msg":"trace[2122603465] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"157.615276ms","start":"2026-08-20T01:15:25.619096Z","end":"2026-08-20T01:15:25.776711Z","steps":["trace[2122603465] 'process raft request' (duration: 157.466402ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:26.272761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.501502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:15:26.272921Z","caller":"traceutil/trace.go:171","msg":"trace[418500690] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"215.594574ms","start":"2026-08-20T01:15:26.057315Z","end":"2026-08-20T01:15:26.272909Z","steps":["trace[418500690] 'process raft request' (duration: 215.547823ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:26.273065Z","caller":"traceutil/trace.go:171","msg":"trace[1983569928] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"398.563694ms","start":"2026-08-20T01:15:25.874495Z","end":"2026-08-20T01:15:26.273059Z","steps":["trace[1983569928] 'process raft request' (duration: 14.593409ms)","trace[1983569928] 'compare' (duration: 383.313887ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:15:26.273097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:15:25.874446Z","time spent":"398.638017ms","remote":"127.0.0.1:52010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-20T01:15:26.273158Z","caller":"traceutil/trace.go:171","msg":"trace[2043481526] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:611; }","duration":"271.141605ms","start":"2026-08-20T01:15:26.00201Z","end":"2026-08-20T01:15:26.273152Z","steps":["trace[2043481526] 'read index received' (duration: 83.122µs)","trace[2043481526] 'applied index is now lower than readState.Index' (duration: 271.057713ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:15:26.273364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.831142ms","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-08-20T01:15:26.27338Z","caller":"traceutil/trace.go:171","msg":"trace[1167816215] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"268.872233ms","start":"2026-08-20T01:15:26.004502Z","end":"2026-08-20T01:15:26.273374Z","steps":["trace[1167816215] 'agreement among raft nodes before linearized reading' (duration: 268.791341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:15:26.274067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.03271ms","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-08-20T01:15:26.274166Z","caller":"traceutil/trace.go:171","msg":"trace[3350642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"272.186344ms","start":"2026-08-20T01:15:26.001959Z","end":"2026-08-20T01:15:26.274146Z","steps":["trace[3350642] 'agreement among raft nodes before linearized reading' (duration: 271.3208ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:15:40.793943Z","caller":"traceutil/trace.go:171","msg":"trace[1962556553] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"122.825553ms","start":"2026-08-20T01:15:40.67109Z","end":"2026-08-20T01:15:40.793916Z","steps":["trace[1962556553] 'process raft request' (duration: 122.638478ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:17:49.172213Z","caller":"traceutil/trace.go:171","msg":"trace[1262251744] transaction","detail":"{read_only:false; response_revision:2146; number_of_response:1; }","duration":"112.73591ms","start":"2026-08-20T01:17:49.059418Z","end":"2026-08-20T01:17:49.172154Z","steps":["trace[1262251744] 'process raft request' (duration: 105.716784ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:02.036723Z","caller":"traceutil/trace.go:171","msg":"trace[2102035189] transaction","detail":"{read_only:false; response_revision:4954; number_of_response:1; }","duration":"169.758423ms","start":"2026-08-20T01:23:01.866925Z","end":"2026-08-20T01:23:02.036684Z","steps":["trace[2102035189] 'process raft request' (duration: 169.509996ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:23:02.037053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.784921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:16794"} {"level":"info","ts":"2026-08-20T01:23:02.03711Z","caller":"traceutil/trace.go:171","msg":"trace[1651602270] linearizableReadLoop","detail":"{readStateIndex:5139; appliedIndex:5138; }","duration":"146.458932ms","start":"2026-08-20T01:23:01.890149Z","end":"2026-08-20T01:23:02.036608Z","steps":["trace[1651602270] 'read index received' (duration: 146.234556ms)","trace[1651602270] 'applied index is now lower than readState.Index' (duration: 223.246µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:23:02.037303Z","caller":"traceutil/trace.go:171","msg":"trace[249776349] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4954; }","duration":"146.994028ms","start":"2026-08-20T01:23:01.890136Z","end":"2026-08-20T01:23:02.03713Z","steps":["trace[249776349] 'agreement among raft nodes before linearized reading' (duration: 146.621967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:23:02.03736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.614817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-20T01:23:02.037401Z","caller":"traceutil/trace.go:171","msg":"trace[1792777847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4954; }","duration":"131.702099ms","start":"2026-08-20T01:23:01.905688Z","end":"2026-08-20T01:23:02.03739Z","steps":["trace[1792777847] 'agreement among raft nodes before linearized reading' (duration: 131.555765ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:02.269461Z","caller":"traceutil/trace.go:171","msg":"trace[992035751] linearizableReadLoop","detail":"{readStateIndex:5146; appliedIndex:5145; }","duration":"131.829503ms","start":"2026-08-20T01:23:02.137607Z","end":"2026-08-20T01:23:02.269437Z","steps":["trace[992035751] 'read index received' (duration: 116.295537ms)","trace[992035751] 'applied index is now lower than readState.Index' (duration: 15.533026ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:23:02.269541Z","caller":"traceutil/trace.go:171","msg":"trace[582634618] transaction","detail":"{read_only:false; response_revision:4961; number_of_response:1; }","duration":"135.372232ms","start":"2026-08-20T01:23:02.134138Z","end":"2026-08-20T01:23:02.26951Z","steps":["trace[582634618] 'process raft request' (duration: 119.755004ms)","trace[582634618] 'compare' (duration: 15.439513ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:23:02.269735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.09963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-20T01:23:02.269811Z","caller":"traceutil/trace.go:171","msg":"trace[1916529844] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4961; }","duration":"132.212824ms","start":"2026-08-20T01:23:02.137583Z","end":"2026-08-20T01:23:02.269796Z","steps":["trace[1916529844] 'agreement among raft nodes before linearized reading' (duration: 131.955176ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:02.621092Z","caller":"traceutil/trace.go:171","msg":"trace[22501307] transaction","detail":"{read_only:false; response_revision:4965; number_of_response:1; }","duration":"171.957296ms","start":"2026-08-20T01:23:02.449089Z","end":"2026-08-20T01:23:02.621046Z","steps":["trace[22501307] 'process raft request' (duration: 171.826752ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:23:02.634848Z","caller":"traceutil/trace.go:171","msg":"trace[404448821] transaction","detail":"{read_only:false; response_revision:4966; number_of_response:1; }","duration":"146.779031ms","start":"2026-08-20T01:23:02.488052Z","end":"2026-08-20T01:23:02.634831Z","steps":["trace[404448821] 'process raft request' (duration: 146.669058ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:23:09.693316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.845576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:23:09.693413Z","caller":"traceutil/trace.go:171","msg":"trace[652631960] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:5125; }","duration":"166.037771ms","start":"2026-08-20T01:23:09.527354Z","end":"2026-08-20T01:23:09.693391Z","steps":["trace[652631960] 'count revisions from in-memory index tree' (duration: 165.743872ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:24:43.946141Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2816} {"level":"info","ts":"2026-08-20T01:24:44.004622Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2816,"took":"57.329085ms","hash":2527196925,"current-db-size-bytes":57532416,"current-db-size":"58 MB","current-db-size-in-use-bytes":33771520,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-20T01:24:44.004716Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2527196925,"revision":2816,"compact-revision":-1} {"level":"info","ts":"2026-08-20T01:28:55.904898Z","caller":"traceutil/trace.go:171","msg":"trace[1639164027] transaction","detail":"{read_only:false; response_revision:7838; number_of_response:1; }","duration":"340.05962ms","start":"2026-08-20T01:28:55.564815Z","end":"2026-08-20T01:28:55.904875Z","steps":["trace[1639164027] 'process raft request' (duration: 339.901155ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:28:55.905057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:28:55.564796Z","time spent":"340.185194ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:28:55.978483Z","caller":"traceutil/trace.go:171","msg":"trace[1011032282] transaction","detail":"{read_only:false; response_revision:7839; number_of_response:1; }","duration":"291.859401ms","start":"2026-08-20T01:28:55.686597Z","end":"2026-08-20T01:28:55.978456Z","steps":["trace[1011032282] 'process raft request' (duration: 281.692987ms)","trace[1011032282] 'compare' (duration: 10.00621ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:28:55.978663Z","caller":"traceutil/trace.go:171","msg":"trace[2146808053] linearizableReadLoop","detail":"{readStateIndex:8112; appliedIndex:8110; }","duration":"270.949547ms","start":"2026-08-20T01:28:55.707681Z","end":"2026-08-20T01:28:55.978631Z","steps":["trace[2146808053] 'read index received' (duration: 197.141683ms)","trace[2146808053] 'applied index is now lower than readState.Index' (duration: 73.806624ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:28:55.978708Z","caller":"traceutil/trace.go:171","msg":"trace[1503023266] transaction","detail":"{read_only:false; response_revision:7840; number_of_response:1; }","duration":"132.076444ms","start":"2026-08-20T01:28:55.846604Z","end":"2026-08-20T01:28:55.978681Z","steps":["trace[1503023266] 'process raft request' (duration: 131.795626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:28:55.978876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.597897ms","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:7"} {"level":"warn","ts":"2026-08-20T01:28:55.978892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.192064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-20T01:28:55.978981Z","caller":"traceutil/trace.go:171","msg":"trace[758122968] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7840; }","duration":"271.313407ms","start":"2026-08-20T01:28:55.707646Z","end":"2026-08-20T01:28:55.97896Z","steps":["trace[758122968] 'agreement among raft nodes before linearized reading' (duration: 271.107712ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:28:55.97892Z","caller":"traceutil/trace.go:171","msg":"trace[1673430133] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:7840; }","duration":"162.69908ms","start":"2026-08-20T01:28:55.816209Z","end":"2026-08-20T01:28:55.978908Z","steps":["trace[1673430133] 'agreement among raft nodes before linearized reading' (duration: 162.584157ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:29:06.998676Z","caller":"traceutil/trace.go:171","msg":"trace[908522479] transaction","detail":"{read_only:false; response_revision:7903; number_of_response:1; }","duration":"113.922735ms","start":"2026-08-20T01:29:06.884726Z","end":"2026-08-20T01:29:06.998649Z","steps":["trace[908522479] 'process raft request' (duration: 95.921802ms)","trace[908522479] 'compare' (duration: 17.87148ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:29:08.18173Z","caller":"traceutil/trace.go:171","msg":"trace[44637201] transaction","detail":"{read_only:false; response_revision:7906; number_of_response:1; }","duration":"135.369785ms","start":"2026-08-20T01:29:08.046321Z","end":"2026-08-20T01:29:08.181691Z","steps":["trace[44637201] 'process raft request' (duration: 112.448184ms)","trace[44637201] 'compare' (duration: 22.820698ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:29:43.957579Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5972} {"level":"info","ts":"2026-08-20T01:29:44.063648Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5972,"took":"103.307789ms","hash":2898892872,"current-db-size-bytes":57532416,"current-db-size":"58 MB","current-db-size-in-use-bytes":22581248,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-20T01:29:44.063694Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2898892872,"revision":5972,"compact-revision":2816} {"level":"info","ts":"2026-08-20T01:29:48.711089Z","caller":"traceutil/trace.go:171","msg":"trace[506868520] transaction","detail":"{read_only:false; response_revision:8219; number_of_response:1; }","duration":"180.912ms","start":"2026-08-20T01:29:48.530139Z","end":"2026-08-20T01:29:48.711051Z","steps":["trace[506868520] 'process raft request' (duration: 180.665603ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:29:48.837031Z","caller":"traceutil/trace.go:171","msg":"trace[1550887504] transaction","detail":"{read_only:false; response_revision:8221; number_of_response:1; }","duration":"112.098675ms","start":"2026-08-20T01:29:48.724893Z","end":"2026-08-20T01:29:48.836991Z","steps":["trace[1550887504] 'process raft request' (duration: 109.472531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:29:48.837271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.067502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"warn","ts":"2026-08-20T01:29:48.837346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.957825ms","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-08-20T01:29:48.837402Z","caller":"traceutil/trace.go:171","msg":"trace[843948439] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8221; }","duration":"115.040648ms","start":"2026-08-20T01:29:48.72234Z","end":"2026-08-20T01:29:48.83738Z","steps":["trace[843948439] 'agreement among raft nodes before linearized reading' (duration: 114.865093ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:29:48.837407Z","caller":"traceutil/trace.go:171","msg":"trace[883622519] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:8221; }","duration":"113.268547ms","start":"2026-08-20T01:29:48.724103Z","end":"2026-08-20T01:29:48.837371Z","steps":["trace[883622519] 'agreement among raft nodes before linearized reading' (duration: 112.913467ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:29:48.901159Z","caller":"traceutil/trace.go:171","msg":"trace[1374144107] transaction","detail":"{read_only:false; response_revision:8222; number_of_response:1; }","duration":"134.753799ms","start":"2026-08-20T01:29:48.766384Z","end":"2026-08-20T01:29:48.901138Z","steps":["trace[1374144107] 'process raft request' (duration: 134.570643ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:29:48.901349Z","caller":"traceutil/trace.go:171","msg":"trace[515443741] transaction","detail":"{read_only:false; response_revision:8223; number_of_response:1; }","duration":"106.420596ms","start":"2026-08-20T01:29:48.794911Z","end":"2026-08-20T01:29:48.901332Z","steps":["trace[515443741] 'process raft request' (duration: 106.165949ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:29:48.901673Z","caller":"traceutil/trace.go:171","msg":"trace[1556717494] transaction","detail":"{read_only:false; response_revision:8224; number_of_response:1; }","duration":"104.028709ms","start":"2026-08-20T01:29:48.79763Z","end":"2026-08-20T01:29:48.901659Z","steps":["trace[1556717494] 'process raft request' (duration: 103.614088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:29:48.901732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.821179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bjbfg\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-08-20T01:29:48.901886Z","caller":"traceutil/trace.go:171","msg":"trace[2046756868] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bjbfg; range_end:; response_count:1; response_revision:8225; }","duration":"106.024965ms","start":"2026-08-20T01:29:48.795842Z","end":"2026-08-20T01:29:48.901867Z","steps":["trace[2046756868] 'agreement among raft nodes before linearized reading' (duration: 105.741427ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:29:49.994063Z","caller":"traceutil/trace.go:171","msg":"trace[2115197192] transaction","detail":"{read_only:false; response_revision:8229; number_of_response:1; }","duration":"267.600513ms","start":"2026-08-20T01:29:49.726423Z","end":"2026-08-20T01:29:49.994023Z","steps":["trace[2115197192] 'process raft request' (duration: 241.878814ms)","trace[2115197192] 'compare' (duration: 25.03943ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:29:50.475355Z","caller":"traceutil/trace.go:171","msg":"trace[1025688942] transaction","detail":"{read_only:false; response_revision:8233; number_of_response:1; }","duration":"108.679569ms","start":"2026-08-20T01:29:50.366638Z","end":"2026-08-20T01:29:50.475317Z","steps":["trace[1025688942] 'process raft request' (duration: 108.396802ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:30:49.992721Z","caller":"traceutil/trace.go:171","msg":"trace[537898665] linearizableReadLoop","detail":"{readStateIndex:9135; appliedIndex:9134; }","duration":"135.816169ms","start":"2026-08-20T01:30:49.856881Z","end":"2026-08-20T01:30:49.992697Z","steps":["trace[537898665] 'read index received' (duration: 135.419058ms)","trace[537898665] 'applied index is now lower than readState.Index' (duration: 395.971µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:30:49.99284Z","caller":"traceutil/trace.go:171","msg":"trace[434912301] transaction","detail":"{read_only:false; response_revision:8827; number_of_response:1; }","duration":"145.12211ms","start":"2026-08-20T01:30:49.847693Z","end":"2026-08-20T01:30:49.992815Z","steps":["trace[434912301] 'process raft request' (duration: 144.78471ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:30:49.992933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.024305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:30:49.993023Z","caller":"traceutil/trace.go:171","msg":"trace[639703624] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:8827; }","duration":"136.166719ms","start":"2026-08-20T01:30:49.856837Z","end":"2026-08-20T01:30:49.993004Z","steps":["trace[639703624] 'agreement among raft nodes before linearized reading' (duration: 135.992704ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:30:57.781935Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023f8.wal"} {"level":"info","ts":"2026-08-20T01:32:29.849859Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-20T01:32:29.855603Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-20T01:32:29.855721Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-20T01:34:43.968283Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8181} {"level":"info","ts":"2026-08-20T01:34:44.045368Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8181,"took":"74.726897ms","hash":281321174,"current-db-size-bytes":57532416,"current-db-size":"58 MB","current-db-size-in-use-bytes":26615808,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-20T01:34:44.04545Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":281321174,"revision":8181,"compact-revision":5972} {"level":"info","ts":"2026-08-20T01:39:43.974663Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10882} {"level":"info","ts":"2026-08-20T01:39:44.056336Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10882,"took":"79.10994ms","hash":2078798959,"current-db-size-bytes":57532416,"current-db-size":"58 MB","current-db-size-in-use-bytes":27365376,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-20T01:39:44.056458Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2078798959,"revision":10882,"compact-revision":8181} {"level":"info","ts":"2026-08-20T01:41:16.682512Z","caller":"traceutil/trace.go:171","msg":"trace[530918394] transaction","detail":"{read_only:false; response_revision:13443; number_of_response:1; }","duration":"109.076036ms","start":"2026-08-20T01:41:16.573337Z","end":"2026-08-20T01:41:16.682413Z","steps":["trace[530918394] 'process raft request' (duration: 108.914461ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:41:49.805921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.360322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-20T01:41:49.806019Z","caller":"traceutil/trace.go:171","msg":"trace[169522059] linearizableReadLoop","detail":"{readStateIndex:14096; appliedIndex:14095; }","duration":"265.036522ms","start":"2026-08-20T01:41:49.540967Z","end":"2026-08-20T01:41:49.806003Z","steps":["trace[169522059] 'read index received' (duration: 60.501µs)","trace[169522059] 'applied index is now lower than readState.Index' (duration: 264.974691ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:41:49.806268Z","caller":"traceutil/trace.go:171","msg":"trace[1582844724] transaction","detail":"{read_only:false; response_revision:13625; number_of_response:1; }","duration":"357.016381ms","start":"2026-08-20T01:41:49.449209Z","end":"2026-08-20T01:41:49.806226Z","steps":["trace[1582844724] 'process raft request' (duration: 53.278758ms)","trace[1582844724] 'compare' (duration: 303.072495ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:41:49.806328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:41:49.449186Z","time spent":"357.110393ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:41:49.806514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.546847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups/\" range_end:\"/registry/ceph.rook.io/cephfilesystemsubvolumegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-20T01:41:49.806546Z","caller":"traceutil/trace.go:171","msg":"trace[1609814641] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemsubvolumegroups/; range_end:/registry/ceph.rook.io/cephfilesystemsubvolumegroups0; response_count:0; response_revision:13625; }","duration":"265.603798ms","start":"2026-08-20T01:41:49.540931Z","end":"2026-08-20T01:41:49.806534Z","steps":["trace[1609814641] 'agreement among raft nodes before linearized reading' (duration: 265.547896ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:41:49.806729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.917192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-20T01:41:49.80682Z","caller":"traceutil/trace.go:171","msg":"trace[828519440] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:13625; }","duration":"162.066846ms","start":"2026-08-20T01:41:49.64473Z","end":"2026-08-20T01:41:49.806797Z","steps":["trace[828519440] 'agreement among raft nodes before linearized reading' (duration: 161.895531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:41:49.806856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.165858ms","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-08-20T01:41:49.806894Z","caller":"traceutil/trace.go:171","msg":"trace[1623419967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13625; }","duration":"148.224919ms","start":"2026-08-20T01:41:49.658659Z","end":"2026-08-20T01:41:49.806884Z","steps":["trace[1623419967] 'agreement among raft nodes before linearized reading' (duration: 148.114276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:41:49.806912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.995953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-20T01:41:49.807001Z","caller":"traceutil/trace.go:171","msg":"trace[907360711] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:13625; }","duration":"148.148628ms","start":"2026-08-20T01:41:49.65883Z","end":"2026-08-20T01:41:49.806979Z","steps":["trace[907360711] 'agreement among raft nodes before linearized reading' (duration: 147.86343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:41:52.304663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.551804ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-20T01:41:52.304803Z","caller":"traceutil/trace.go:171","msg":"trace[1917172061] linearizableReadLoop","detail":"{readStateIndex:14119; appliedIndex:14118; }","duration":"176.974332ms","start":"2026-08-20T01:41:52.127783Z","end":"2026-08-20T01:41:52.304758Z","steps":["trace[1917172061] 'read index received' (duration: 21.259804ms)","trace[1917172061] 'applied index is now lower than readState.Index' (duration: 155.713048ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:41:52.305135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.335652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bjbfg\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-08-20T01:41:52.30517Z","caller":"traceutil/trace.go:171","msg":"trace[1250061096] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bjbfg; range_end:; response_count:1; response_revision:13647; }","duration":"177.399075ms","start":"2026-08-20T01:41:52.127759Z","end":"2026-08-20T01:41:52.305158Z","steps":["trace[1250061096] 'agreement among raft nodes before linearized reading' (duration: 177.253781ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:41:58.65828Z","caller":"traceutil/trace.go:171","msg":"trace[123479911] transaction","detail":"{read_only:false; response_revision:13786; number_of_response:1; }","duration":"203.151943ms","start":"2026-08-20T01:41:58.455078Z","end":"2026-08-20T01:41:58.65823Z","steps":["trace[123479911] 'process raft request' (duration: 202.942727ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:41:58.884297Z","caller":"traceutil/trace.go:171","msg":"trace[364420324] transaction","detail":"{read_only:false; response_revision:13788; number_of_response:1; }","duration":"220.606891ms","start":"2026-08-20T01:41:58.663634Z","end":"2026-08-20T01:41:58.88424Z","steps":["trace[364420324] 'process raft request' (duration: 220.528819ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:41:58.884523Z","caller":"traceutil/trace.go:171","msg":"trace[1536545438] transaction","detail":"{read_only:false; response_revision:13787; number_of_response:1; }","duration":"278.159558ms","start":"2026-08-20T01:41:58.606299Z","end":"2026-08-20T01:41:58.884459Z","steps":["trace[1536545438] 'process raft request' (duration: 181.801797ms)","trace[1536545438] 'compare' (duration: 95.869118ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:41:58.884546Z","caller":"traceutil/trace.go:171","msg":"trace[626829745] linearizableReadLoop","detail":"{readStateIndex:14261; appliedIndex:14260; }","duration":"226.30998ms","start":"2026-08-20T01:41:58.658188Z","end":"2026-08-20T01:41:58.884498Z","steps":["trace[626829745] 'read index received' (duration: 130.025192ms)","trace[626829745] 'applied index is now lower than readState.Index' (duration: 96.280388ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:41:58.88492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.739896ms","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-08-20T01:41:58.885019Z","caller":"traceutil/trace.go:171","msg":"trace[28934768] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:13788; }","duration":"306.903211ms","start":"2026-08-20T01:41:58.578094Z","end":"2026-08-20T01:41:58.884997Z","steps":["trace[28934768] 'agreement among raft nodes before linearized reading' (duration: 306.638104ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:41:58.885065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:41:58.578069Z","time spent":"306.987064ms","remote":"127.0.0.1:51770","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-08-20T01:41:58.884886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.448256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18831"} {"level":"info","ts":"2026-08-20T01:41:58.88529Z","caller":"traceutil/trace.go:171","msg":"trace[2106193636] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:13788; }","duration":"302.886919ms","start":"2026-08-20T01:41:58.582319Z","end":"2026-08-20T01:41:58.885206Z","steps":["trace[2106193636] 'agreement among raft nodes before linearized reading' (duration: 302.18764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:41:58.885979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:41:58.582311Z","time spent":"303.62715ms","remote":"127.0.0.1:51690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18853,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-20T01:44:43.980437Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12887} {"level":"info","ts":"2026-08-20T01:44:44.056203Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12887,"took":"71.658483ms","hash":2596797956,"current-db-size-bytes":57532416,"current-db-size":"58 MB","current-db-size-in-use-bytes":30388224,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-20T01:44:44.056248Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2596797956,"revision":12887,"compact-revision":10882} {"level":"warn","ts":"2026-08-20T01:48:17.199105Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022559432161232,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T01:48:17.699967Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022559432161232,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-20T01:48:17.980373Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.343446774s","expected-duration":"1s"} {"level":"info","ts":"2026-08-20T01:48:17.980797Z","caller":"traceutil/trace.go:171","msg":"trace[1315214726] linearizableReadLoop","detail":"{readStateIndex:17567; appliedIndex:17566; }","duration":"1.281841984s","start":"2026-08-20T01:48:16.698932Z","end":"2026-08-20T01:48:17.980774Z","steps":["trace[1315214726] 'read index received' (duration: 1.28166549s)","trace[1315214726] 'applied index is now lower than readState.Index' (duration: 175.694µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:48:17.980934Z","caller":"traceutil/trace.go:171","msg":"trace[592285179] transaction","detail":"{read_only:false; response_revision:16986; number_of_response:1; }","duration":"1.344103603s","start":"2026-08-20T01:48:16.63682Z","end":"2026-08-20T01:48:17.980923Z","steps":["trace[592285179] 'process raft request' (duration: 1.343819175s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:17.981079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:16.636795Z","time spent":"1.344198935s","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:48:17.981214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.989803ms","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-08-20T01:48:17.981275Z","caller":"traceutil/trace.go:171","msg":"trace[232456918] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:16986; }","duration":"291.069505ms","start":"2026-08-20T01:48:17.690191Z","end":"2026-08-20T01:48:17.98126Z","steps":["trace[232456918] 'agreement among raft nodes before linearized reading' (duration: 290.945122ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:17.981393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.282470222s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-20T01:48:17.981425Z","caller":"traceutil/trace.go:171","msg":"trace[1783024077] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:16986; }","duration":"1.282532533s","start":"2026-08-20T01:48:16.698881Z","end":"2026-08-20T01:48:17.981413Z","steps":["trace[1783024077] 'agreement among raft nodes before linearized reading' (duration: 1.282474752s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:17.98145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:16.698855Z","time spent":"1.282588215s","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-20T01:48:17.981605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.742039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/nfd.k8s-sigs.io/nodefeaturerules/\" range_end:\"/registry/nfd.k8s-sigs.io/nodefeaturerules0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T01:48:17.981633Z","caller":"traceutil/trace.go:171","msg":"trace[364055381] range","detail":"{range_begin:/registry/nfd.k8s-sigs.io/nodefeaturerules/; range_end:/registry/nfd.k8s-sigs.io/nodefeaturerules0; response_count:0; response_revision:16986; }","duration":"202.803541ms","start":"2026-08-20T01:48:17.778822Z","end":"2026-08-20T01:48:17.981626Z","steps":["trace[364055381] 'agreement among raft nodes before linearized reading' (duration: 202.75338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:17.981685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.801259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-kbfjk\" ","response":"range_response_count:1 size:22121"} {"level":"info","ts":"2026-08-20T01:48:17.981758Z","caller":"traceutil/trace.go:171","msg":"trace[1691561372] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-kbfjk; range_end:; response_count:1; response_revision:16986; }","duration":"160.897132ms","start":"2026-08-20T01:48:17.820846Z","end":"2026-08-20T01:48:17.981743Z","steps":["trace[1691561372] 'agreement among raft nodes before linearized reading' (duration: 160.685296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:17.98201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.433094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-kbfjk\" ","response":"range_response_count:1 size:22121"} {"level":"info","ts":"2026-08-20T01:48:17.982088Z","caller":"traceutil/trace.go:171","msg":"trace[502557530] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-kbfjk; range_end:; response_count:1; response_revision:16986; }","duration":"148.526427ms","start":"2026-08-20T01:48:17.833551Z","end":"2026-08-20T01:48:17.982077Z","steps":["trace[502557530] 'agreement among raft nodes before linearized reading' (duration: 148.389553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:17.982047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.776309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-08-20T01:48:17.982187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.175965879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:699"} {"level":"info","ts":"2026-08-20T01:48:17.982197Z","caller":"traceutil/trace.go:171","msg":"trace[123933279] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:16986; }","duration":"294.927943ms","start":"2026-08-20T01:48:17.687234Z","end":"2026-08-20T01:48:17.982162Z","steps":["trace[123933279] 'agreement among raft nodes before linearized reading' (duration: 294.748428ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:48:17.98226Z","caller":"traceutil/trace.go:171","msg":"trace[1454618679] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:16986; }","duration":"1.176061901s","start":"2026-08-20T01:48:16.806179Z","end":"2026-08-20T01:48:17.982241Z","steps":["trace[1454618679] 'agreement among raft nodes before linearized reading' (duration: 1.175882416s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:17.982178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.427487ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-server-9bb45cf48-2h22g\" ","response":"range_response_count:1 size:12297"} {"level":"warn","ts":"2026-08-20T01:48:17.982118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.578143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-4rt2n\" ","response":"range_response_count:1 size:15431"} {"level":"warn","ts":"2026-08-20T01:48:17.982296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:16.80616Z","time spent":"1.176129693s","remote":"127.0.0.1:51680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":721,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"info","ts":"2026-08-20T01:48:17.982387Z","caller":"traceutil/trace.go:171","msg":"trace[135583443] range","detail":"{range_begin:/registry/pods/openstack/neutron-server-9bb45cf48-2h22g; range_end:; response_count:1; response_revision:16986; }","duration":"775.636223ms","start":"2026-08-20T01:48:17.206689Z","end":"2026-08-20T01:48:17.982325Z","steps":["trace[135583443] 'agreement among raft nodes before linearized reading' (duration: 775.282943ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:17.982515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.20667Z","time spent":"775.770017ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":12319,"request content":"key:\"/registry/pods/openstack/neutron-server-9bb45cf48-2h22g\" "} {"level":"info","ts":"2026-08-20T01:48:17.982364Z","caller":"traceutil/trace.go:171","msg":"trace[1192210247] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-metadata-agent-default-4rt2n; range_end:; response_count:1; response_revision:16986; }","duration":"323.852651ms","start":"2026-08-20T01:48:17.658502Z","end":"2026-08-20T01:48:17.982355Z","steps":["trace[1192210247] 'agreement among raft nodes before linearized reading' (duration: 323.571523ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:17.982638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.658482Z","time spent":"324.143959ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":15453,"request content":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-4rt2n\" "} {"level":"warn","ts":"2026-08-20T01:48:17.98288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.275871537s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"info","ts":"2026-08-20T01:48:17.982915Z","caller":"traceutil/trace.go:171","msg":"trace[495043025] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:16986; }","duration":"1.2777373s","start":"2026-08-20T01:48:16.705168Z","end":"2026-08-20T01:48:17.982905Z","steps":["trace[495043025] 'agreement among raft nodes before linearized reading' (duration: 1.275798716s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:17.982914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035278771s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:137"} {"level":"warn","ts":"2026-08-20T01:48:17.982936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:16.705157Z","time spent":"1.277773501s","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-08-20T01:48:17.982997Z","caller":"traceutil/trace.go:171","msg":"trace[1341983846] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:16986; }","duration":"1.035448296s","start":"2026-08-20T01:48:16.947507Z","end":"2026-08-20T01:48:17.982955Z","steps":["trace[1341983846] 'agreement among raft nodes before linearized reading' (duration: 1.034742377s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:17.98304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:16.947483Z","time spent":"1.035547918s","remote":"127.0.0.1:51572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.204.45.38\" "} {"level":"warn","ts":"2026-08-20T01:48:18.342895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.98838Z","time spent":"354.508516ms","remote":"127.0.0.1:51572","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-20T01:48:18.346259Z","caller":"traceutil/trace.go:171","msg":"trace[699971277] transaction","detail":"{read_only:false; response_revision:16989; number_of_response:1; }","duration":"355.501774ms","start":"2026-08-20T01:48:17.990742Z","end":"2026-08-20T01:48:18.346243Z","steps":["trace[699971277] 'process raft request' (duration: 355.424102ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:48:18.346364Z","caller":"traceutil/trace.go:171","msg":"trace[2075890936] linearizableReadLoop","detail":"{readStateIndex:17571; appliedIndex:17567; }","duration":"355.170415ms","start":"2026-08-20T01:48:17.991181Z","end":"2026-08-20T01:48:18.346351Z","steps":["trace[2075890936] 'read index received' (duration: 348.971302ms)","trace[2075890936] 'applied index is now lower than readState.Index' (duration: 6.198123ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:48:18.346431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.990737Z","time spent":"355.592056ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:48:18.346512Z","caller":"traceutil/trace.go:171","msg":"trace[1109419531] transaction","detail":"{read_only:false; response_revision:16990; number_of_response:1; }","duration":"354.235128ms","start":"2026-08-20T01:48:17.992268Z","end":"2026-08-20T01:48:18.346503Z","steps":["trace[1109419531] 'process raft request' (duration: 353.94888ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.346558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.992258Z","time spent":"354.27101ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:48:18.346716Z","caller":"traceutil/trace.go:171","msg":"trace[1014804135] transaction","detail":"{read_only:false; response_revision:16991; number_of_response:1; }","duration":"348.90864ms","start":"2026-08-20T01:48:17.9978Z","end":"2026-08-20T01:48:18.346708Z","steps":["trace[1014804135] 'process raft request' (duration: 348.479998ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:48:18.346727Z","caller":"traceutil/trace.go:171","msg":"trace[1758012437] transaction","detail":"{read_only:false; response_revision:16987; number_of_response:1; }","duration":"356.79125ms","start":"2026-08-20T01:48:17.989927Z","end":"2026-08-20T01:48:18.346718Z","steps":["trace[1758012437] 'process raft request' (duration: 355.990818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.346752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.99778Z","time spent":"348.952242ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12557,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:48:18.346773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.989908Z","time spent":"356.839211ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T01:48:18.346839Z","caller":"traceutil/trace.go:171","msg":"trace[1786516884] transaction","detail":"{read_only:false; response_revision:16992; number_of_response:1; }","duration":"174.735457ms","start":"2026-08-20T01:48:18.172096Z","end":"2026-08-20T01:48:18.346831Z","steps":["trace[1786516884] 'process raft request' (duration: 174.229973ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:48:18.346863Z","caller":"traceutil/trace.go:171","msg":"trace[682892274] transaction","detail":"{read_only:false; response_revision:16988; number_of_response:1; }","duration":"356.175303ms","start":"2026-08-20T01:48:17.990681Z","end":"2026-08-20T01:48:18.346857Z","steps":["trace[682892274] 'process raft request' (duration: 355.452513ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.346896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.990663Z","time spent":"356.215134ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T01:48:18.347743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.779644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:92 size:897760"} {"level":"info","ts":"2026-08-20T01:48:18.347814Z","caller":"traceutil/trace.go:171","msg":"trace[1867438039] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:92; response_revision:16993; }","duration":"354.866846ms","start":"2026-08-20T01:48:17.992927Z","end":"2026-08-20T01:48:18.347794Z","steps":["trace[1867438039] 'agreement among raft nodes before linearized reading' (duration: 353.701374ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.347853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.992921Z","time spent":"354.920688ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":92,"response size":897782,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-08-20T01:48:18.34788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.695587ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:92 size:897760"} {"level":"info","ts":"2026-08-20T01:48:18.347911Z","caller":"traceutil/trace.go:171","msg":"trace[500457858] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:92; response_revision:16993; }","duration":"356.763979ms","start":"2026-08-20T01:48:17.991138Z","end":"2026-08-20T01:48:18.347902Z","steps":["trace[500457858] 'agreement among raft nodes before linearized reading' (duration: 355.787792ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.347933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.991128Z","time spent":"356.7977ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":92,"response size":897782,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"warn","ts":"2026-08-20T01:48:18.34823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.929894ms","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-08-20T01:48:18.348262Z","caller":"traceutil/trace.go:171","msg":"trace[700519189] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:16993; }","duration":"213.041707ms","start":"2026-08-20T01:48:18.135214Z","end":"2026-08-20T01:48:18.348255Z","steps":["trace[700519189] 'agreement among raft nodes before linearized reading' (duration: 212.965285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.348439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.915819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-bjbfg\" ","response":"range_response_count:1 size:6521"} {"level":"info","ts":"2026-08-20T01:48:18.348459Z","caller":"traceutil/trace.go:171","msg":"trace[1921149060] range","detail":"{range_begin:/registry/pods/openstack/keepalived-bjbfg; range_end:; response_count:1; response_revision:16993; }","duration":"152.96343ms","start":"2026-08-20T01:48:18.195489Z","end":"2026-08-20T01:48:18.348452Z","steps":["trace[1921149060] 'agreement among raft nodes before linearized reading' (duration: 152.903678ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.348483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.961324ms","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-08-20T01:48:18.348518Z","caller":"traceutil/trace.go:171","msg":"trace[1471885031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:16993; }","duration":"276.029356ms","start":"2026-08-20T01:48:18.072479Z","end":"2026-08-20T01:48:18.348508Z","steps":["trace[1471885031] 'agreement among raft nodes before linearized reading' (duration: 275.926933ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.348589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.914902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-qwwzb\" ","response":"range_response_count:1 size:20650"} {"level":"info","ts":"2026-08-20T01:48:18.348609Z","caller":"traceutil/trace.go:171","msg":"trace[15052340] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-qwwzb; range_end:; response_count:1; response_revision:16993; }","duration":"203.935443ms","start":"2026-08-20T01:48:18.144667Z","end":"2026-08-20T01:48:18.348603Z","steps":["trace[15052340] 'agreement among raft nodes before linearized reading' (duration: 203.875081ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.34875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.374939ms","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-08-20T01:48:18.34877Z","caller":"traceutil/trace.go:171","msg":"trace[1187454607] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:16993; }","duration":"212.41448ms","start":"2026-08-20T01:48:18.136349Z","end":"2026-08-20T01:48:18.348764Z","steps":["trace[1187454607] 'agreement among raft nodes before linearized reading' (duration: 212.354739ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.349184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.475634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:92 size:897760"} {"level":"info","ts":"2026-08-20T01:48:18.349223Z","caller":"traceutil/trace.go:171","msg":"trace[1219285270] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:92; response_revision:16993; }","duration":"355.528335ms","start":"2026-08-20T01:48:17.993685Z","end":"2026-08-20T01:48:18.349213Z","steps":["trace[1219285270] 'agreement among raft nodes before linearized reading' (duration: 354.917458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:48:18.349252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T01:48:17.993679Z","time spent":"355.562726ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":92,"response size":897782,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-08-20T01:49:22.188483Z","caller":"traceutil/trace.go:171","msg":"trace[952152143] linearizableReadLoop","detail":"{readStateIndex:18227; appliedIndex:18226; }","duration":"125.446082ms","start":"2026-08-20T01:49:22.063011Z","end":"2026-08-20T01:49:22.188457Z","steps":["trace[952152143] 'read index received' (duration: 125.270667ms)","trace[952152143] 'applied index is now lower than readState.Index' (duration: 174.065µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:49:22.188629Z","caller":"traceutil/trace.go:171","msg":"trace[991306664] transaction","detail":"{read_only:false; response_revision:17630; number_of_response:1; }","duration":"268.63662ms","start":"2026-08-20T01:49:21.919946Z","end":"2026-08-20T01:49:22.188583Z","steps":["trace[991306664] 'process raft request' (duration: 268.255239ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:49:22.188829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.786902ms","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-08-20T01:49:22.18893Z","caller":"traceutil/trace.go:171","msg":"trace[1531947802] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:17630; }","duration":"125.932986ms","start":"2026-08-20T01:49:22.062969Z","end":"2026-08-20T01:49:22.188902Z","steps":["trace[1531947802] 'agreement among raft nodes before linearized reading' (duration: 125.648518ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:49:22.286116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.555897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-qwwzb\" ","response":"range_response_count:1 size:20752"} {"level":"info","ts":"2026-08-20T01:49:22.286234Z","caller":"traceutil/trace.go:171","msg":"trace[67368939] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-qwwzb; range_end:; response_count:1; response_revision:17631; }","duration":"134.70959ms","start":"2026-08-20T01:49:22.151502Z","end":"2026-08-20T01:49:22.286212Z","steps":["trace[67368939] 'agreement among raft nodes before linearized reading' (duration: 131.950203ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:49:43.986545Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15323} {"level":"info","ts":"2026-08-20T01:49:44.076404Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15323,"took":"87.493503ms","hash":2743192068,"current-db-size-bytes":57532416,"current-db-size":"58 MB","current-db-size-in-use-bytes":32534528,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-20T01:49:44.076459Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2743192068,"revision":15323,"compact-revision":12887} {"level":"info","ts":"2026-08-20T01:52:27.554079Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"331455d6226d3423","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-20T01:52:27.558818Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-20T01:52:27.558908Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-20T01:54:43.993097Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17867} {"level":"info","ts":"2026-08-20T01:54:44.099951Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17867,"took":"104.262953ms","hash":2792391101,"current-db-size-bytes":57532416,"current-db-size":"58 MB","current-db-size-in-use-bytes":31481856,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-20T01:54:44.100074Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2792391101,"revision":17867,"compact-revision":15323} {"level":"info","ts":"2026-08-20T01:55:50.572302Z","caller":"traceutil/trace.go:171","msg":"trace[894894603] linearizableReadLoop","detail":"{readStateIndex:21488; appliedIndex:21487; }","duration":"162.851279ms","start":"2026-08-20T01:55:50.409429Z","end":"2026-08-20T01:55:50.57228Z","steps":["trace[894894603] 'read index received' (duration: 162.715195ms)","trace[894894603] 'applied index is now lower than readState.Index' (duration: 134.944µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:55:50.572376Z","caller":"traceutil/trace.go:171","msg":"trace[581386912] transaction","detail":"{read_only:false; response_revision:20790; number_of_response:1; }","duration":"209.979807ms","start":"2026-08-20T01:55:50.362358Z","end":"2026-08-20T01:55:50.572337Z","steps":["trace[581386912] 'process raft request' (duration: 209.72973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:55:50.572514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.069876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T01:55:50.572759Z","caller":"traceutil/trace.go:171","msg":"trace[700018181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:20790; }","duration":"163.283372ms","start":"2026-08-20T01:55:50.409371Z","end":"2026-08-20T01:55:50.572654Z","steps":["trace[700018181] 'agreement among raft nodes before linearized reading' (duration: 163.051575ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:55:50.848106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.910685ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T01:55:50.84834Z","caller":"traceutil/trace.go:171","msg":"trace[138657207] linearizableReadLoop","detail":"{readStateIndex:21493; appliedIndex:21489; }","duration":"240.893012ms","start":"2026-08-20T01:55:50.607434Z","end":"2026-08-20T01:55:50.848327Z","steps":["trace[138657207] 'read index received' (duration: 93.605486ms)","trace[138657207] 'applied index is now lower than readState.Index' (duration: 147.286726ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:55:50.84832Z","caller":"traceutil/trace.go:171","msg":"trace[119669928] transaction","detail":"{read_only:false; response_revision:20792; number_of_response:1; }","duration":"270.083727ms","start":"2026-08-20T01:55:50.578207Z","end":"2026-08-20T01:55:50.848291Z","steps":["trace[119669928] 'process raft request' (duration: 122.895674ms)","trace[119669928] 'compare' (duration: 146.72101ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:55:50.848406Z","caller":"traceutil/trace.go:171","msg":"trace[1681341201] transaction","detail":"{read_only:false; response_revision:20795; number_of_response:1; }","duration":"259.482981ms","start":"2026-08-20T01:55:50.588916Z","end":"2026-08-20T01:55:50.848399Z","steps":["trace[1681341201] 'process raft request' (duration: 259.376268ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:55:50.848475Z","caller":"traceutil/trace.go:171","msg":"trace[1194267366] transaction","detail":"{read_only:false; response_revision:20794; number_of_response:1; }","duration":"270.156689ms","start":"2026-08-20T01:55:50.578296Z","end":"2026-08-20T01:55:50.848453Z","steps":["trace[1194267366] 'process raft request' (duration: 269.968494ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:55:50.848562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.131138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-20T01:55:50.84859Z","caller":"traceutil/trace.go:171","msg":"trace[277425196] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:20795; }","duration":"241.162499ms","start":"2026-08-20T01:55:50.60742Z","end":"2026-08-20T01:55:50.848583Z","steps":["trace[277425196] 'agreement among raft nodes before linearized reading' (duration: 241.066116ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:55:50.848619Z","caller":"traceutil/trace.go:171","msg":"trace[91130850] transaction","detail":"{read_only:false; response_revision:20793; number_of_response:1; }","duration":"270.304463ms","start":"2026-08-20T01:55:50.578283Z","end":"2026-08-20T01:55:50.848588Z","steps":["trace[91130850] 'process raft request' (duration: 269.911082ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:55:50.8487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.337084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"warn","ts":"2026-08-20T01:55:50.848726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.983651ms","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-08-20T01:55:50.848793Z","caller":"traceutil/trace.go:171","msg":"trace[1699984446] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:20795; }","duration":"202.487438ms","start":"2026-08-20T01:55:50.646286Z","end":"2026-08-20T01:55:50.848773Z","steps":["trace[1699984446] 'agreement among raft nodes before linearized reading' (duration: 202.252082ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:55:50.848805Z","caller":"traceutil/trace.go:171","msg":"trace[343493990] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:20795; }","duration":"184.106854ms","start":"2026-08-20T01:55:50.664679Z","end":"2026-08-20T01:55:50.848786Z","steps":["trace[343493990] 'agreement among raft nodes before linearized reading' (duration: 183.94548ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:55:50.848788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.461337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-20T01:55:50.848882Z","caller":"traceutil/trace.go:171","msg":"trace[1325147629] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:20795; }","duration":"149.59115ms","start":"2026-08-20T01:55:50.699267Z","end":"2026-08-20T01:55:50.848859Z","steps":["trace[1325147629] 'agreement among raft nodes before linearized reading' (duration: 149.323562ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:55:51.038236Z","caller":"traceutil/trace.go:171","msg":"trace[1821949396] transaction","detail":"{read_only:false; response_revision:20796; number_of_response:1; }","duration":"185.387491ms","start":"2026-08-20T01:55:50.852821Z","end":"2026-08-20T01:55:51.038209Z","steps":["trace[1821949396] 'process raft request' (duration: 157.591734ms)","trace[1821949396] 'compare' (duration: 27.678883ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:55:51.090448Z","caller":"traceutil/trace.go:171","msg":"trace[75535800] linearizableReadLoop","detail":"{readStateIndex:21495; appliedIndex:21493; }","duration":"101.840926ms","start":"2026-08-20T01:55:50.988584Z","end":"2026-08-20T01:55:51.090425Z","steps":["trace[75535800] 'read index received' (duration: 21.849601ms)","trace[75535800] 'applied index is now lower than readState.Index' (duration: 79.990415ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T01:55:51.090578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.98076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T01:55:51.090636Z","caller":"traceutil/trace.go:171","msg":"trace[1571007037] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:20797; }","duration":"102.094963ms","start":"2026-08-20T01:55:50.988531Z","end":"2026-08-20T01:55:51.090626Z","steps":["trace[1571007037] 'agreement among raft nodes before linearized reading' (duration: 101.97644ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:55:51.090619Z","caller":"traceutil/trace.go:171","msg":"trace[2059238741] transaction","detail":"{read_only:false; response_revision:20797; number_of_response:1; }","duration":"235.48171ms","start":"2026-08-20T01:55:50.855099Z","end":"2026-08-20T01:55:51.090581Z","steps":["trace[2059238741] 'process raft request' (duration: 235.218243ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:59:28.316783Z","caller":"traceutil/trace.go:171","msg":"trace[143904550] linearizableReadLoop","detail":"{readStateIndex:23871; appliedIndex:23870; }","duration":"115.085966ms","start":"2026-08-20T01:59:28.201673Z","end":"2026-08-20T01:59:28.316759Z","steps":["trace[143904550] 'read index received' (duration: 114.962352ms)","trace[143904550] 'applied index is now lower than readState.Index' (duration: 122.404µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T01:59:28.316958Z","caller":"traceutil/trace.go:171","msg":"trace[1053495623] transaction","detail":"{read_only:false; response_revision:23105; number_of_response:1; }","duration":"152.83735ms","start":"2026-08-20T01:59:28.164083Z","end":"2026-08-20T01:59:28.31692Z","steps":["trace[1053495623] 'process raft request' (duration: 152.499381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T01:59:28.31704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.358544ms","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-08-20T01:59:28.317088Z","caller":"traceutil/trace.go:171","msg":"trace[339070755] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23105; }","duration":"115.460917ms","start":"2026-08-20T01:59:28.201616Z","end":"2026-08-20T01:59:28.317077Z","steps":["trace[339070755] 'agreement among raft nodes before linearized reading' (duration: 115.253311ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T01:59:43.999869Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20361} {"level":"info","ts":"2026-08-20T01:59:44.11028Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20361,"took":"106.349491ms","hash":1998967985,"current-db-size-bytes":57532416,"current-db-size":"58 MB","current-db-size-in-use-bytes":42921984,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-08-20T01:59:44.110336Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1998967985,"revision":20361,"compact-revision":17867} {"level":"info","ts":"2026-08-20T02:02:56.837043Z","caller":"traceutil/trace.go:171","msg":"trace[797871081] transaction","detail":"{read_only:false; response_revision:25330; number_of_response:1; }","duration":"150.666337ms","start":"2026-08-20T02:02:56.686349Z","end":"2026-08-20T02:02:56.837016Z","steps":["trace[797871081] 'process raft request' (duration: 150.613426ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:02:56.837423Z","caller":"traceutil/trace.go:171","msg":"trace[1861721233] transaction","detail":"{read_only:false; response_revision:25329; number_of_response:1; }","duration":"221.6923ms","start":"2026-08-20T02:02:56.615677Z","end":"2026-08-20T02:02:56.837369Z","steps":["trace[1861721233] 'process raft request' (duration: 177.416644ms)","trace[1861721233] 'compare' (duration: 43.727931ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:01.515541Z","caller":"traceutil/trace.go:171","msg":"trace[1845080797] transaction","detail":"{read_only:false; response_revision:25375; number_of_response:1; }","duration":"101.916806ms","start":"2026-08-20T02:03:01.413612Z","end":"2026-08-20T02:03:01.515529Z","steps":["trace[1845080797] 'process raft request' (duration: 101.799173ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:01.515541Z","caller":"traceutil/trace.go:171","msg":"trace[2144000245] transaction","detail":"{read_only:false; response_revision:25374; number_of_response:1; }","duration":"167.610301ms","start":"2026-08-20T02:03:01.347906Z","end":"2026-08-20T02:03:01.515516Z","steps":["trace[2144000245] 'process raft request' (duration: 167.397765ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:01.515463Z","caller":"traceutil/trace.go:171","msg":"trace[1663492916] linearizableReadLoop","detail":"{readStateIndex:26196; appliedIndex:26194; }","duration":"110.627419ms","start":"2026-08-20T02:03:01.404815Z","end":"2026-08-20T02:03:01.515443Z","steps":["trace[1663492916] 'read index received' (duration: 40.11717ms)","trace[1663492916] 'applied index is now lower than readState.Index' (duration: 70.509479ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:01.548929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.078123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:01.549041Z","caller":"traceutil/trace.go:171","msg":"trace[1681348902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25375; }","duration":"144.237277ms","start":"2026-08-20T02:03:01.404777Z","end":"2026-08-20T02:03:01.549014Z","steps":["trace[1681348902] 'agreement among raft nodes before linearized reading' (duration: 110.927967ms)","trace[1681348902] 'range keys from in-memory index tree' (duration: 33.115865ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:01.609243Z","caller":"traceutil/trace.go:171","msg":"trace[127468000] transaction","detail":"{read_only:false; response_revision:25376; number_of_response:1; }","duration":"149.421103ms","start":"2026-08-20T02:03:01.459801Z","end":"2026-08-20T02:03:01.609222Z","steps":["trace[127468000] 'process raft request' (duration: 149.294599ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:02.362674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.83525ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-20T02:03:02.506363Z","caller":"traceutil/trace.go:171","msg":"trace[1336620156] linearizableReadLoop","detail":"{readStateIndex:26200; appliedIndex:26199; }","duration":"100.135456ms","start":"2026-08-20T02:03:02.4062Z","end":"2026-08-20T02:03:02.506336Z","steps":["trace[1336620156] 'read index received' (duration: 99.93086ms)","trace[1336620156] 'applied index is now lower than readState.Index' (duration: 200.476µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:02.506467Z","caller":"traceutil/trace.go:171","msg":"trace[374270695] transaction","detail":"{read_only:false; response_revision:25377; number_of_response:1; }","duration":"125.569537ms","start":"2026-08-20T02:03:02.380866Z","end":"2026-08-20T02:03:02.506436Z","steps":["trace[374270695] 'process raft request' (duration: 125.262728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:02.506596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.360372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:02.506642Z","caller":"traceutil/trace.go:171","msg":"trace[1678125765] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25377; }","duration":"100.435334ms","start":"2026-08-20T02:03:02.406195Z","end":"2026-08-20T02:03:02.50663Z","steps":["trace[1678125765] 'agreement among raft nodes before linearized reading' (duration: 100.324921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:02.507483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.081203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:02.507531Z","caller":"traceutil/trace.go:171","msg":"trace[1255460061] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:25377; }","duration":"101.172195ms","start":"2026-08-20T02:03:02.406346Z","end":"2026-08-20T02:03:02.507518Z","steps":["trace[1255460061] 'agreement among raft nodes before linearized reading' (duration: 100.597679ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:02.979207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.875039ms","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-08-20T02:03:02.979282Z","caller":"traceutil/trace.go:171","msg":"trace[897266130] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25378; }","duration":"114.977641ms","start":"2026-08-20T02:03:02.864288Z","end":"2026-08-20T02:03:02.979265Z","steps":["trace[897266130] 'range keys from in-memory index tree' (duration: 114.692613ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:02.979312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.805723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-20T02:03:02.979401Z","caller":"traceutil/trace.go:171","msg":"trace[146001897] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25378; }","duration":"120.941867ms","start":"2026-08-20T02:03:02.858438Z","end":"2026-08-20T02:03:02.97938Z","steps":["trace[146001897] 'range keys from in-memory index tree' (duration: 120.504615ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:03.39531Z","caller":"traceutil/trace.go:171","msg":"trace[1155053682] transaction","detail":"{read_only:false; response_revision:25381; number_of_response:1; }","duration":"409.876486ms","start":"2026-08-20T02:03:02.985411Z","end":"2026-08-20T02:03:03.395287Z","steps":["trace[1155053682] 'process raft request' (duration: 409.642609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:03.395455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:02.985402Z","time spent":"409.978008ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:03.395543Z","caller":"traceutil/trace.go:171","msg":"trace[751745024] transaction","detail":"{read_only:false; response_revision:25382; number_of_response:1; }","duration":"410.065441ms","start":"2026-08-20T02:03:02.98546Z","end":"2026-08-20T02:03:03.395526Z","steps":["trace[751745024] 'process raft request' (duration: 409.777803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:03.395635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:02.985447Z","time spent":"410.144633ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:03.395827Z","caller":"traceutil/trace.go:171","msg":"trace[1336977288] transaction","detail":"{read_only:false; response_revision:25383; number_of_response:1; }","duration":"362.976226ms","start":"2026-08-20T02:03:03.032837Z","end":"2026-08-20T02:03:03.395813Z","steps":["trace[1336977288] 'process raft request' (duration: 362.619166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:03.395899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:03.032816Z","time spent":"363.043878ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:03.395851Z","caller":"traceutil/trace.go:171","msg":"trace[1011397564] linearizableReadLoop","detail":"{readStateIndex:26205; appliedIndex:26201; }","duration":"364.881549ms","start":"2026-08-20T02:03:03.030944Z","end":"2026-08-20T02:03:03.395826Z","steps":["trace[1011397564] 'read index received' (duration: 15.221315ms)","trace[1011397564] 'applied index is now lower than readState.Index' (duration: 349.659274ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:03.396064Z","caller":"traceutil/trace.go:171","msg":"trace[1099115617] transaction","detail":"{read_only:false; response_revision:25384; number_of_response:1; }","duration":"361.645328ms","start":"2026-08-20T02:03:03.034408Z","end":"2026-08-20T02:03:03.396054Z","steps":["trace[1099115617] 'process raft request' (duration: 361.316299ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:03.396117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:03.034383Z","time spent":"361.70505ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:03.397299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.3649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-20T02:03:03.397639Z","caller":"traceutil/trace.go:171","msg":"trace[1212360025] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:25384; }","duration":"366.475753ms","start":"2026-08-20T02:03:03.030862Z","end":"2026-08-20T02:03:03.397338Z","steps":["trace[1212360025] 'agreement among raft nodes before linearized reading' (duration: 365.084504ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:03.397726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:03.030841Z","time spent":"366.841144ms","remote":"127.0.0.1:52018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":2761,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-08-20T02:03:03.414708Z","caller":"traceutil/trace.go:171","msg":"trace[365287967] transaction","detail":"{read_only:false; response_revision:25385; number_of_response:1; }","duration":"225.889088ms","start":"2026-08-20T02:03:03.188769Z","end":"2026-08-20T02:03:03.414658Z","steps":["trace[365287967] 'process raft request' (duration: 225.688552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:03.708328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.21913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:03.708527Z","caller":"traceutil/trace.go:171","msg":"trace[1675116084] linearizableReadLoop","detail":"{readStateIndex:26209; appliedIndex:26208; }","duration":"233.252373ms","start":"2026-08-20T02:03:03.475256Z","end":"2026-08-20T02:03:03.708508Z","steps":["trace[1675116084] 'read index received' (duration: 104.756074ms)","trace[1675116084] 'applied index is now lower than readState.Index' (duration: 128.494528ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:03.708574Z","caller":"traceutil/trace.go:171","msg":"trace[712086899] transaction","detail":"{read_only:false; response_revision:25386; number_of_response:1; }","duration":"252.416008ms","start":"2026-08-20T02:03:03.456131Z","end":"2026-08-20T02:03:03.708547Z","steps":["trace[712086899] 'process raft request' (duration: 123.861938ms)","trace[712086899] 'compare' (duration: 127.731367ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:03.708759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.482659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-20T02:03:03.708832Z","caller":"traceutil/trace.go:171","msg":"trace[992421351] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25386; }","duration":"233.613693ms","start":"2026-08-20T02:03:03.475202Z","end":"2026-08-20T02:03:03.708816Z","steps":["trace[992421351] 'agreement among raft nodes before linearized reading' (duration: 233.367936ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:04.102192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.464336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:04.102499Z","caller":"traceutil/trace.go:171","msg":"trace[641404176] transaction","detail":"{read_only:false; response_revision:25387; number_of_response:1; }","duration":"582.277749ms","start":"2026-08-20T02:03:03.520187Z","end":"2026-08-20T02:03:04.102465Z","steps":["trace[641404176] 'process raft request' (duration: 307.732872ms)","trace[641404176] 'compare' (duration: 273.033094ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:04.102688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:03.520173Z","time spent":"582.434714ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:04.102721Z","caller":"traceutil/trace.go:171","msg":"trace[1987062807] transaction","detail":"{read_only:false; response_revision:25388; number_of_response:1; }","duration":"489.267132ms","start":"2026-08-20T02:03:03.613435Z","end":"2026-08-20T02:03:04.102702Z","steps":["trace[1987062807] 'process raft request' (duration: 488.933603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:04.102869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:03.613416Z","time spent":"489.381236ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:04.103002Z","caller":"traceutil/trace.go:171","msg":"trace[1495018968] linearizableReadLoop","detail":"{readStateIndex:26211; appliedIndex:26209; }","duration":"394.389573ms","start":"2026-08-20T02:03:03.708586Z","end":"2026-08-20T02:03:04.102976Z","steps":["trace[1495018968] 'read index received' (duration: 119.344863ms)","trace[1495018968] 'applied index is now lower than readState.Index' (duration: 275.04366ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:04.103678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.446182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:35 size:293208"} {"level":"info","ts":"2026-08-20T02:03:04.103131Z","caller":"traceutil/trace.go:171","msg":"trace[1543111640] transaction","detail":"{read_only:false; response_revision:25389; number_of_response:1; }","duration":"347.653528ms","start":"2026-08-20T02:03:03.755463Z","end":"2026-08-20T02:03:04.103116Z","steps":["trace[1543111640] 'process raft request' (duration: 347.159204ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:04.103735Z","caller":"traceutil/trace.go:171","msg":"trace[1426596397] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:35; response_revision:25389; }","duration":"463.531693ms","start":"2026-08-20T02:03:03.64018Z","end":"2026-08-20T02:03:04.103712Z","steps":["trace[1426596397] 'agreement among raft nodes before linearized reading' (duration: 462.986538ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:04.103925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.408629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-08-20T02:03:04.103957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.593738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" ","response":"range_response_count:1 size:1862"} {"level":"info","ts":"2026-08-20T02:03:04.104008Z","caller":"traceutil/trace.go:171","msg":"trace[1090877237] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25390; }","duration":"391.530093ms","start":"2026-08-20T02:03:03.712459Z","end":"2026-08-20T02:03:04.103989Z","steps":["trace[1090877237] 'agreement among raft nodes before linearized reading' (duration: 391.254815ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:04.10404Z","caller":"traceutil/trace.go:171","msg":"trace[2108931324] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0; range_end:; response_count:1; response_revision:25390; }","duration":"544.729531ms","start":"2026-08-20T02:03:03.559295Z","end":"2026-08-20T02:03:04.104025Z","steps":["trace[2108931324] 'agreement among raft nodes before linearized reading' (duration: 544.118764ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:04.104097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:03.559249Z","time spent":"544.826993ms","remote":"127.0.0.1:51678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":1,"response size":1884,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-neutron-server-0\" "} {"level":"warn","ts":"2026-08-20T02:03:04.104381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.105724ms","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-08-20T02:03:04.104434Z","caller":"traceutil/trace.go:171","msg":"trace[638243226] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:25390; }","duration":"178.188396ms","start":"2026-08-20T02:03:03.926233Z","end":"2026-08-20T02:03:04.104421Z","steps":["trace[638243226] 'agreement among raft nodes before linearized reading' (duration: 178.051742ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:04.104555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.349075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-20T02:03:04.104615Z","caller":"traceutil/trace.go:171","msg":"trace[422677222] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25390; }","duration":"585.464708ms","start":"2026-08-20T02:03:03.519135Z","end":"2026-08-20T02:03:04.104599Z","steps":["trace[422677222] 'agreement among raft nodes before linearized reading' (duration: 584.255484ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:04.104668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:03.519115Z","time spent":"585.53581ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-20T02:03:04.104049Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:03.712446Z","time spent":"391.594305ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-20T02:03:04.105145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:03.640167Z","time spent":"463.776241ms","remote":"127.0.0.1:51952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":35,"response size":293230,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"warn","ts":"2026-08-20T02:03:04.103958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:03.755435Z","time spent":"348.346487ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:04.832061Z","caller":"traceutil/trace.go:171","msg":"trace[927065316] linearizableReadLoop","detail":"{readStateIndex:26215; appliedIndex:26214; }","duration":"314.296956ms","start":"2026-08-20T02:03:04.517746Z","end":"2026-08-20T02:03:04.832043Z","steps":["trace[927065316] 'read index received' (duration: 314.07057ms)","trace[927065316] 'applied index is now lower than readState.Index' (duration: 225.806µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:04.832125Z","caller":"traceutil/trace.go:171","msg":"trace[709244777] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"320.358396ms","start":"2026-08-20T02:03:04.511735Z","end":"2026-08-20T02:03:04.832093Z","steps":["trace[709244777] 'process raft request' (duration: 320.176301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:04.832302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:04.511716Z","time spent":"320.48565ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:04.832393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.626845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-08-20T02:03:04.832452Z","caller":"traceutil/trace.go:171","msg":"trace[1995351666] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:25392; }","duration":"314.736239ms","start":"2026-08-20T02:03:04.517703Z","end":"2026-08-20T02:03:04.832439Z","steps":["trace[1995351666] 'agreement among raft nodes before linearized reading' (duration: 314.439771ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:04.832485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:04.517684Z","time spent":"314.79275ms","remote":"127.0.0.1:51678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":1588,"request content":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" "} {"level":"warn","ts":"2026-08-20T02:03:05.40178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.995949ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:05.401899Z","caller":"traceutil/trace.go:171","msg":"trace[1900615505] transaction","detail":"{read_only:false; response_revision:25395; number_of_response:1; }","duration":"347.575126ms","start":"2026-08-20T02:03:05.054305Z","end":"2026-08-20T02:03:05.40188Z","steps":["trace[1900615505] 'process raft request' (duration: 197.371631ms)","trace[1900615505] 'compare' (duration: 149.578997ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:05.401969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:05.054291Z","time spent":"347.651379ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:05.618837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.174408ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:05.618922Z","caller":"traceutil/trace.go:171","msg":"trace[699145284] linearizableReadLoop","detail":"{readStateIndex:26219; appliedIndex:26218; }","duration":"215.627431ms","start":"2026-08-20T02:03:05.403279Z","end":"2026-08-20T02:03:05.618907Z","steps":["trace[699145284] 'read index received' (duration: 101.323409ms)","trace[699145284] 'applied index is now lower than readState.Index' (duration: 114.302732ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:05.618988Z","caller":"traceutil/trace.go:171","msg":"trace[503853624] transaction","detail":"{read_only:false; response_revision:25396; number_of_response:1; }","duration":"216.167236ms","start":"2026-08-20T02:03:05.40281Z","end":"2026-08-20T02:03:05.618977Z","steps":["trace[503853624] 'process raft request' (duration: 101.776632ms)","trace[503853624] 'compare' (duration: 114.029484ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:05.619312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.017992ms","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-08-20T02:03:05.619388Z","caller":"traceutil/trace.go:171","msg":"trace[1708117690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25396; }","duration":"216.155526ms","start":"2026-08-20T02:03:05.403216Z","end":"2026-08-20T02:03:05.619371Z","steps":["trace[1708117690] 'agreement among raft nodes before linearized reading' (duration: 215.95654ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:05.6861Z","caller":"traceutil/trace.go:171","msg":"trace[1827484228] transaction","detail":"{read_only:false; response_revision:25397; number_of_response:1; }","duration":"282.771526ms","start":"2026-08-20T02:03:05.403286Z","end":"2026-08-20T02:03:05.686058Z","steps":["trace[1827484228] 'process raft request' (duration: 282.481688ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:05.686161Z","caller":"traceutil/trace.go:171","msg":"trace[1951957483] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"278.702672ms","start":"2026-08-20T02:03:05.407453Z","end":"2026-08-20T02:03:05.686155Z","steps":["trace[1951957483] 'process raft request' (duration: 278.453115ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:05.686168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.072115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:05.686238Z","caller":"traceutil/trace.go:171","msg":"trace[1009960020] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25399; }","duration":"278.176748ms","start":"2026-08-20T02:03:05.408042Z","end":"2026-08-20T02:03:05.686219Z","steps":["trace[1009960020] 'agreement among raft nodes before linearized reading' (duration: 278.035964ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:05.686111Z","caller":"traceutil/trace.go:171","msg":"trace[2016778952] transaction","detail":"{read_only:false; response_revision:25399; number_of_response:1; }","duration":"266.148242ms","start":"2026-08-20T02:03:05.419942Z","end":"2026-08-20T02:03:05.68609Z","steps":["trace[2016778952] 'process raft request' (duration: 266.05883ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:05.686287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.345226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephblockpools/\" range_end:\"/registry/ceph.rook.io/cephblockpools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:05.686544Z","caller":"traceutil/trace.go:171","msg":"trace[1760727169] range","detail":"{range_begin:/registry/ceph.rook.io/cephblockpools/; range_end:/registry/ceph.rook.io/cephblockpools0; response_count:0; response_revision:25399; }","duration":"209.535822ms","start":"2026-08-20T02:03:05.476859Z","end":"2026-08-20T02:03:05.686395Z","steps":["trace[1760727169] 'agreement among raft nodes before linearized reading' (duration: 209.352597ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:16.36203Z","caller":"traceutil/trace.go:171","msg":"trace[1079301033] transaction","detail":"{read_only:false; response_revision:25478; number_of_response:1; }","duration":"104.718014ms","start":"2026-08-20T02:03:16.257293Z","end":"2026-08-20T02:03:16.362011Z","steps":["trace[1079301033] 'process raft request' (duration: 104.542209ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:18.771391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.696149ms","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-08-20T02:03:18.77149Z","caller":"traceutil/trace.go:171","msg":"trace[1754177059] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25492; }","duration":"405.851103ms","start":"2026-08-20T02:03:18.365613Z","end":"2026-08-20T02:03:18.771464Z","steps":["trace[1754177059] 'range keys from in-memory index tree' (duration: 405.587806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:18.771783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:18.365595Z","time spent":"406.172663ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-20T02:03:19.031467Z","caller":"traceutil/trace.go:171","msg":"trace[1589635507] transaction","detail":"{read_only:false; response_revision:25493; number_of_response:1; }","duration":"254.871547ms","start":"2026-08-20T02:03:18.776566Z","end":"2026-08-20T02:03:19.031438Z","steps":["trace[1589635507] 'process raft request' (duration: 254.678332ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:19.481982Z","caller":"traceutil/trace.go:171","msg":"trace[2112083483] linearizableReadLoop","detail":"{readStateIndex:26320; appliedIndex:26318; }","duration":"520.740831ms","start":"2026-08-20T02:03:18.961211Z","end":"2026-08-20T02:03:19.481952Z","steps":["trace[2112083483] 'read index received' (duration: 69.980564ms)","trace[2112083483] 'applied index is now lower than readState.Index' (duration: 450.759337ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:19.482026Z","caller":"traceutil/trace.go:171","msg":"trace[1423175475] transaction","detail":"{read_only:false; response_revision:25494; number_of_response:1; }","duration":"594.909763ms","start":"2026-08-20T02:03:18.887083Z","end":"2026-08-20T02:03:19.481993Z","steps":["trace[1423175475] 'process raft request' (duration: 594.715387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:19.482182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:18.887058Z","time spent":"595.042827ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:19.482248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.003788ms","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-08-20T02:03:19.482372Z","caller":"traceutil/trace.go:171","msg":"trace[1974090359] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:25494; }","duration":"521.194475ms","start":"2026-08-20T02:03:18.961155Z","end":"2026-08-20T02:03:19.482349Z","steps":["trace[1974090359] 'agreement among raft nodes before linearized reading' (duration: 520.888516ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:19.482445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:18.961125Z","time spent":"521.303617ms","remote":"127.0.0.1:51838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":125,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-08-20T02:03:19.872178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.957114ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:19.872631Z","caller":"traceutil/trace.go:171","msg":"trace[1203914099] transaction","detail":"{read_only:false; response_revision:25495; number_of_response:1; }","duration":"742.378741ms","start":"2026-08-20T02:03:19.130218Z","end":"2026-08-20T02:03:19.872596Z","steps":["trace[1203914099] 'process raft request' (duration: 464.909633ms)","trace[1203914099] 'compare' (duration: 276.594364ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:19.872738Z","caller":"traceutil/trace.go:171","msg":"trace[801071053] linearizableReadLoop","detail":"{readStateIndex:26321; appliedIndex:26320; }","duration":"390.625758ms","start":"2026-08-20T02:03:19.482078Z","end":"2026-08-20T02:03:19.872704Z","steps":["trace[801071053] 'read index received' (duration: 112.999345ms)","trace[801071053] 'applied index is now lower than readState.Index' (duration: 277.622493ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:19.87281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:19.130192Z","time spent":"742.521315ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:19.872861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.190701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:19.872924Z","caller":"traceutil/trace.go:171","msg":"trace[1354366319] transaction","detail":"{read_only:false; response_revision:25496; number_of_response:1; }","duration":"212.226477ms","start":"2026-08-20T02:03:19.660677Z","end":"2026-08-20T02:03:19.872904Z","steps":["trace[1354366319] 'process raft request' (duration: 211.799165ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:19.872967Z","caller":"traceutil/trace.go:171","msg":"trace[1467652876] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25495; }","duration":"465.338475ms","start":"2026-08-20T02:03:19.407602Z","end":"2026-08-20T02:03:19.87294Z","steps":["trace[1467652876] 'agreement among raft nodes before linearized reading' (duration: 465.138229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:19.873021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:19.407581Z","time spent":"465.432337ms","remote":"127.0.0.1:51544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-20T02:03:19.873011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.886917ms","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":"warn","ts":"2026-08-20T02:03:19.873014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.952026ms","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-08-20T02:03:19.873085Z","caller":"traceutil/trace.go:171","msg":"trace[1280839225] 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:25496; }","duration":"477.044031ms","start":"2026-08-20T02:03:19.396019Z","end":"2026-08-20T02:03:19.873063Z","steps":["trace[1280839225] 'agreement among raft nodes before linearized reading' (duration: 476.841756ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:19.873099Z","caller":"traceutil/trace.go:171","msg":"trace[1889725646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25496; }","duration":"695.07619ms","start":"2026-08-20T02:03:19.178005Z","end":"2026-08-20T02:03:19.873081Z","steps":["trace[1889725646] 'agreement among raft nodes before linearized reading' (duration: 694.877474ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:19.873139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.125131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-20T02:03:19.873147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:19.395997Z","time spent":"477.134374ms","remote":"127.0.0.1:35626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true "} {"level":"info","ts":"2026-08-20T02:03:19.873188Z","caller":"traceutil/trace.go:171","msg":"trace[166720744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25496; }","duration":"878.206843ms","start":"2026-08-20T02:03:18.99497Z","end":"2026-08-20T02:03:19.873177Z","steps":["trace[166720744] 'agreement among raft nodes before linearized reading' (duration: 878.131121ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:19.873206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.537229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-20T02:03:19.873234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:18.994951Z","time spent":"878.272156ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-20T02:03:19.873238Z","caller":"traceutil/trace.go:171","msg":"trace[293502043] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:25496; }","duration":"461.618721ms","start":"2026-08-20T02:03:19.41161Z","end":"2026-08-20T02:03:19.873228Z","steps":["trace[293502043] 'agreement among raft nodes before linearized reading' (duration: 461.567619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:19.873277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:19.411549Z","time spent":"461.720264ms","remote":"127.0.0.1:35590","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true "} {"level":"warn","ts":"2026-08-20T02:03:19.873275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.837597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-08-20T02:03:19.873143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:19.17799Z","time spent":"695.145462ms","remote":"127.0.0.1:51770","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-08-20T02:03:19.873322Z","caller":"traceutil/trace.go:171","msg":"trace[1532837338] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25496; }","duration":"413.918969ms","start":"2026-08-20T02:03:19.459391Z","end":"2026-08-20T02:03:19.87331Z","steps":["trace[1532837338] 'agreement among raft nodes before linearized reading' (duration: 413.834627ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:19.873374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:19.459372Z","time spent":"413.991431ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-20T02:03:19.884618Z","caller":"traceutil/trace.go:171","msg":"trace[1306852583] transaction","detail":"{read_only:false; response_revision:25497; number_of_response:1; }","duration":"153.108095ms","start":"2026-08-20T02:03:19.731497Z","end":"2026-08-20T02:03:19.884605Z","steps":["trace[1306852583] 'process raft request' (duration: 153.005553ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:19.884903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.498621ms","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-08-20T02:03:19.884934Z","caller":"traceutil/trace.go:171","msg":"trace[1220424662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25498; }","duration":"136.573273ms","start":"2026-08-20T02:03:19.748353Z","end":"2026-08-20T02:03:19.884926Z","steps":["trace[1220424662] 'agreement among raft nodes before linearized reading' (duration: 136.477531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:19.88497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.875806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-08-20T02:03:19.885003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.957209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-20T02:03:19.885025Z","caller":"traceutil/trace.go:171","msg":"trace[1086362699] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25498; }","duration":"144.934498ms","start":"2026-08-20T02:03:19.740078Z","end":"2026-08-20T02:03:19.885012Z","steps":["trace[1086362699] 'agreement among raft nodes before linearized reading' (duration: 144.791034ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:19.885071Z","caller":"traceutil/trace.go:171","msg":"trace[1186878774] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25498; }","duration":"299.086492ms","start":"2026-08-20T02:03:19.585967Z","end":"2026-08-20T02:03:19.885054Z","steps":["trace[1186878774] 'agreement among raft nodes before linearized reading' (duration: 298.903287ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:19.885288Z","caller":"traceutil/trace.go:171","msg":"trace[2077244947] transaction","detail":"{read_only:false; response_revision:25498; number_of_response:1; }","duration":"148.735423ms","start":"2026-08-20T02:03:19.736084Z","end":"2026-08-20T02:03:19.884819Z","steps":["trace[2077244947] 'process raft request' (duration: 148.493867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:20.385636Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022559432185610,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-20T02:03:20.393395Z","caller":"traceutil/trace.go:171","msg":"trace[1750815599] transaction","detail":"{read_only:false; response_revision:25499; number_of_response:1; }","duration":"514.606671ms","start":"2026-08-20T02:03:19.878765Z","end":"2026-08-20T02:03:20.393372Z","steps":["trace[1750815599] 'process raft request' (duration: 514.358864ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:20.393539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:19.878747Z","time spent":"514.704643ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:20.3936Z","caller":"traceutil/trace.go:171","msg":"trace[224660126] transaction","detail":"{read_only:false; response_revision:25500; number_of_response:1; }","duration":"514.122567ms","start":"2026-08-20T02:03:19.879449Z","end":"2026-08-20T02:03:20.393571Z","steps":["trace[224660126] 'process raft request' (duration: 513.823199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:20.393754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:19.87944Z","time spent":"514.22611ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:20.394044Z","caller":"traceutil/trace.go:171","msg":"trace[382159641] transaction","detail":"{read_only:false; response_revision:25501; number_of_response:1; }","duration":"514.204699ms","start":"2026-08-20T02:03:19.879824Z","end":"2026-08-20T02:03:20.394029Z","steps":["trace[382159641] 'process raft request' (duration: 513.707815ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:20.394165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:19.879817Z","time spent":"514.26724ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:20.702937Z","caller":"traceutil/trace.go:171","msg":"trace[28645794] linearizableReadLoop","detail":"{readStateIndex:26332; appliedIndex:26324; }","duration":"818.173888ms","start":"2026-08-20T02:03:19.88474Z","end":"2026-08-20T02:03:20.702913Z","steps":["trace[28645794] 'read index received' (duration: 508.427508ms)","trace[28645794] 'applied index is now lower than readState.Index' (duration: 309.745489ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:20.703478Z","caller":"traceutil/trace.go:171","msg":"trace[1980146805] transaction","detail":"{read_only:false; response_revision:25503; number_of_response:1; }","duration":"815.072771ms","start":"2026-08-20T02:03:19.888383Z","end":"2026-08-20T02:03:20.703456Z","steps":["trace[1980146805] 'process raft request' (duration: 814.399652ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:20.703475Z","caller":"traceutil/trace.go:171","msg":"trace[107892272] transaction","detail":"{read_only:false; response_revision:25506; number_of_response:1; }","duration":"489.487579ms","start":"2026-08-20T02:03:20.213953Z","end":"2026-08-20T02:03:20.70344Z","steps":["trace[107892272] 'process raft request' (duration: 488.928194ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:20.703526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"816.180391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-08-20T02:03:20.703522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.929213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:20.703559Z","caller":"traceutil/trace.go:171","msg":"trace[34732097] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25506; }","duration":"816.249573ms","start":"2026-08-20T02:03:19.887301Z","end":"2026-08-20T02:03:20.70355Z","steps":["trace[34732097] 'agreement among raft nodes before linearized reading' (duration: 816.13535ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:20.703571Z","caller":"traceutil/trace.go:171","msg":"trace[349584890] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:25506; }","duration":"600.019275ms","start":"2026-08-20T02:03:20.103538Z","end":"2026-08-20T02:03:20.703557Z","steps":["trace[349584890] 'agreement among raft nodes before linearized reading' (duration: 599.924563ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:20.703581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.444296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:03:20.703613Z","caller":"traceutil/trace.go:171","msg":"trace[1916838645] transaction","detail":"{read_only:false; response_revision:25505; number_of_response:1; }","duration":"491.153326ms","start":"2026-08-20T02:03:20.212455Z","end":"2026-08-20T02:03:20.703609Z","steps":["trace[1916838645] 'process raft request' (duration: 490.396595ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:20.703619Z","caller":"traceutil/trace.go:171","msg":"trace[34384452] transaction","detail":"{read_only:false; response_revision:25502; number_of_response:1; }","duration":"815.312527ms","start":"2026-08-20T02:03:19.888299Z","end":"2026-08-20T02:03:20.703611Z","steps":["trace[34384452] 'process raft request' (duration: 814.33839ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:20.703619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:20.10352Z","time spent":"600.089568ms","remote":"127.0.0.1:40366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"warn","ts":"2026-08-20T02:03:20.703592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.738496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"warn","ts":"2026-08-20T02:03:20.703644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:20.212414Z","time spent":"491.213487ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:20.703638Z","caller":"traceutil/trace.go:171","msg":"trace[2140733188] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25506; }","duration":"466.548628ms","start":"2026-08-20T02:03:20.237076Z","end":"2026-08-20T02:03:20.703625Z","steps":["trace[2140733188] 'agreement among raft nodes before linearized reading' (duration: 466.371413ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:20.703671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:20.237057Z","time spent":"466.60876ms","remote":"127.0.0.1:51680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-08-20T02:03:20.703639Z","caller":"traceutil/trace.go:171","msg":"trace[852756012] transaction","detail":"{read_only:false; response_revision:25504; number_of_response:1; }","duration":"814.123624ms","start":"2026-08-20T02:03:19.889495Z","end":"2026-08-20T02:03:20.703619Z","steps":["trace[852756012] 'process raft request' (duration: 813.325422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:20.703689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:19.88828Z","time spent":"815.362899ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:20.703688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.428485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-20T02:03:20.703673Z","caller":"traceutil/trace.go:171","msg":"trace[707819875] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25506; }","duration":"465.822088ms","start":"2026-08-20T02:03:20.237829Z","end":"2026-08-20T02:03:20.703651Z","steps":["trace[707819875] 'agreement among raft nodes before linearized reading' (duration: 465.638193ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:20.703699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.750666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:03:20.703775Z","caller":"traceutil/trace.go:171","msg":"trace[1016021970] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:25506; }","duration":"288.583329ms","start":"2026-08-20T02:03:20.415146Z","end":"2026-08-20T02:03:20.703755Z","steps":["trace[1016021970] 'agreement among raft nodes before linearized reading' (duration: 288.287591ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:20.703548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.965738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-20T02:03:20.70381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:20.237815Z","time spent":"465.944941ms","remote":"127.0.0.1:51680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-08-20T02:03:20.703862Z","caller":"traceutil/trace.go:171","msg":"trace[714289482] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25506; }","duration":"829.306478ms","start":"2026-08-20T02:03:19.87452Z","end":"2026-08-20T02:03:20.703827Z","steps":["trace[714289482] 'agreement among raft nodes before linearized reading' (duration: 828.930898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:20.703931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:19.874502Z","time spent":"829.415702ms","remote":"127.0.0.1:51528","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-20T02:03:20.703967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.099499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-20T02:03:20.703581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:19.887283Z","time spent":"816.292925ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-08-20T02:03:20.704Z","caller":"traceutil/trace.go:171","msg":"trace[2084638148] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:25506; }","duration":"826.157151ms","start":"2026-08-20T02:03:19.877835Z","end":"2026-08-20T02:03:20.703992Z","steps":["trace[2084638148] 'agreement among raft nodes before linearized reading' (duration: 826.087569ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:20.704035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:19.877823Z","time spent":"826.204452ms","remote":"127.0.0.1:51640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":19,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-08-20T02:03:20.704186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:20.213937Z","time spent":"490.195889ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:20.704029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:19.88948Z","time spent":"814.264088ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:20.703587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:19.888374Z","time spent":"815.156643ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:20.70383Z","caller":"traceutil/trace.go:171","msg":"trace[604341582] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25506; }","duration":"465.89547ms","start":"2026-08-20T02:03:20.237898Z","end":"2026-08-20T02:03:20.703794Z","steps":["trace[604341582] 'agreement among raft nodes before linearized reading' (duration: 465.5409ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:20.7045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:20.237884Z","time spent":"466.594489ms","remote":"127.0.0.1:51680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-20T02:03:21.165583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.933118ms","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":"warn","ts":"2026-08-20T02:03:21.165653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.778773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-20T02:03:21.165666Z","caller":"traceutil/trace.go:171","msg":"trace[1428375557] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25506; }","duration":"207.030221ms","start":"2026-08-20T02:03:20.958616Z","end":"2026-08-20T02:03:21.165646Z","steps":["trace[1428375557] 'count revisions from in-memory index tree' (duration: 206.818785ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:21.165709Z","caller":"traceutil/trace.go:171","msg":"trace[162680126] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25506; }","duration":"129.873996ms","start":"2026-08-20T02:03:21.03582Z","end":"2026-08-20T02:03:21.165694Z","steps":["trace[162680126] 'range keys from in-memory index tree' (duration: 129.63425ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:21.165739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.592064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/applicationcredentials/\" range_end:\"/registry/openstack.k-orc.cloud/applicationcredentials0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:21.165845Z","caller":"traceutil/trace.go:171","msg":"trace[838810007] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/applicationcredentials/; range_end:/registry/openstack.k-orc.cloud/applicationcredentials0; response_count:0; response_revision:25506; }","duration":"117.757378ms","start":"2026-08-20T02:03:21.04806Z","end":"2026-08-20T02:03:21.165817Z","steps":["trace[838810007] 'count revisions from in-memory index tree' (duration: 117.520031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:30.044029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.110905ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:30.044138Z","caller":"traceutil/trace.go:171","msg":"trace[164458586] transaction","detail":"{read_only:false; response_revision:25628; number_of_response:1; }","duration":"128.017185ms","start":"2026-08-20T02:03:29.916101Z","end":"2026-08-20T02:03:30.044118Z","steps":["trace[164458586] 'process raft request' (duration: 17.700784ms)","trace[164458586] 'compare' (duration: 109.886919ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:30.097194Z","caller":"traceutil/trace.go:171","msg":"trace[1786692089] transaction","detail":"{read_only:false; response_revision:25630; number_of_response:1; }","duration":"168.107474ms","start":"2026-08-20T02:03:29.929061Z","end":"2026-08-20T02:03:30.097169Z","steps":["trace[1786692089] 'process raft request' (duration: 168.033732ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:30.097189Z","caller":"traceutil/trace.go:171","msg":"trace[1794721729] transaction","detail":"{read_only:false; response_revision:25629; number_of_response:1; }","duration":"169.559745ms","start":"2026-08-20T02:03:29.9276Z","end":"2026-08-20T02:03:30.09716Z","steps":["trace[1794721729] 'process raft request' (duration: 169.37273ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:30.58463Z","caller":"traceutil/trace.go:171","msg":"trace[841558755] transaction","detail":"{read_only:false; response_revision:25631; number_of_response:1; }","duration":"148.700883ms","start":"2026-08-20T02:03:30.435904Z","end":"2026-08-20T02:03:30.584605Z","steps":["trace[841558755] 'process raft request' (duration: 148.537628ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:30.855497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.004524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:30.855581Z","caller":"traceutil/trace.go:171","msg":"trace[1577728650] linearizableReadLoop","detail":"{readStateIndex:26460; appliedIndex:26458; }","duration":"408.798615ms","start":"2026-08-20T02:03:30.44677Z","end":"2026-08-20T02:03:30.855568Z","steps":["trace[1577728650] 'read index received' (duration: 137.722175ms)","trace[1577728650] 'applied index is now lower than readState.Index' (duration: 271.0755ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:30.855643Z","caller":"traceutil/trace.go:171","msg":"trace[133728292] transaction","detail":"{read_only:false; response_revision:25632; number_of_response:1; }","duration":"416.984595ms","start":"2026-08-20T02:03:30.438631Z","end":"2026-08-20T02:03:30.855615Z","steps":["trace[133728292] 'process raft request' (duration: 288.795795ms)","trace[133728292] 'compare' (duration: 127.85999ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:30.855698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.93417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-20T02:03:30.855728Z","caller":"traceutil/trace.go:171","msg":"trace[556728007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25632; }","duration":"408.98167ms","start":"2026-08-20T02:03:30.446735Z","end":"2026-08-20T02:03:30.855717Z","steps":["trace[556728007] 'agreement among raft nodes before linearized reading' (duration: 408.884458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:30.855755Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:30.438608Z","time spent":"417.089887ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:30.855763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:30.44669Z","time spent":"409.064633ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-20T02:03:30.865843Z","caller":"traceutil/trace.go:171","msg":"trace[1862013076] transaction","detail":"{read_only:false; response_revision:25633; number_of_response:1; }","duration":"128.862638ms","start":"2026-08-20T02:03:30.736948Z","end":"2026-08-20T02:03:30.865811Z","steps":["trace[1862013076] 'process raft request' (duration: 128.678713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.111316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.70816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:31.111486Z","caller":"traceutil/trace.go:171","msg":"trace[1739598482] linearizableReadLoop","detail":"{readStateIndex:26462; appliedIndex:26460; }","duration":"255.872605ms","start":"2026-08-20T02:03:30.855598Z","end":"2026-08-20T02:03:31.111471Z","steps":["trace[1739598482] 'read index received' (duration: 9.990819ms)","trace[1739598482] 'applied index is now lower than readState.Index' (duration: 245.880766ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:31.111642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.409922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:574"} {"level":"warn","ts":"2026-08-20T02:03:31.111662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.37979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"warn","ts":"2026-08-20T02:03:31.111693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.688672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:03:31.111693Z","caller":"traceutil/trace.go:171","msg":"trace[1436156336] transaction","detail":"{read_only:false; response_revision:25634; number_of_response:1; }","duration":"371.080653ms","start":"2026-08-20T02:03:30.74059Z","end":"2026-08-20T02:03:31.111671Z","steps":["trace[1436156336] 'process raft request' (duration: 234.941581ms)","trace[1436156336] 'compare' (duration: 135.509984ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:31.111715Z","caller":"traceutil/trace.go:171","msg":"trace[27432477] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25635; }","duration":"376.467823ms","start":"2026-08-20T02:03:30.735235Z","end":"2026-08-20T02:03:31.111702Z","steps":["trace[27432477] 'agreement among raft nodes before linearized reading' (duration: 376.271027ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:31.11173Z","caller":"traceutil/trace.go:171","msg":"trace[262270306] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25635; }","duration":"379.765115ms","start":"2026-08-20T02:03:30.731952Z","end":"2026-08-20T02:03:31.111717Z","steps":["trace[262270306] 'agreement among raft nodes before linearized reading' (duration: 379.621431ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.111748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.696869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-08-20T02:03:31.111747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:30.735222Z","time spent":"376.518364ms","remote":"127.0.0.1:51680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-08-20T02:03:31.111767Z","caller":"traceutil/trace.go:171","msg":"trace[1162354047] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:25635; }","duration":"357.74368ms","start":"2026-08-20T02:03:30.754018Z","end":"2026-08-20T02:03:31.111762Z","steps":["trace[1162354047] 'agreement among raft nodes before linearized reading' (duration: 357.696749ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.111764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:30.731931Z","time spent":"379.823816ms","remote":"127.0.0.1:51680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-08-20T02:03:31.111752Z","caller":"traceutil/trace.go:171","msg":"trace[1000430348] transaction","detail":"{read_only:false; response_revision:25635; number_of_response:1; }","duration":"253.074157ms","start":"2026-08-20T02:03:30.858642Z","end":"2026-08-20T02:03:31.111716Z","steps":["trace[1000430348] 'process raft request' (duration: 252.768978ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.111782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:30.74057Z","time spent":"371.156424ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:31.11179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:30.754004Z","time spent":"357.780711ms","remote":"127.0.0.1:51678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":17,"response size":30,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-08-20T02:03:31.111786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.851573ms","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-08-20T02:03:31.111821Z","caller":"traceutil/trace.go:171","msg":"trace[618181519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25635; }","duration":"362.925665ms","start":"2026-08-20T02:03:30.748885Z","end":"2026-08-20T02:03:31.111811Z","steps":["trace[618181519] 'agreement among raft nodes before linearized reading' (duration: 362.832902ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.111843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:30.748866Z","time spent":"362.972085ms","remote":"127.0.0.1:51770","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":"info","ts":"2026-08-20T02:03:31.111696Z","caller":"traceutil/trace.go:171","msg":"trace[985068924] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:25635; }","duration":"361.507415ms","start":"2026-08-20T02:03:30.750178Z","end":"2026-08-20T02:03:31.111685Z","steps":["trace[985068924] 'agreement among raft nodes before linearized reading' (duration: 361.302579ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.111849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.891811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-08-20T02:03:31.111893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:30.750165Z","time spent":"361.717161ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"info","ts":"2026-08-20T02:03:31.111897Z","caller":"traceutil/trace.go:171","msg":"trace[1440779528] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25635; }","duration":"233.979414ms","start":"2026-08-20T02:03:30.877904Z","end":"2026-08-20T02:03:31.111884Z","steps":["trace[1440779528] 'agreement among raft nodes before linearized reading' (duration: 233.85109ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.111902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.321875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-08-20T02:03:31.111934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.689318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"warn","ts":"2026-08-20T02:03:31.111839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.905013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7628e4b9-c223-4514-bbf0-2098e14ed341\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-08-20T02:03:31.111959Z","caller":"traceutil/trace.go:171","msg":"trace[369096720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25635; }","duration":"363.409158ms","start":"2026-08-20T02:03:30.748535Z","end":"2026-08-20T02:03:31.111944Z","steps":["trace[369096720] 'agreement among raft nodes before linearized reading' (duration: 363.194492ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:31.11197Z","caller":"traceutil/trace.go:171","msg":"trace[1122388218] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25635; }","duration":"381.75645ms","start":"2026-08-20T02:03:30.730204Z","end":"2026-08-20T02:03:31.11196Z","steps":["trace[1122388218] 'agreement among raft nodes before linearized reading' (duration: 381.673597ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:31.111981Z","caller":"traceutil/trace.go:171","msg":"trace[229597655] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7628e4b9-c223-4514-bbf0-2098e14ed341; range_end:; response_count:1; response_revision:25635; }","duration":"407.079318ms","start":"2026-08-20T02:03:30.704887Z","end":"2026-08-20T02:03:31.111966Z","steps":["trace[229597655] 'agreement among raft nodes before linearized reading' (duration: 406.848752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.111987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:30.748524Z","time spent":"363.458039ms","remote":"127.0.0.1:51770","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-08-20T02:03:31.111998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:30.730186Z","time spent":"381.804772ms","remote":"127.0.0.1:51680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-20T02:03:31.112015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:30.704871Z","time spent":"407.134669ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3665,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7628e4b9-c223-4514-bbf0-2098e14ed341\" "} {"level":"warn","ts":"2026-08-20T02:03:31.481784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.563337ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:31.482015Z","caller":"traceutil/trace.go:171","msg":"trace[1194186937] linearizableReadLoop","detail":"{readStateIndex:26465; appliedIndex:26463; }","duration":"365.376443ms","start":"2026-08-20T02:03:31.116623Z","end":"2026-08-20T02:03:31.481999Z","steps":["trace[1194186937] 'read index received' (duration: 79.101659ms)","trace[1194186937] 'applied index is now lower than readState.Index' (duration: 286.273884ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:31.48211Z","caller":"traceutil/trace.go:171","msg":"trace[972176207] transaction","detail":"{read_only:false; response_revision:25639; number_of_response:1; }","duration":"363.253464ms","start":"2026-08-20T02:03:31.118847Z","end":"2026-08-20T02:03:31.4821Z","steps":["trace[972176207] 'process raft request' (duration: 363.09774ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.482177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:31.118826Z","time spent":"363.314315ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:31.482328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.681238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-08-20T02:03:31.482407Z","caller":"traceutil/trace.go:171","msg":"trace[1561861317] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25639; }","duration":"362.807011ms","start":"2026-08-20T02:03:31.119581Z","end":"2026-08-20T02:03:31.482388Z","steps":["trace[1561861317] 'agreement among raft nodes before linearized reading' (duration: 362.596175ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.482453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:31.119563Z","time spent":"362.881994ms","remote":"127.0.0.1:51714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1280,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" "} {"level":"info","ts":"2026-08-20T02:03:31.482424Z","caller":"traceutil/trace.go:171","msg":"trace[1967486123] transaction","detail":"{read_only:false; response_revision:25638; number_of_response:1; }","duration":"364.843258ms","start":"2026-08-20T02:03:31.117544Z","end":"2026-08-20T02:03:31.482387Z","steps":["trace[1967486123] 'process raft request' (duration: 364.344794ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:31.482433Z","caller":"traceutil/trace.go:171","msg":"trace[386884023] transaction","detail":"{read_only:false; response_revision:25637; number_of_response:1; }","duration":"366.355501ms","start":"2026-08-20T02:03:31.11603Z","end":"2026-08-20T02:03:31.482386Z","steps":["trace[386884023] 'process raft request' (duration: 245.110845ms)","trace[386884023] 'compare' (duration: 120.450824ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:31.482574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.83785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-08-20T02:03:31.482599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:31.11753Z","time spent":"364.973222ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:31.482617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:31.116007Z","time spent":"366.519755ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:31.482655Z","caller":"traceutil/trace.go:171","msg":"trace[67216025] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25639; }","duration":"180.932683ms","start":"2026-08-20T02:03:31.301704Z","end":"2026-08-20T02:03:31.482636Z","steps":["trace[67216025] 'agreement among raft nodes before linearized reading' (duration: 180.695556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.482584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.939239ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18143"} {"level":"info","ts":"2026-08-20T02:03:31.482751Z","caller":"traceutil/trace.go:171","msg":"trace[703751057] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:25639; }","duration":"366.129834ms","start":"2026-08-20T02:03:31.116608Z","end":"2026-08-20T02:03:31.482738Z","steps":["trace[703751057] 'agreement among raft nodes before linearized reading' (duration: 365.753533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.482799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:31.1166Z","time spent":"366.190726ms","remote":"127.0.0.1:51690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18165,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-20T02:03:31.803724Z","caller":"traceutil/trace.go:171","msg":"trace[82407943] transaction","detail":"{read_only:false; response_revision:25640; number_of_response:1; }","duration":"314.307097ms","start":"2026-08-20T02:03:31.489387Z","end":"2026-08-20T02:03:31.803694Z","steps":["trace[82407943] 'process raft request' (duration: 297.821087ms)","trace[82407943] 'compare' (duration: 16.13151ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:31.80374Z","caller":"traceutil/trace.go:171","msg":"trace[2101855518] linearizableReadLoop","detail":"{readStateIndex:26468; appliedIndex:26467; }","duration":"314.295797ms","start":"2026-08-20T02:03:31.489419Z","end":"2026-08-20T02:03:31.803715Z","steps":["trace[2101855518] 'read index received' (duration: 297.781736ms)","trace[2101855518] 'applied index is now lower than readState.Index' (duration: 16.512381ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:31.803745Z","caller":"traceutil/trace.go:171","msg":"trace[1262321718] transaction","detail":"{read_only:false; response_revision:25641; number_of_response:1; }","duration":"290.400549ms","start":"2026-08-20T02:03:31.513318Z","end":"2026-08-20T02:03:31.803719Z","steps":["trace[1262321718] 'process raft request' (duration: 290.268085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.80386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:31.48937Z","time spent":"314.4176ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:31.804184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.171117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-1c442ea7-9d61-48f7-8d24-62e6173d70f5\" ","response":"range_response_count:1 size:1323"} {"level":"info","ts":"2026-08-20T02:03:31.804238Z","caller":"traceutil/trace.go:171","msg":"trace[1786513960] range","detail":"{range_begin:/registry/persistentvolumes/pvc-1c442ea7-9d61-48f7-8d24-62e6173d70f5; range_end:; response_count:1; response_revision:25641; }","duration":"307.26053ms","start":"2026-08-20T02:03:31.496963Z","end":"2026-08-20T02:03:31.804224Z","steps":["trace[1786513960] 'agreement among raft nodes before linearized reading' (duration: 307.087005ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.80422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.78635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:1448"} {"level":"warn","ts":"2026-08-20T02:03:31.804274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:31.49695Z","time spent":"307.315601ms","remote":"127.0.0.1:51672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-1c442ea7-9d61-48f7-8d24-62e6173d70f5\" "} {"level":"info","ts":"2026-08-20T02:03:31.804328Z","caller":"traceutil/trace.go:171","msg":"trace[169042194] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25641; }","duration":"314.913454ms","start":"2026-08-20T02:03:31.489387Z","end":"2026-08-20T02:03:31.8043Z","steps":["trace[169042194] 'agreement among raft nodes before linearized reading' (duration: 314.633576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.804392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:31.489369Z","time spent":"315.009106ms","remote":"127.0.0.1:51714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":1470,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-08-20T02:03:31.804076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.118378ms","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-08-20T02:03:31.804565Z","caller":"traceutil/trace.go:171","msg":"trace[1825879088] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25641; }","duration":"253.621032ms","start":"2026-08-20T02:03:31.550901Z","end":"2026-08-20T02:03:31.804522Z","steps":["trace[1825879088] 'agreement among raft nodes before linearized reading' (duration: 253.101578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.805087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.500921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-manila.18cd611e19fe370d\" ","response":"range_response_count:1 size:742"} {"level":"info","ts":"2026-08-20T02:03:31.805183Z","caller":"traceutil/trace.go:171","msg":"trace[1531720617] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-manila.18cd611e19fe370d; range_end:; response_count:1; response_revision:25641; }","duration":"315.611383ms","start":"2026-08-20T02:03:31.489553Z","end":"2026-08-20T02:03:31.805164Z","steps":["trace[1531720617] 'agreement among raft nodes before linearized reading' (duration: 314.329917ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:31.805238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:31.489546Z","time spent":"315.679645ms","remote":"127.0.0.1:51616","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":764,"request content":"key:\"/registry/events/openstack/rabbitmq-manila.18cd611e19fe370d\" "} {"level":"warn","ts":"2026-08-20T02:03:34.081866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.080254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3237"} {"level":"info","ts":"2026-08-20T02:03:34.081947Z","caller":"traceutil/trace.go:171","msg":"trace[1587459411] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25658; }","duration":"216.201427ms","start":"2026-08-20T02:03:33.865727Z","end":"2026-08-20T02:03:34.081929Z","steps":["trace[1587459411] 'range keys from in-memory index tree' (duration: 215.857778ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:34.082068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.865731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/\" range_end:\"/registry/rabbitmq.com/rabbitmqclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-20T02:03:34.082135Z","caller":"traceutil/trace.go:171","msg":"trace[1582743870] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/; range_end:/registry/rabbitmq.com/rabbitmqclusters0; response_count:0; response_revision:25658; }","duration":"118.000305ms","start":"2026-08-20T02:03:33.964119Z","end":"2026-08-20T02:03:34.082119Z","steps":["trace[1582743870] 'count revisions from in-memory index tree' (duration: 117.799759ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:34.16525Z","caller":"traceutil/trace.go:171","msg":"trace[1906403745] transaction","detail":"{read_only:false; response_revision:25659; number_of_response:1; }","duration":"109.443927ms","start":"2026-08-20T02:03:34.055787Z","end":"2026-08-20T02:03:34.165231Z","steps":["trace[1906403745] 'process raft request' (duration: 109.101107ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:34.27386Z","caller":"traceutil/trace.go:171","msg":"trace[1176429628] transaction","detail":"{read_only:false; response_revision:25661; number_of_response:1; }","duration":"166.595302ms","start":"2026-08-20T02:03:34.107229Z","end":"2026-08-20T02:03:34.273824Z","steps":["trace[1176429628] 'process raft request' (duration: 166.473748ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:34.274079Z","caller":"traceutil/trace.go:171","msg":"trace[511839615] transaction","detail":"{read_only:false; response_revision:25660; number_of_response:1; }","duration":"166.937121ms","start":"2026-08-20T02:03:34.107117Z","end":"2026-08-20T02:03:34.274054Z","steps":["trace[511839615] 'process raft request' (duration: 165.560123ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:34.274082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.70006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/schemareplications/\" range_end:\"/registry/rabbitmq.com/schemareplications0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:34.274154Z","caller":"traceutil/trace.go:171","msg":"trace[318317334] range","detail":"{range_begin:/registry/rabbitmq.com/schemareplications/; range_end:/registry/rabbitmq.com/schemareplications0; response_count:0; response_revision:25661; }","duration":"115.822585ms","start":"2026-08-20T02:03:34.158318Z","end":"2026-08-20T02:03:34.27414Z","steps":["trace[318317334] 'agreement among raft nodes before linearized reading' (duration: 115.66063ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:34.273807Z","caller":"traceutil/trace.go:171","msg":"trace[474020935] linearizableReadLoop","detail":"{readStateIndex:26490; appliedIndex:26488; }","duration":"108.737667ms","start":"2026-08-20T02:03:34.165048Z","end":"2026-08-20T02:03:34.273786Z","steps":["trace[474020935] 'read index received' (duration: 107.534043ms)","trace[474020935] 'applied index is now lower than readState.Index' (duration: 1.202764ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:34.274564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.20162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-7628e4b9-c223-4514-bbf0-2098e14ed341\" ","response":"range_response_count:1 size:3946"} {"level":"info","ts":"2026-08-20T02:03:34.274626Z","caller":"traceutil/trace.go:171","msg":"trace[771060269] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-7628e4b9-c223-4514-bbf0-2098e14ed341; range_end:; response_count:1; response_revision:25661; }","duration":"152.296233ms","start":"2026-08-20T02:03:34.122312Z","end":"2026-08-20T02:03:34.274609Z","steps":["trace[771060269] 'agreement among raft nodes before linearized reading' (duration: 152.096867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:34.274695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.576761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:34.274849Z","caller":"traceutil/trace.go:171","msg":"trace[688007505] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:25661; }","duration":"118.710235ms","start":"2026-08-20T02:03:34.156094Z","end":"2026-08-20T02:03:34.274804Z","steps":["trace[688007505] 'agreement among raft nodes before linearized reading' (duration: 117.959234ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:37.269229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.645534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:03:37.269313Z","caller":"traceutil/trace.go:171","msg":"trace[1474361605] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25699; }","duration":"142.762467ms","start":"2026-08-20T02:03:37.12653Z","end":"2026-08-20T02:03:37.269292Z","steps":["trace[1474361605] 'range keys from in-memory index tree' (duration: 142.494899ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:37.269383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.916893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:03:37.269474Z","caller":"traceutil/trace.go:171","msg":"trace[1468654512] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25699; }","duration":"142.015676ms","start":"2026-08-20T02:03:37.127431Z","end":"2026-08-20T02:03:37.269447Z","steps":["trace[1468654512] 'range keys from in-memory index tree' (duration: 141.773259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:37.269927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.534356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:03:37.270004Z","caller":"traceutil/trace.go:171","msg":"trace[440173742] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25699; }","duration":"144.66373ms","start":"2026-08-20T02:03:37.125322Z","end":"2026-08-20T02:03:37.269985Z","steps":["trace[440173742] 'range keys from in-memory index tree' (duration: 144.32271ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:37.523874Z","caller":"traceutil/trace.go:171","msg":"trace[1396590791] linearizableReadLoop","detail":"{readStateIndex:26531; appliedIndex:26530; }","duration":"388.947181ms","start":"2026-08-20T02:03:37.134901Z","end":"2026-08-20T02:03:37.523849Z","steps":["trace[1396590791] 'read index received' (duration: 388.760926ms)","trace[1396590791] 'applied index is now lower than readState.Index' (duration: 185.085µs)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:37.524115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.198868ms","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-08-20T02:03:37.524138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.848873ms","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-08-20T02:03:37.524163Z","caller":"traceutil/trace.go:171","msg":"trace[1497963054] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25700; }","duration":"389.286851ms","start":"2026-08-20T02:03:37.134859Z","end":"2026-08-20T02:03:37.524145Z","steps":["trace[1497963054] 'agreement among raft nodes before linearized reading' (duration: 389.130997ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:37.524141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.806895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:37.524191Z","caller":"traceutil/trace.go:171","msg":"trace[2097967618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25700; }","duration":"304.944525ms","start":"2026-08-20T02:03:37.219236Z","end":"2026-08-20T02:03:37.52418Z","steps":["trace[2097967618] 'agreement among raft nodes before linearized reading' (duration: 304.77017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:37.524199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.134841Z","time spent":"389.350132ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-20T02:03:37.524195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.790661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:03:37.523957Z","caller":"traceutil/trace.go:171","msg":"trace[628700859] transaction","detail":"{read_only:false; response_revision:25700; number_of_response:1; }","duration":"395.650108ms","start":"2026-08-20T02:03:37.128282Z","end":"2026-08-20T02:03:37.523932Z","steps":["trace[628700859] 'process raft request' (duration: 395.387231ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:37.524265Z","caller":"traceutil/trace.go:171","msg":"trace[994603590] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:25700; }","duration":"105.958449ms","start":"2026-08-20T02:03:37.418253Z","end":"2026-08-20T02:03:37.524212Z","steps":["trace[994603590] 'agreement among raft nodes before linearized reading' (duration: 105.801225ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:37.52427Z","caller":"traceutil/trace.go:171","msg":"trace[1971314045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25700; }","duration":"116.895854ms","start":"2026-08-20T02:03:37.407354Z","end":"2026-08-20T02:03:37.52425Z","steps":["trace[1971314045] 'agreement among raft nodes before linearized reading' (duration: 116.779511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:37.524228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.219215Z","time spent":"305.005917ms","remote":"127.0.0.1:51770","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-08-20T02:03:37.524402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.128267Z","time spent":"396.015359ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":496,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:38.270128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.13441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:38.270316Z","caller":"traceutil/trace.go:171","msg":"trace[2004763940] transaction","detail":"{read_only:false; response_revision:25701; number_of_response:1; }","duration":"742.635968ms","start":"2026-08-20T02:03:37.52766Z","end":"2026-08-20T02:03:38.270296Z","steps":["trace[2004763940] 'process raft request' (duration: 432.253421ms)","trace[2004763940] 'compare' (duration: 310.018727ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:38.270379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.527648Z","time spent":"742.69965ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:38.677964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.449396ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:38.678235Z","caller":"traceutil/trace.go:171","msg":"trace[1175342862] transaction","detail":"{read_only:false; response_revision:25704; number_of_response:1; }","duration":"1.149701805s","start":"2026-08-20T02:03:37.528517Z","end":"2026-08-20T02:03:38.678219Z","steps":["trace[1175342862] 'process raft request' (duration: 1.149630083s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.678311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.528509Z","time spent":"1.149768287s","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:38.678341Z","caller":"traceutil/trace.go:171","msg":"trace[399957202] transaction","detail":"{read_only:false; response_revision:25703; number_of_response:1; }","duration":"1.14985609s","start":"2026-08-20T02:03:37.528455Z","end":"2026-08-20T02:03:38.678311Z","steps":["trace[399957202] 'process raft request' (duration: 1.149601693s)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:38.678401Z","caller":"traceutil/trace.go:171","msg":"trace[687828823] transaction","detail":"{read_only:false; response_revision:25702; number_of_response:1; }","duration":"1.150120257s","start":"2026-08-20T02:03:37.528193Z","end":"2026-08-20T02:03:38.678314Z","steps":["trace[687828823] 'process raft request' (duration: 958.239289ms)","trace[687828823] 'compare' (duration: 191.300502ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:38.678485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.528442Z","time spent":"1.149964693s","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:38.678591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.528184Z","time spent":"1.150326972s","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:38.678738Z","caller":"traceutil/trace.go:171","msg":"trace[695561449] transaction","detail":"{read_only:false; response_revision:25705; number_of_response:1; }","duration":"1.14311155s","start":"2026-08-20T02:03:37.535614Z","end":"2026-08-20T02:03:38.678726Z","steps":["trace[695561449] 'process raft request' (duration: 1.142569765s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.678809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.535591Z","time spent":"1.143185883s","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:38.678862Z","caller":"traceutil/trace.go:171","msg":"trace[514539151] transaction","detail":"{read_only:false; response_revision:25709; number_of_response:1; }","duration":"392.85422ms","start":"2026-08-20T02:03:38.285995Z","end":"2026-08-20T02:03:38.678849Z","steps":["trace[514539151] 'process raft request' (duration: 392.817849ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:38.678882Z","caller":"traceutil/trace.go:171","msg":"trace[1802470145] linearizableReadLoop","detail":"{readStateIndex:26536; appliedIndex:26531; }","duration":"1.135686634s","start":"2026-08-20T02:03:37.543184Z","end":"2026-08-20T02:03:38.67887Z","steps":["trace[1802470145] 'read index received' (duration: 416.748907ms)","trace[1802470145] 'applied index is now lower than readState.Index' (duration: 718.936106ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:38.6789Z","caller":"traceutil/trace.go:171","msg":"trace[166580420] transaction","detail":"{read_only:false; response_revision:25706; number_of_response:1; }","duration":"857.933548ms","start":"2026-08-20T02:03:37.820956Z","end":"2026-08-20T02:03:38.67889Z","steps":["trace[166580420] 'process raft request' (duration: 857.682371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.678946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.820925Z","time spent":"857.995539ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:38.678938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.285974Z","time spent":"392.929402ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:38.679037Z","caller":"traceutil/trace.go:171","msg":"trace[1066930424] transaction","detail":"{read_only:false; response_revision:25707; number_of_response:1; }","duration":"504.419576ms","start":"2026-08-20T02:03:38.174605Z","end":"2026-08-20T02:03:38.679024Z","steps":["trace[1066930424] 'process raft request' (duration: 504.126598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.679096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.13590922s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1936"} {"level":"info","ts":"2026-08-20T02:03:38.67913Z","caller":"traceutil/trace.go:171","msg":"trace[1619080216] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:25709; }","duration":"1.136003992s","start":"2026-08-20T02:03:37.543116Z","end":"2026-08-20T02:03:38.67912Z","steps":["trace[1619080216] 'agreement among raft nodes before linearized reading' (duration: 1.135829188s)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.679145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.174583Z","time spent":"504.530638ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:38.679187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.543102Z","time spent":"1.136075255s","remote":"127.0.0.1:51678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1958,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-08-20T02:03:38.67937Z","caller":"traceutil/trace.go:171","msg":"trace[1504000298] transaction","detail":"{read_only:false; response_revision:25708; number_of_response:1; }","duration":"394.160107ms","start":"2026-08-20T02:03:38.285185Z","end":"2026-08-20T02:03:38.679345Z","steps":["trace[1504000298] 'process raft request' (duration: 393.590321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.679514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"709.329878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-08-20T02:03:38.67952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.285165Z","time spent":"394.280551ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:38.679552Z","caller":"traceutil/trace.go:171","msg":"trace[742388183] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:25709; }","duration":"709.40527ms","start":"2026-08-20T02:03:37.970133Z","end":"2026-08-20T02:03:38.679538Z","steps":["trace[742388183] 'agreement among raft nodes before linearized reading' (duration: 709.343788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.679573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.961263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-08-20T02:03:38.679611Z","caller":"traceutil/trace.go:171","msg":"trace[1619435641] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25709; }","duration":"774.038505ms","start":"2026-08-20T02:03:37.905561Z","end":"2026-08-20T02:03:38.679599Z","steps":["trace[1619435641] 'agreement among raft nodes before linearized reading' (duration: 773.918041ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.679639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.905538Z","time spent":"774.092187ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":584,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-08-20T02:03:38.67958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.970111Z","time spent":"709.461411ms","remote":"127.0.0.1:51812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-20T02:03:38.67973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.227372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"info","ts":"2026-08-20T02:03:38.679817Z","caller":"traceutil/trace.go:171","msg":"trace[854676397] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25709; }","duration":"831.360795ms","start":"2026-08-20T02:03:37.848432Z","end":"2026-08-20T02:03:38.679793Z","steps":["trace[854676397] 'agreement among raft nodes before linearized reading' (duration: 831.085968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.679873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.848412Z","time spent":"831.447318ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10380,"request content":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" "} {"level":"warn","ts":"2026-08-20T02:03:38.681039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.051923ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-20T02:03:38.681122Z","caller":"traceutil/trace.go:171","msg":"trace[214271532] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25709; }","duration":"857.174887ms","start":"2026-08-20T02:03:37.823924Z","end":"2026-08-20T02:03:38.681099Z","steps":["trace[214271532] 'agreement among raft nodes before linearized reading' (duration: 855.48752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:38.681548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:37.823911Z","time spent":"857.243038ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-20T02:03:39.101015Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.974799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:03:39.101097Z","caller":"traceutil/trace.go:171","msg":"trace[281776072] transaction","detail":"{read_only:false; response_revision:25710; number_of_response:1; }","duration":"416.378328ms","start":"2026-08-20T02:03:38.684704Z","end":"2026-08-20T02:03:39.101082Z","steps":["trace[281776072] 'process raft request' (duration: 103.254884ms)","trace[281776072] 'compare' (duration: 312.661921ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:03:39.101136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.684686Z","time spent":"416.430659ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:39.137635Z","caller":"traceutil/trace.go:171","msg":"trace[1936588278] transaction","detail":"{read_only:false; response_revision:25711; number_of_response:1; }","duration":"452.677791ms","start":"2026-08-20T02:03:38.684938Z","end":"2026-08-20T02:03:39.137616Z","steps":["trace[1936588278] 'process raft request' (duration: 452.454594ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:39.137734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.684918Z","time spent":"452.763393ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:39.137691Z","caller":"traceutil/trace.go:171","msg":"trace[1285481641] linearizableReadLoop","detail":"{readStateIndex:26543; appliedIndex:26540; }","duration":"450.790348ms","start":"2026-08-20T02:03:38.686859Z","end":"2026-08-20T02:03:39.13765Z","steps":["trace[1285481641] 'read index received' (duration: 101.141114ms)","trace[1285481641] 'applied index is now lower than readState.Index' (duration: 349.648054ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:03:39.137781Z","caller":"traceutil/trace.go:171","msg":"trace[1795801180] transaction","detail":"{read_only:false; response_revision:25713; number_of_response:1; }","duration":"448.185545ms","start":"2026-08-20T02:03:38.689563Z","end":"2026-08-20T02:03:39.137748Z","steps":["trace[1795801180] 'process raft request' (duration: 448.017541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:39.137914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.035674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-08-20T02:03:39.137965Z","caller":"traceutil/trace.go:171","msg":"trace[1680661910] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:25713; }","duration":"451.108727ms","start":"2026-08-20T02:03:38.686843Z","end":"2026-08-20T02:03:39.137952Z","steps":["trace[1680661910] 'agreement among raft nodes before linearized reading' (duration: 450.955533ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:39.137999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.686835Z","time spent":"451.158208ms","remote":"127.0.0.1:51678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1882,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"info","ts":"2026-08-20T02:03:39.13782Z","caller":"traceutil/trace.go:171","msg":"trace[971913035] transaction","detail":"{read_only:false; response_revision:25712; number_of_response:1; }","duration":"452.242658ms","start":"2026-08-20T02:03:38.685566Z","end":"2026-08-20T02:03:39.137808Z","steps":["trace[971913035] 'process raft request' (duration: 451.95785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:39.13805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.685556Z","time spent":"452.469005ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-20T02:03:39.138474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:03:38.689545Z","time spent":"448.346589ms","remote":"127.0.0.1:51706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10633,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:03:39.143178Z","caller":"traceutil/trace.go:171","msg":"trace[1956708705] transaction","detail":"{read_only:false; response_revision:25714; number_of_response:1; }","duration":"188.646718ms","start":"2026-08-20T02:03:38.954488Z","end":"2026-08-20T02:03:39.143135Z","steps":["trace[1956708705] 'process raft request' (duration: 188.406791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:03:39.143738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.344724ms","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-08-20T02:03:39.143789Z","caller":"traceutil/trace.go:171","msg":"trace[1221640523] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25714; }","duration":"205.426807ms","start":"2026-08-20T02:03:38.938337Z","end":"2026-08-20T02:03:39.143764Z","steps":["trace[1221640523] 'agreement among raft nodes before linearized reading' (duration: 205.287783ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:03:57.85478Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006859.wal"} {"level":"info","ts":"2026-08-20T02:04:07.624281Z","caller":"traceutil/trace.go:171","msg":"trace[419665520] linearizableReadLoop","detail":"{readStateIndex:26786; appliedIndex:26785; }","duration":"286.982314ms","start":"2026-08-20T02:04:07.337264Z","end":"2026-08-20T02:04:07.624247Z","steps":["trace[419665520] 'read index received' (duration: 252.346667ms)","trace[419665520] 'applied index is now lower than readState.Index' (duration: 34.634357ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:07.624323Z","caller":"traceutil/trace.go:171","msg":"trace[1575432231] transaction","detail":"{read_only:false; response_revision:25948; number_of_response:1; }","duration":"302.534668ms","start":"2026-08-20T02:04:07.32175Z","end":"2026-08-20T02:04:07.624285Z","steps":["trace[1575432231] 'process raft request' (duration: 267.917272ms)","trace[1575432231] 'compare' (duration: 34.336768ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:07.62443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.145749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:04:07.62532Z","caller":"traceutil/trace.go:171","msg":"trace[1715988453] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25948; }","duration":"288.056294ms","start":"2026-08-20T02:04:07.337238Z","end":"2026-08-20T02:04:07.625295Z","steps":["trace[1715988453] 'agreement among raft nodes before linearized reading' (duration: 287.055537ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:07.624483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.083754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:04:07.625417Z","caller":"traceutil/trace.go:171","msg":"trace[1948193153] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25948; }","duration":"217.059822ms","start":"2026-08-20T02:04:07.408344Z","end":"2026-08-20T02:04:07.625404Z","steps":["trace[1948193153] 'agreement among raft nodes before linearized reading' (duration: 216.067214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:07.624518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.809749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"warn","ts":"2026-08-20T02:04:07.625291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:07.321734Z","time spent":"303.485655ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:04:07.625532Z","caller":"traceutil/trace.go:171","msg":"trace[1331830804] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25948; }","duration":"287.833788ms","start":"2026-08-20T02:04:07.337668Z","end":"2026-08-20T02:04:07.625501Z","steps":["trace[1331830804] 'agreement among raft nodes before linearized reading' (duration: 286.719667ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:07.624539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.55041ms","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-08-20T02:04:07.625634Z","caller":"traceutil/trace.go:171","msg":"trace[528376324] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:25948; }","duration":"177.658931ms","start":"2026-08-20T02:04:07.447941Z","end":"2026-08-20T02:04:07.6256Z","steps":["trace[528376324] 'agreement among raft nodes before linearized reading' (duration: 176.55294ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:07.624586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.105293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-08-20T02:04:07.625756Z","caller":"traceutil/trace.go:171","msg":"trace[2030757364] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25948; }","duration":"179.334098ms","start":"2026-08-20T02:04:07.446392Z","end":"2026-08-20T02:04:07.625726Z","steps":["trace[2030757364] 'agreement among raft nodes before linearized reading' (duration: 178.013162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:07.624629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.603958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:04:07.625858Z","caller":"traceutil/trace.go:171","msg":"trace[392481508] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25948; }","duration":"285.878923ms","start":"2026-08-20T02:04:07.339969Z","end":"2026-08-20T02:04:07.625848Z","steps":["trace[392481508] 'agreement among raft nodes before linearized reading' (duration: 284.462073ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:07.827313Z","caller":"traceutil/trace.go:171","msg":"trace[1948362928] linearizableReadLoop","detail":"{readStateIndex:26787; appliedIndex:26786; }","duration":"139.460994ms","start":"2026-08-20T02:04:07.68783Z","end":"2026-08-20T02:04:07.827291Z","steps":["trace[1948362928] 'read index received' (duration: 127.729666ms)","trace[1948362928] 'applied index is now lower than readState.Index' (duration: 11.730398ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:07.82744Z","caller":"traceutil/trace.go:171","msg":"trace[109480573] transaction","detail":"{read_only:false; response_revision:25949; number_of_response:1; }","duration":"175.232314ms","start":"2026-08-20T02:04:07.65218Z","end":"2026-08-20T02:04:07.827412Z","steps":["trace[109480573] 'process raft request' (duration: 163.376682ms)","trace[109480573] 'compare' (duration: 11.394119ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:07.827511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.676251ms","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-08-20T02:04:07.828103Z","caller":"traceutil/trace.go:171","msg":"trace[1522000518] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25949; }","duration":"140.311098ms","start":"2026-08-20T02:04:07.687773Z","end":"2026-08-20T02:04:07.828084Z","steps":["trace[1522000518] 'agreement among raft nodes before linearized reading' (duration: 139.64388ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:08.7084Z","caller":"traceutil/trace.go:171","msg":"trace[288522474] transaction","detail":"{read_only:false; response_revision:25951; number_of_response:1; }","duration":"232.591196ms","start":"2026-08-20T02:04:08.475767Z","end":"2026-08-20T02:04:08.708358Z","steps":["trace[288522474] 'process raft request' (duration: 179.73834ms)","trace[288522474] 'compare' (duration: 52.716352ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:08.899089Z","caller":"traceutil/trace.go:171","msg":"trace[2126661457] transaction","detail":"{read_only:false; response_revision:25952; number_of_response:1; }","duration":"112.998716ms","start":"2026-08-20T02:04:08.786057Z","end":"2026-08-20T02:04:08.899056Z","steps":["trace[2126661457] 'process raft request' (duration: 59.212604ms)","trace[2126661457] 'compare' (duration: 53.480263ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:09.16071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.248863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:04:09.160944Z","caller":"traceutil/trace.go:171","msg":"trace[1355210098] linearizableReadLoop","detail":"{readStateIndex:26793; appliedIndex:26790; }","duration":"221.181686ms","start":"2026-08-20T02:04:08.939745Z","end":"2026-08-20T02:04:09.160927Z","steps":["trace[1355210098] 'read index received' (duration: 35.960234ms)","trace[1355210098] 'applied index is now lower than readState.Index' (duration: 185.220282ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:09.160982Z","caller":"traceutil/trace.go:171","msg":"trace[947377379] transaction","detail":"{read_only:false; response_revision:25954; number_of_response:1; }","duration":"228.669325ms","start":"2026-08-20T02:04:08.932291Z","end":"2026-08-20T02:04:09.16096Z","steps":["trace[947377379] 'process raft request' (duration: 67.074683ms)","trace[947377379] 'compare' (duration: 161.108119ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:09.160984Z","caller":"traceutil/trace.go:171","msg":"trace[1143286921] transaction","detail":"{read_only:false; response_revision:25955; number_of_response:1; }","duration":"228.369598ms","start":"2026-08-20T02:04:08.9326Z","end":"2026-08-20T02:04:09.160969Z","steps":["trace[1143286921] 'process raft request' (duration: 228.230434ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:09.161124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.357581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-20T02:04:09.161191Z","caller":"traceutil/trace.go:171","msg":"trace[706778658] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25955; }","duration":"221.453384ms","start":"2026-08-20T02:04:08.939723Z","end":"2026-08-20T02:04:09.161177Z","steps":["trace[706778658] 'agreement among raft nodes before linearized reading' (duration: 221.250819ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:09.166811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.661744ms","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-08-20T02:04:09.16687Z","caller":"traceutil/trace.go:171","msg":"trace[1919688485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25956; }","duration":"224.739956ms","start":"2026-08-20T02:04:08.942117Z","end":"2026-08-20T02:04:09.166857Z","steps":["trace[1919688485] 'agreement among raft nodes before linearized reading' (duration: 224.572951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:09.166901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.51743ms","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-08-20T02:04:09.166944Z","caller":"traceutil/trace.go:171","msg":"trace[732122881] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25956; }","duration":"224.607162ms","start":"2026-08-20T02:04:08.942325Z","end":"2026-08-20T02:04:09.166933Z","steps":["trace[732122881] 'agreement among raft nodes before linearized reading' (duration: 224.450208ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:09.527024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.14986ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:04:09.527194Z","caller":"traceutil/trace.go:171","msg":"trace[457741977] linearizableReadLoop","detail":"{readStateIndex:26801; appliedIndex:26799; }","duration":"118.560631ms","start":"2026-08-20T02:04:09.408619Z","end":"2026-08-20T02:04:09.527179Z","steps":["trace[457741977] 'read index received' (duration: 36.441308ms)","trace[457741977] 'applied index is now lower than readState.Index' (duration: 82.117573ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:09.527272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.665083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:04:09.52729Z","caller":"traceutil/trace.go:171","msg":"trace[765216011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25963; }","duration":"118.685204ms","start":"2026-08-20T02:04:09.408599Z","end":"2026-08-20T02:04:09.527284Z","steps":["trace[765216011] 'agreement among raft nodes before linearized reading' (duration: 118.618592ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:09.527306Z","caller":"traceutil/trace.go:171","msg":"trace[872235096] transaction","detail":"{read_only:false; response_revision:25962; number_of_response:1; }","duration":"230.840776ms","start":"2026-08-20T02:04:09.296448Z","end":"2026-08-20T02:04:09.527289Z","steps":["trace[872235096] 'process raft request' (duration: 98.392887ms)","trace[872235096] 'compare' (duration: 132.017657ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:09.52739Z","caller":"traceutil/trace.go:171","msg":"trace[1836961905] transaction","detail":"{read_only:false; response_revision:25963; number_of_response:1; }","duration":"213.44814ms","start":"2026-08-20T02:04:09.313903Z","end":"2026-08-20T02:04:09.527351Z","steps":["trace[1836961905] 'process raft request' (duration: 213.205114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:10.051363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.657775ms","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-08-20T02:04:10.051416Z","caller":"traceutil/trace.go:171","msg":"trace[358176685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25963; }","duration":"421.783438ms","start":"2026-08-20T02:04:09.629621Z","end":"2026-08-20T02:04:10.051405Z","steps":["trace[358176685] 'range keys from in-memory index tree' (duration: 421.522951ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:10.051439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:09.629602Z","time spent":"421.83136ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-20T02:04:10.051576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.795948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:04:10.051598Z","caller":"traceutil/trace.go:171","msg":"trace[972305395] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25963; }","duration":"421.84865ms","start":"2026-08-20T02:04:09.629743Z","end":"2026-08-20T02:04:10.051592Z","steps":["trace[972305395] 'range keys from in-memory index tree' (duration: 421.716047ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:10.051611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:09.629726Z","time spent":"421.882201ms","remote":"127.0.0.1:51680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"warn","ts":"2026-08-20T02:04:10.052095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.626894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:04:10.05212Z","caller":"traceutil/trace.go:171","msg":"trace[1407896525] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25963; }","duration":"421.709377ms","start":"2026-08-20T02:04:09.630406Z","end":"2026-08-20T02:04:10.052115Z","steps":["trace[1407896525] 'range keys from in-memory index tree' (duration: 421.536021ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:10.052091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.21262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"warn","ts":"2026-08-20T02:04:10.052171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:09.630396Z","time spent":"421.735687ms","remote":"127.0.0.1:51680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-08-20T02:04:10.052199Z","caller":"traceutil/trace.go:171","msg":"trace[80619151] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25963; }","duration":"422.363804ms","start":"2026-08-20T02:04:09.629815Z","end":"2026-08-20T02:04:10.052179Z","steps":["trace[80619151] 'range keys from in-memory index tree' (duration: 421.988254ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:10.052251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:09.629777Z","time spent":"422.463617ms","remote":"127.0.0.1:51680","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":695,"request content":"key:\"/registry/services/endpoints/openstack/octavia-api\" "} {"level":"info","ts":"2026-08-20T02:04:10.052578Z","caller":"traceutil/trace.go:171","msg":"trace[2075655634] transaction","detail":"{read_only:false; response_revision:25964; number_of_response:1; }","duration":"219.85769ms","start":"2026-08-20T02:04:09.832689Z","end":"2026-08-20T02:04:10.052547Z","steps":["trace[2075655634] 'process raft request' (duration: 127.543342ms)","trace[2075655634] 'compare' (duration: 91.485204ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:10.131105Z","caller":"traceutil/trace.go:171","msg":"trace[931463132] linearizableReadLoop","detail":"{readStateIndex:26803; appliedIndex:26802; }","duration":"146.241404ms","start":"2026-08-20T02:04:09.984842Z","end":"2026-08-20T02:04:10.131083Z","steps":["trace[931463132] 'read index received' (duration: 146.131331ms)","trace[931463132] 'applied index is now lower than readState.Index' (duration: 109.273µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:10.131262Z","caller":"traceutil/trace.go:171","msg":"trace[1782642280] transaction","detail":"{read_only:false; response_revision:25965; number_of_response:1; }","duration":"150.987196ms","start":"2026-08-20T02:04:09.980251Z","end":"2026-08-20T02:04:10.131238Z","steps":["trace[1782642280] 'process raft request' (duration: 150.702348ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:10.131278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.409478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-20T02:04:10.131316Z","caller":"traceutil/trace.go:171","msg":"trace[1997259054] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:25965; }","duration":"146.4698ms","start":"2026-08-20T02:04:09.984834Z","end":"2026-08-20T02:04:10.131304Z","steps":["trace[1997259054] 'agreement among raft nodes before linearized reading' (duration: 146.353527ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:10.454961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.008278ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:04:10.455054Z","caller":"traceutil/trace.go:171","msg":"trace[793318657] transaction","detail":"{read_only:false; response_revision:25966; number_of_response:1; }","duration":"398.966091ms","start":"2026-08-20T02:04:10.056068Z","end":"2026-08-20T02:04:10.455034Z","steps":["trace[793318657] 'process raft request' (duration: 122.811249ms)","trace[793318657] 'compare' (duration: 275.776281ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:10.455183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:10.056044Z","time spent":"399.068514ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:04:10.623728Z","caller":"traceutil/trace.go:171","msg":"trace[269337686] transaction","detail":"{read_only:false; response_revision:25967; number_of_response:1; }","duration":"144.883226ms","start":"2026-08-20T02:04:10.478812Z","end":"2026-08-20T02:04:10.623696Z","steps":["trace[269337686] 'process raft request' (duration: 142.204261ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:11.129105Z","caller":"traceutil/trace.go:171","msg":"trace[919230931] transaction","detail":"{read_only:false; response_revision:25969; number_of_response:1; }","duration":"147.227301ms","start":"2026-08-20T02:04:10.981844Z","end":"2026-08-20T02:04:11.129072Z","steps":["trace[919230931] 'process raft request' (duration: 86.120145ms)","trace[919230931] 'compare' (duration: 60.853409ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:11.420712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.130375ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-08-20T02:04:11.421083Z","caller":"traceutil/trace.go:171","msg":"trace[1429827940] linearizableReadLoop","detail":"{readStateIndex:26809; appliedIndex:26807; }","duration":"240.774283ms","start":"2026-08-20T02:04:11.180296Z","end":"2026-08-20T02:04:11.42107Z","steps":["trace[1429827940] 'read index received' (duration: 101.232806ms)","trace[1429827940] 'applied index is now lower than readState.Index' (duration: 139.540877ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:11.421161Z","caller":"traceutil/trace.go:171","msg":"trace[1952269059] transaction","detail":"{read_only:false; response_revision:25971; number_of_response:1; }","duration":"255.853054ms","start":"2026-08-20T02:04:11.1653Z","end":"2026-08-20T02:04:11.421153Z","steps":["trace[1952269059] 'process raft request' (duration: 255.68709ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:11.421141Z","caller":"traceutil/trace.go:171","msg":"trace[566671289] transaction","detail":"{read_only:false; response_revision:25970; number_of_response:1; }","duration":"256.316837ms","start":"2026-08-20T02:04:11.164792Z","end":"2026-08-20T02:04:11.421109Z","steps":["trace[566671289] 'process raft request' (duration: 116.722039ms)","trace[566671289] 'compare' (duration: 138.93764ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:11.421542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.519742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-08-20T02:04:11.421543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.346597ms","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-08-20T02:04:11.421554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.247937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-08-20T02:04:11.4216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.662833ms","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-08-20T02:04:11.421629Z","caller":"traceutil/trace.go:171","msg":"trace[1950585914] 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:25971; }","duration":"213.468371ms","start":"2026-08-20T02:04:11.208138Z","end":"2026-08-20T02:04:11.421607Z","steps":["trace[1950585914] 'agreement among raft nodes before linearized reading' (duration: 213.328837ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:11.421651Z","caller":"traceutil/trace.go:171","msg":"trace[539377541] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25971; }","duration":"241.385671ms","start":"2026-08-20T02:04:11.180248Z","end":"2026-08-20T02:04:11.421634Z","steps":["trace[539377541] 'agreement among raft nodes before linearized reading' (duration: 241.193846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:11.421642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.709105ms","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-08-20T02:04:11.421669Z","caller":"traceutil/trace.go:171","msg":"trace[213585686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25971; }","duration":"229.755245ms","start":"2026-08-20T02:04:11.191895Z","end":"2026-08-20T02:04:11.42165Z","steps":["trace[213585686] 'agreement among raft nodes before linearized reading' (duration: 229.57489ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:11.421699Z","caller":"traceutil/trace.go:171","msg":"trace[1246045816] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25971; }","duration":"229.809137ms","start":"2026-08-20T02:04:11.191879Z","end":"2026-08-20T02:04:11.421688Z","steps":["trace[1246045816] 'agreement among raft nodes before linearized reading' (duration: 229.662843ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:11.421588Z","caller":"traceutil/trace.go:171","msg":"trace[584741241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25971; }","duration":"107.598664ms","start":"2026-08-20T02:04:11.313978Z","end":"2026-08-20T02:04:11.421577Z","steps":["trace[584741241] 'agreement among raft nodes before linearized reading' (duration: 107.45997ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:11.616252Z","caller":"traceutil/trace.go:171","msg":"trace[1328413814] transaction","detail":"{read_only:false; response_revision:25972; number_of_response:1; }","duration":"191.119126ms","start":"2026-08-20T02:04:11.425105Z","end":"2026-08-20T02:04:11.616225Z","steps":["trace[1328413814] 'process raft request' (duration: 190.967722ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:11.709251Z","caller":"traceutil/trace.go:171","msg":"trace[1770819419] transaction","detail":"{read_only:false; response_revision:25973; number_of_response:1; }","duration":"283.687611ms","start":"2026-08-20T02:04:11.425527Z","end":"2026-08-20T02:04:11.709214Z","steps":["trace[1770819419] 'process raft request' (duration: 267.965331ms)","trace[1770819419] 'compare' (duration: 15.247936ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:11.709327Z","caller":"traceutil/trace.go:171","msg":"trace[2129980723] transaction","detail":"{read_only:false; response_revision:25975; number_of_response:1; }","duration":"283.682511ms","start":"2026-08-20T02:04:11.425606Z","end":"2026-08-20T02:04:11.709289Z","steps":["trace[2129980723] 'process raft request' (duration: 283.546007ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:11.709367Z","caller":"traceutil/trace.go:171","msg":"trace[804138325] transaction","detail":"{read_only:false; response_revision:25976; number_of_response:1; }","duration":"177.065674ms","start":"2026-08-20T02:04:11.532275Z","end":"2026-08-20T02:04:11.709341Z","steps":["trace[804138325] 'process raft request' (duration: 176.91768ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:11.709256Z","caller":"traceutil/trace.go:171","msg":"trace[634950769] linearizableReadLoop","detail":"{readStateIndex:26813; appliedIndex:26809; }","duration":"177.018842ms","start":"2026-08-20T02:04:11.532214Z","end":"2026-08-20T02:04:11.709233Z","steps":["trace[634950769] 'read index received' (duration: 83.917062ms)","trace[634950769] 'applied index is now lower than readState.Index' (duration: 93.10073ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:11.70949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.251918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-08-20T02:04:11.709592Z","caller":"traceutil/trace.go:171","msg":"trace[850936286] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:25976; }","duration":"177.384232ms","start":"2026-08-20T02:04:11.532184Z","end":"2026-08-20T02:04:11.709568Z","steps":["trace[850936286] 'agreement among raft nodes before linearized reading' (duration: 177.119865ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:11.709362Z","caller":"traceutil/trace.go:171","msg":"trace[770294739] transaction","detail":"{read_only:false; response_revision:25974; number_of_response:1; }","duration":"283.814675ms","start":"2026-08-20T02:04:11.425518Z","end":"2026-08-20T02:04:11.709333Z","steps":["trace[770294739] 'process raft request' (duration: 283.524797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:11.709696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.515907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-08-20T02:04:11.70978Z","caller":"traceutil/trace.go:171","msg":"trace[2133281023] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:25976; }","duration":"148.6242ms","start":"2026-08-20T02:04:11.561132Z","end":"2026-08-20T02:04:11.709756Z","steps":["trace[2133281023] 'agreement among raft nodes before linearized reading' (duration: 148.478276ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:11.880643Z","caller":"traceutil/trace.go:171","msg":"trace[1587644013] transaction","detail":"{read_only:false; response_revision:25977; number_of_response:1; }","duration":"166.673775ms","start":"2026-08-20T02:04:11.713944Z","end":"2026-08-20T02:04:11.880618Z","steps":["trace[1587644013] 'process raft request' (duration: 164.206306ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:12.195437Z","caller":"traceutil/trace.go:171","msg":"trace[523956051] linearizableReadLoop","detail":"{readStateIndex:26816; appliedIndex:26815; }","duration":"137.763108ms","start":"2026-08-20T02:04:12.057654Z","end":"2026-08-20T02:04:12.195417Z","steps":["trace[523956051] 'read index received' (duration: 137.645294ms)","trace[523956051] 'applied index is now lower than readState.Index' (duration: 116.853µs)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:12.195491Z","caller":"traceutil/trace.go:171","msg":"trace[735419538] transaction","detail":"{read_only:false; response_revision:25978; number_of_response:1; }","duration":"137.890201ms","start":"2026-08-20T02:04:12.057567Z","end":"2026-08-20T02:04:12.195457Z","steps":["trace[735419538] 'process raft request' (duration: 137.707706ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:12.195641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.828549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:04:12.195671Z","caller":"traceutil/trace.go:171","msg":"trace[477508958] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25978; }","duration":"138.078586ms","start":"2026-08-20T02:04:12.057583Z","end":"2026-08-20T02:04:12.195662Z","steps":["trace[477508958] 'agreement among raft nodes before linearized reading' (duration: 137.955283ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:12.195934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.253871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/octavia-api\" ","response":"range_response_count:1 size:673"} {"level":"info","ts":"2026-08-20T02:04:12.19602Z","caller":"traceutil/trace.go:171","msg":"trace[907010042] range","detail":"{range_begin:/registry/services/endpoints/openstack/octavia-api; range_end:; response_count:1; response_revision:25978; }","duration":"138.394745ms","start":"2026-08-20T02:04:12.057606Z","end":"2026-08-20T02:04:12.196Z","steps":["trace[907010042] 'agreement among raft nodes before linearized reading' (duration: 137.931982ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:12.454442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.226471ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-08-20T02:04:12.611217Z","caller":"traceutil/trace.go:171","msg":"trace[735992101] transaction","detail":"{read_only:false; response_revision:25979; number_of_response:1; }","duration":"141.182533ms","start":"2026-08-20T02:04:12.470006Z","end":"2026-08-20T02:04:12.611189Z","steps":["trace[735992101] 'process raft request' (duration: 141.000258ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:16.768924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.884196ms","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-08-20T02:04:16.769021Z","caller":"traceutil/trace.go:171","msg":"trace[330219143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26019; }","duration":"144.992769ms","start":"2026-08-20T02:04:16.624006Z","end":"2026-08-20T02:04:16.768999Z","steps":["trace[330219143] 'range keys from in-memory index tree' (duration: 144.741572ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:17.054551Z","caller":"traceutil/trace.go:171","msg":"trace[210603534] transaction","detail":"{read_only:false; response_revision:26020; number_of_response:1; }","duration":"281.214543ms","start":"2026-08-20T02:04:16.773317Z","end":"2026-08-20T02:04:17.054531Z","steps":["trace[210603534] 'process raft request' (duration: 281.064629ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:17.179834Z","caller":"traceutil/trace.go:171","msg":"trace[2110386766] linearizableReadLoop","detail":"{readStateIndex:26860; appliedIndex:26858; }","duration":"345.681763ms","start":"2026-08-20T02:04:16.834134Z","end":"2026-08-20T02:04:17.179816Z","steps":["trace[2110386766] 'read index received' (duration: 220.183949ms)","trace[2110386766] 'applied index is now lower than readState.Index' (duration: 125.497194ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:17.180171Z","caller":"traceutil/trace.go:171","msg":"trace[1258550734] transaction","detail":"{read_only:false; response_revision:26022; number_of_response:1; }","duration":"249.019864ms","start":"2026-08-20T02:04:16.931127Z","end":"2026-08-20T02:04:17.180147Z","steps":["trace[1258550734] 'process raft request' (duration: 248.625043ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:17.180261Z","caller":"traceutil/trace.go:171","msg":"trace[16940798] transaction","detail":"{read_only:false; response_revision:26021; number_of_response:1; }","duration":"406.690647ms","start":"2026-08-20T02:04:16.773381Z","end":"2026-08-20T02:04:17.180072Z","steps":["trace[16940798] 'process raft request' (duration: 366.540575ms)","trace[16940798] 'compare' (duration: 39.341949ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:17.180328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.162767ms","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":"warn","ts":"2026-08-20T02:04:17.180384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:16.773364Z","time spent":"406.958205ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:04:17.180421Z","caller":"traceutil/trace.go:171","msg":"trace[710783274] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:26022; }","duration":"346.27694ms","start":"2026-08-20T02:04:16.834123Z","end":"2026-08-20T02:04:17.1804Z","steps":["trace[710783274] 'agreement among raft nodes before linearized reading' (duration: 346.096805ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:17.180485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:16.83403Z","time spent":"346.433594ms","remote":"127.0.0.1:37164","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-20T02:04:17.212331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.034615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-08-20T02:04:17.21243Z","caller":"traceutil/trace.go:171","msg":"trace[2133571477] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:26023; }","duration":"205.170739ms","start":"2026-08-20T02:04:17.007235Z","end":"2026-08-20T02:04:17.212405Z","steps":["trace[2133571477] 'agreement among raft nodes before linearized reading' (duration: 204.910722ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:17.397234Z","caller":"traceutil/trace.go:171","msg":"trace[2083609240] transaction","detail":"{read_only:false; response_revision:26024; number_of_response:1; }","duration":"173.035462ms","start":"2026-08-20T02:04:17.224164Z","end":"2026-08-20T02:04:17.397199Z","steps":["trace[2083609240] 'process raft request' (duration: 162.385334ms)","trace[2083609240] 'compare' (duration: 10.414481ms)"],"step_count":2} {"level":"info","ts":"2026-08-20T02:04:17.772016Z","caller":"traceutil/trace.go:171","msg":"trace[940760539] transaction","detail":"{read_only:false; response_revision:26025; number_of_response:1; }","duration":"324.215823ms","start":"2026-08-20T02:04:17.447777Z","end":"2026-08-20T02:04:17.771993Z","steps":["trace[940760539] 'process raft request' (duration: 324.047789ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:17.772163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:17.447755Z","time spent":"324.334357ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:04:17.781389Z","caller":"traceutil/trace.go:171","msg":"trace[1019918918] linearizableReadLoop","detail":"{readStateIndex:26866; appliedIndex:26864; }","duration":"143.96106ms","start":"2026-08-20T02:04:17.637408Z","end":"2026-08-20T02:04:17.78137Z","steps":["trace[1019918918] 'read index received' (duration: 134.495996ms)","trace[1019918918] 'applied index is now lower than readState.Index' (duration: 9.464204ms)"],"step_count":2} {"level":"warn","ts":"2026-08-20T02:04:17.781585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.155686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-20T02:04:17.781627Z","caller":"traceutil/trace.go:171","msg":"trace[1605795520] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:26027; }","duration":"144.248618ms","start":"2026-08-20T02:04:17.637366Z","end":"2026-08-20T02:04:17.781614Z","steps":["trace[1605795520] 'agreement among raft nodes before linearized reading' (duration: 144.110434ms)"],"step_count":1} {"level":"info","ts":"2026-08-20T02:04:17.781412Z","caller":"traceutil/trace.go:171","msg":"trace[653916312] transaction","detail":"{read_only:false; response_revision:26026; number_of_response:1; }","duration":"318.253557ms","start":"2026-08-20T02:04:17.463141Z","end":"2026-08-20T02:04:17.781395Z","steps":["trace[653916312] 'process raft request' (duration: 318.056242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-20T02:04:17.781845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-20T02:04:17.463116Z","time spent":"318.69653ms","remote":"127.0.0.1:51770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-20T02:04:44.009079Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23258} {"level":"info","ts":"2026-08-20T02:04:44.221822Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23258,"took":"208.619245ms","hash":3672294767,"current-db-size-bytes":60063744,"current-db-size":"60 MB","current-db-size-in-use-bytes":39505920,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-08-20T02:04:44.221874Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3672294767,"revision":23258,"compact-revision":20361}