{"level":"warn","ts":"2026-03-09T01:39:10.230539Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-09T01:39:10.230655Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-03-09T01:39:10.230741Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-09T01:39:10.230762Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-03-09T01:39:10.230787Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-09T01:39:10.23315Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-03-09T01:39:10.233253Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-03-09T01:39:10.238301Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.771753ms"} {"level":"info","ts":"2026-03-09T01:39:10.246075Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-03-09T01:39:10.246177Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-03-09T01:39:10.24621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-03-09T01:39:10.24623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-09T01:39:10.246248Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-03-09T01:39:10.246303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-03-09T01:39:10.255509Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-09T01:39:10.258866Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-09T01:39:10.25987Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-03-09T01:39:10.261935Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-09T01:39:10.262233Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-09T01:39:10.262348Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-09T01:39:10.262359Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-03-09T01:39:10.262284Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-09T01:39:10.264171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-03-09T01:39:10.264333Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-03-09T01:39:10.266357Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-03-09T01:39:10.266455Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-03-09T01:39:10.266516Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-03-09T01:39:10.266748Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-09T01:39:10.266808Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-09T01:39:10.447469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-03-09T01:39:10.447606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-09T01:39:10.447645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-03-09T01:39:10.447673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-03-09T01:39:10.447842Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-03-09T01:39:10.447861Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-03-09T01:39:10.44795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-03-09T01:39:10.448995Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:39:10.449758Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T01:39:10.44978Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T01:39:10.449759Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-03-09T01:39:10.450127Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-09T01:39:10.450177Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-09T01:39:10.450426Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:39:10.450639Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:39:10.45069Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:39:10.453175Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-09T01:39:10.453571Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"warn","ts":"2026-03-09T01:39:38.19224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.844762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-03-09T01:39:38.192402Z","caller":"traceutil/trace.go:171","msg":"trace[1694922773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:461; }","duration":"122.083057ms","start":"2026-03-09T01:39:38.070298Z","end":"2026-03-09T01:39:38.192382Z","steps":["trace[1694922773] 'range keys from in-memory index tree' (duration: 121.712849ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:39:39.481873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.128262ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-03-09T01:39:45.877533Z","caller":"traceutil/trace.go:171","msg":"trace[591667979] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"205.321675ms","start":"2026-03-09T01:39:45.672178Z","end":"2026-03-09T01:39:45.877499Z","steps":["trace[591667979] 'read index received' (duration: 205.1619ms)","trace[591667979] 'applied index is now lower than readState.Index' (duration: 157.985µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:39:45.877585Z","caller":"traceutil/trace.go:171","msg":"trace[1345248900] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"244.990605ms","start":"2026-03-09T01:39:45.632563Z","end":"2026-03-09T01:39:45.877554Z","steps":["trace[1345248900] 'process raft request' (duration: 244.724048ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:39:45.877775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.57874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-nbvcr\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-03-09T01:39:45.877819Z","caller":"traceutil/trace.go:171","msg":"trace[272964309] range","detail":"{range_begin:/registry/pods/kube-system/cilium-nbvcr; range_end:; response_count:1; response_revision:507; }","duration":"205.671902ms","start":"2026-03-09T01:39:45.672137Z","end":"2026-03-09T01:39:45.877809Z","steps":["trace[272964309] 'agreement among raft nodes before linearized reading' (duration: 205.484898ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:39:46.136532Z","caller":"traceutil/trace.go:171","msg":"trace[418568194] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"244.952093ms","start":"2026-03-09T01:39:45.891554Z","end":"2026-03-09T01:39:46.136506Z","steps":["trace[418568194] 'process raft request' (duration: 244.754039ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:43:18.673201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.417987ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T01:43:18.67332Z","caller":"traceutil/trace.go:171","msg":"trace[1466883574] linearizableReadLoop","detail":"{readStateIndex:2577; appliedIndex:2576; }","duration":"119.501914ms","start":"2026-03-09T01:43:18.553803Z","end":"2026-03-09T01:43:18.673304Z","steps":["trace[1466883574] 'read index received' (duration: 11.525325ms)","trace[1466883574] 'applied index is now lower than readState.Index' (duration: 107.974179ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:43:18.67339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.576446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:43:18.673407Z","caller":"traceutil/trace.go:171","msg":"trace[83585835] transaction","detail":"{read_only:false; response_revision:2478; number_of_response:1; }","duration":"162.652032ms","start":"2026-03-09T01:43:18.510697Z","end":"2026-03-09T01:43:18.673349Z","steps":["trace[83585835] 'process raft request' (duration: 54.590331ms)","trace[83585835] 'compare' (duration: 107.299245ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:43:18.673453Z","caller":"traceutil/trace.go:171","msg":"trace[1675768912] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2478; }","duration":"119.650838ms","start":"2026-03-09T01:43:18.553776Z","end":"2026-03-09T01:43:18.673427Z","steps":["trace[1675768912] 'agreement among raft nodes before linearized reading' (duration: 119.574616ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:47:10.19187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.050079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-03-09T01:47:10.191955Z","caller":"traceutil/trace.go:171","msg":"trace[1223630361] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4523; }","duration":"168.152431ms","start":"2026-03-09T01:47:10.023781Z","end":"2026-03-09T01:47:10.191933Z","steps":["trace[1223630361] 'range keys from in-memory index tree' (duration: 167.932346ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:47:10.191989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.736525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-09T01:47:10.192085Z","caller":"traceutil/trace.go:171","msg":"trace[726712544] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4523; }","duration":"186.870607ms","start":"2026-03-09T01:47:10.005184Z","end":"2026-03-09T01:47:10.192054Z","steps":["trace[726712544] 'range keys from in-memory index tree' (duration: 186.55648ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:10.191992Z","caller":"traceutil/trace.go:171","msg":"trace[1011875635] transaction","detail":"{read_only:false; response_revision:4524; number_of_response:1; }","duration":"118.991691ms","start":"2026-03-09T01:47:10.072963Z","end":"2026-03-09T01:47:10.191954Z","steps":["trace[1011875635] 'process raft request' (duration: 61.970813ms)","trace[1011875635] 'compare' (duration: 56.869295ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:47:14.061787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.1183ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T01:47:14.061918Z","caller":"traceutil/trace.go:171","msg":"trace[582757857] transaction","detail":"{read_only:false; response_revision:4595; number_of_response:1; }","duration":"210.933875ms","start":"2026-03-09T01:47:13.850951Z","end":"2026-03-09T01:47:14.061885Z","steps":["trace[582757857] 'process raft request' (duration: 74.658331ms)","trace[582757857] 'compare' (duration: 136.015688ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:47:14.062011Z","caller":"traceutil/trace.go:171","msg":"trace[100282806] linearizableReadLoop","detail":"{readStateIndex:4775; appliedIndex:4774; }","duration":"121.408899ms","start":"2026-03-09T01:47:13.940538Z","end":"2026-03-09T01:47:14.061946Z","steps":["trace[100282806] 'read index received' (duration: 40.12µs)","trace[100282806] 'applied index is now lower than readState.Index' (duration: 121.358979ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:47:14.062263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.711606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-09T01:47:14.062344Z","caller":"traceutil/trace.go:171","msg":"trace[61545804] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4596; }","duration":"121.831108ms","start":"2026-03-09T01:47:13.940493Z","end":"2026-03-09T01:47:14.062324Z","steps":["trace[61545804] 'agreement among raft nodes before linearized reading' (duration: 121.537462ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:28.258436Z","caller":"traceutil/trace.go:171","msg":"trace[1973818450] linearizableReadLoop","detail":"{readStateIndex:5039; appliedIndex:5038; }","duration":"123.39968ms","start":"2026-03-09T01:47:28.135017Z","end":"2026-03-09T01:47:28.258417Z","steps":["trace[1973818450] 'read index received' (duration: 123.337509ms)","trace[1973818450] 'applied index is now lower than readState.Index' (duration: 61.091µs)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:47:28.258715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.680836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-09T01:47:28.258806Z","caller":"traceutil/trace.go:171","msg":"trace[2031282218] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4854; }","duration":"123.820579ms","start":"2026-03-09T01:47:28.134967Z","end":"2026-03-09T01:47:28.258787Z","steps":["trace[2031282218] 'agreement among raft nodes before linearized reading' (duration: 123.559554ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:28.258784Z","caller":"traceutil/trace.go:171","msg":"trace[1326568024] transaction","detail":"{read_only:false; response_revision:4854; number_of_response:1; }","duration":"143.057931ms","start":"2026-03-09T01:47:28.115688Z","end":"2026-03-09T01:47:28.258746Z","steps":["trace[1326568024] 'process raft request' (duration: 142.378277ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:28.478977Z","caller":"traceutil/trace.go:171","msg":"trace[2080244652] linearizableReadLoop","detail":"{readStateIndex:5040; appliedIndex:5039; }","duration":"215.058522ms","start":"2026-03-09T01:47:28.263893Z","end":"2026-03-09T01:47:28.478951Z","steps":["trace[2080244652] 'read index received' (duration: 123.537453ms)","trace[2080244652] 'applied index is now lower than readState.Index' (duration: 91.519869ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:47:28.478995Z","caller":"traceutil/trace.go:171","msg":"trace[970347861] transaction","detail":"{read_only:false; response_revision:4855; number_of_response:1; }","duration":"215.698675ms","start":"2026-03-09T01:47:28.263244Z","end":"2026-03-09T01:47:28.478942Z","steps":["trace[970347861] 'process raft request' (duration: 124.091555ms)","trace[970347861] 'compare' (duration: 91.389146ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:47:28.479203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.286997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b216e1f-0245-4853-898a-08a173b6e70c\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-03-09T01:47:28.479272Z","caller":"traceutil/trace.go:171","msg":"trace[2037671742] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1b216e1f-0245-4853-898a-08a173b6e70c; range_end:; response_count:1; response_revision:4855; }","duration":"215.405409ms","start":"2026-03-09T01:47:28.263848Z","end":"2026-03-09T01:47:28.479254Z","steps":["trace[2037671742] 'agreement among raft nodes before linearized reading' (duration: 215.222435ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:28.479373Z","caller":"traceutil/trace.go:171","msg":"trace[1806373642] transaction","detail":"{read_only:false; response_revision:4856; number_of_response:1; }","duration":"214.334287ms","start":"2026-03-09T01:47:28.264999Z","end":"2026-03-09T01:47:28.479333Z","steps":["trace[1806373642] 'process raft request' (duration: 213.901688ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:28.479589Z","caller":"traceutil/trace.go:171","msg":"trace[1655574438] transaction","detail":"{read_only:false; response_revision:4857; number_of_response:1; }","duration":"214.061652ms","start":"2026-03-09T01:47:28.265505Z","end":"2026-03-09T01:47:28.479567Z","steps":["trace[1655574438] 'process raft request' (duration: 213.688034ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:28.479662Z","caller":"traceutil/trace.go:171","msg":"trace[1888813102] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"213.884977ms","start":"2026-03-09T01:47:28.265749Z","end":"2026-03-09T01:47:28.479634Z","steps":["trace[1888813102] 'process raft request' (duration: 213.800385ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:28.47993Z","caller":"traceutil/trace.go:171","msg":"trace[184015301] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"213.658302ms","start":"2026-03-09T01:47:28.266258Z","end":"2026-03-09T01:47:28.479916Z","steps":["trace[184015301] 'process raft request' (duration: 213.330995ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:28.479995Z","caller":"traceutil/trace.go:171","msg":"trace[281585126] transaction","detail":"{read_only:false; response_revision:4858; number_of_response:1; }","duration":"214.419889ms","start":"2026-03-09T01:47:28.265549Z","end":"2026-03-09T01:47:28.479969Z","steps":["trace[281585126] 'process raft request' (duration: 213.922159ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:47:28.480187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.871479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-d43ec232-ec6f-4aa1-8cea-137ca20c07eb\" ","response":"range_response_count:1 size:1275"} {"level":"info","ts":"2026-03-09T01:47:28.480281Z","caller":"traceutil/trace.go:171","msg":"trace[711879209] range","detail":"{range_begin:/registry/persistentvolumes/pvc-d43ec232-ec6f-4aa1-8cea-137ca20c07eb; range_end:; response_count:1; response_revision:4862; }","duration":"216.018843ms","start":"2026-03-09T01:47:28.264222Z","end":"2026-03-09T01:47:28.480241Z","steps":["trace[711879209] 'agreement among raft nodes before linearized reading' (duration: 215.733977ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:28.480308Z","caller":"traceutil/trace.go:171","msg":"trace[1836256558] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"213.911417ms","start":"2026-03-09T01:47:28.266374Z","end":"2026-03-09T01:47:28.480285Z","steps":["trace[1836256558] 'process raft request' (duration: 213.394816ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:28.480326Z","caller":"traceutil/trace.go:171","msg":"trace[1692650077] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"199.603681ms","start":"2026-03-09T01:47:28.280691Z","end":"2026-03-09T01:47:28.480295Z","steps":["trace[1692650077] 'process raft request' (duration: 199.187852ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:47:28.480585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.481351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-1b216e1f-0245-4853-898a-08a173b6e70c\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-03-09T01:47:28.480597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.691687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-03-09T01:47:28.480627Z","caller":"traceutil/trace.go:171","msg":"trace[1017892169] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-1b216e1f-0245-4853-898a-08a173b6e70c; range_end:; response_count:1; response_revision:4862; }","duration":"165.564893ms","start":"2026-03-09T01:47:28.315048Z","end":"2026-03-09T01:47:28.480613Z","steps":["trace[1017892169] 'agreement among raft nodes before linearized reading' (duration: 165.4214ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:28.480646Z","caller":"traceutil/trace.go:171","msg":"trace[15989545] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4862; }","duration":"173.768508ms","start":"2026-03-09T01:47:28.306864Z","end":"2026-03-09T01:47:28.480632Z","steps":["trace[15989545] 'agreement among raft nodes before linearized reading' (duration: 173.638085ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:47:28.480719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.3235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-03-09T01:47:28.480754Z","caller":"traceutil/trace.go:171","msg":"trace[709042741] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4862; }","duration":"159.401221ms","start":"2026-03-09T01:47:28.321342Z","end":"2026-03-09T01:47:28.480743Z","steps":["trace[709042741] 'agreement among raft nodes before linearized reading' (duration: 159.268629ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:47:28.761648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.036338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T01:47:28.761743Z","caller":"traceutil/trace.go:171","msg":"trace[875221066] linearizableReadLoop","detail":"{readStateIndex:5051; appliedIndex:5050; }","duration":"146.644079ms","start":"2026-03-09T01:47:28.615087Z","end":"2026-03-09T01:47:28.761731Z","steps":["trace[875221066] 'read index received' (duration: 19.374315ms)","trace[875221066] 'applied index is now lower than readState.Index' (duration: 127.268794ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:47:28.762062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.687429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-03-09T01:47:28.762091Z","caller":"traceutil/trace.go:171","msg":"trace[1298837053] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4866; }","duration":"146.71685ms","start":"2026-03-09T01:47:28.615366Z","end":"2026-03-09T01:47:28.762083Z","steps":["trace[1298837053] 'agreement among raft nodes before linearized reading' (duration: 146.617398ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:47:28.762101Z","caller":"traceutil/trace.go:171","msg":"trace[581448577] transaction","detail":"{read_only:false; response_revision:4866; number_of_response:1; }","duration":"194.85444ms","start":"2026-03-09T01:47:28.567228Z","end":"2026-03-09T01:47:28.762083Z","steps":["trace[581448577] 'process raft request' (duration: 67.30955ms)","trace[581448577] 'compare' (duration: 126.929956ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:47:28.762093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.000176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-03-09T01:47:28.762165Z","caller":"traceutil/trace.go:171","msg":"trace[1771576865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4866; }","duration":"147.115838ms","start":"2026-03-09T01:47:28.61503Z","end":"2026-03-09T01:47:28.762145Z","steps":["trace[1771576865] 'agreement among raft nodes before linearized reading' (duration: 146.892394ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:47:30.41206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.900018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:47:30.412118Z","caller":"traceutil/trace.go:171","msg":"trace[311481666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4875; }","duration":"129.00769ms","start":"2026-03-09T01:47:30.283098Z","end":"2026-03-09T01:47:30.412106Z","steps":["trace[311481666] 'range keys from in-memory index tree' (duration: 128.802906ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:47:30.412424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.209515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-03-09T01:47:30.412559Z","caller":"traceutil/trace.go:171","msg":"trace[581027182] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:4875; }","duration":"201.339978ms","start":"2026-03-09T01:47:30.211177Z","end":"2026-03-09T01:47:30.412517Z","steps":["trace[581027182] 'range keys from in-memory index tree' (duration: 200.987531ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:47:40.46906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.417674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-03-09T01:47:40.469164Z","caller":"traceutil/trace.go:171","msg":"trace[996418580] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5051; }","duration":"175.561357ms","start":"2026-03-09T01:47:40.293576Z","end":"2026-03-09T01:47:40.469138Z","steps":["trace[996418580] 'range keys from in-memory index tree' (duration: 175.146179ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:49:10.748246Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2736} {"level":"info","ts":"2026-03-09T01:49:10.805352Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2736,"took":"55.573388ms","hash":1836223997,"current-db-size-bytes":41390080,"current-db-size":"41 MB","current-db-size-in-use-bytes":30879744,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-03-09T01:49:10.805415Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1836223997,"revision":2736,"compact-revision":-1} {"level":"info","ts":"2026-03-09T01:53:07.846311Z","caller":"traceutil/trace.go:171","msg":"trace[45111193] transaction","detail":"{read_only:false; response_revision:7425; number_of_response:1; }","duration":"129.320788ms","start":"2026-03-09T01:53:07.716949Z","end":"2026-03-09T01:53:07.846269Z","steps":["trace[45111193] 'process raft request' (duration: 129.038192ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:53:19.187332Z","caller":"traceutil/trace.go:171","msg":"trace[1077451467] transaction","detail":"{read_only:false; response_revision:7480; number_of_response:1; }","duration":"177.138094ms","start":"2026-03-09T01:53:19.010167Z","end":"2026-03-09T01:53:19.187305Z","steps":["trace[1077451467] 'process raft request' (duration: 176.967221ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:53:20.705816Z","caller":"traceutil/trace.go:171","msg":"trace[864337852] transaction","detail":"{read_only:false; response_revision:7488; number_of_response:1; }","duration":"126.794314ms","start":"2026-03-09T01:53:20.578969Z","end":"2026-03-09T01:53:20.705763Z","steps":["trace[864337852] 'process raft request' (duration: 98.047863ms)","trace[864337852] 'compare' (duration: 28.442834ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:53:20.705844Z","caller":"traceutil/trace.go:171","msg":"trace[1785491051] transaction","detail":"{read_only:false; response_revision:7489; number_of_response:1; }","duration":"126.330445ms","start":"2026-03-09T01:53:20.579485Z","end":"2026-03-09T01:53:20.705816Z","steps":["trace[1785491051] 'process raft request' (duration: 126.140411ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:53:48.561624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.627257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-03-09T01:53:48.561704Z","caller":"traceutil/trace.go:171","msg":"trace[873319423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7674; }","duration":"120.7478ms","start":"2026-03-09T01:53:48.440942Z","end":"2026-03-09T01:53:48.561689Z","steps":["trace[873319423] 'range keys from in-memory index tree' (duration: 120.477143ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:53:48.835289Z","caller":"traceutil/trace.go:171","msg":"trace[1205452896] transaction","detail":"{read_only:false; response_revision:7677; number_of_response:1; }","duration":"111.833549ms","start":"2026-03-09T01:53:48.723431Z","end":"2026-03-09T01:53:48.835264Z","steps":["trace[1205452896] 'process raft request' (duration: 111.532103ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:03.156242Z","caller":"traceutil/trace.go:171","msg":"trace[1233330342] transaction","detail":"{read_only:false; response_revision:7786; number_of_response:1; }","duration":"176.528195ms","start":"2026-03-09T01:54:02.97969Z","end":"2026-03-09T01:54:03.156218Z","steps":["trace[1233330342] 'process raft request' (duration: 168.7811ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:03.326008Z","caller":"traceutil/trace.go:171","msg":"trace[1039693443] transaction","detail":"{read_only:false; response_revision:7787; number_of_response:1; }","duration":"345.698225ms","start":"2026-03-09T01:54:02.980254Z","end":"2026-03-09T01:54:03.325952Z","steps":["trace[1039693443] 'process raft request' (duration: 345.342947ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:03.32627Z","caller":"traceutil/trace.go:171","msg":"trace[863434855] transaction","detail":"{read_only:false; number_of_response:1; response_revision:7788; }","duration":"344.752985ms","start":"2026-03-09T01:54:02.981496Z","end":"2026-03-09T01:54:03.326249Z","steps":["trace[863434855] 'process raft request' (duration: 344.342095ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:03.326297Z","caller":"traceutil/trace.go:171","msg":"trace[1580380] transaction","detail":"{read_only:false; response_revision:7789; number_of_response:1; }","duration":"342.027267ms","start":"2026-03-09T01:54:02.984235Z","end":"2026-03-09T01:54:03.326262Z","steps":["trace[1580380] 'process raft request' (duration: 341.684129ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:54:03.326276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:54:02.980232Z","time spent":"345.91088ms","remote":"127.0.0.1:56842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T01:54:03.326467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:54:02.984215Z","time spent":"342.164081ms","remote":"127.0.0.1:56748","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4630,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T01:54:03.326488Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:54:02.981484Z","time spent":"344.862327ms","remote":"127.0.0.1:51776","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80,"response count":0,"response size":39,"request content":"compare: success: > failure: >"} {"level":"info","ts":"2026-03-09T01:54:03.996394Z","caller":"traceutil/trace.go:171","msg":"trace[1579372557] linearizableReadLoop","detail":"{readStateIndex:8080; appliedIndex:8079; }","duration":"127.970853ms","start":"2026-03-09T01:54:03.868376Z","end":"2026-03-09T01:54:03.996347Z","steps":["trace[1579372557] 'read index received' (duration: 127.771549ms)","trace[1579372557] 'applied index is now lower than readState.Index' (duration: 197.414µs)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:54:03.996656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.252039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-03-09T01:54:03.996701Z","caller":"traceutil/trace.go:171","msg":"trace[2094470065] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:7794; }","duration":"128.352961ms","start":"2026-03-09T01:54:03.868336Z","end":"2026-03-09T01:54:03.996689Z","steps":["trace[2094470065] 'agreement among raft nodes before linearized reading' (duration: 128.150717ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:03.996845Z","caller":"traceutil/trace.go:171","msg":"trace[1761247511] transaction","detail":"{read_only:false; response_revision:7794; number_of_response:1; }","duration":"200.593978ms","start":"2026-03-09T01:54:03.796195Z","end":"2026-03-09T01:54:03.996789Z","steps":["trace[1761247511] 'process raft request' (duration: 199.958135ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:04.137215Z","caller":"traceutil/trace.go:171","msg":"trace[1130775173] transaction","detail":"{read_only:false; response_revision:7795; number_of_response:1; }","duration":"234.573321ms","start":"2026-03-09T01:54:03.902606Z","end":"2026-03-09T01:54:04.137179Z","steps":["trace[1130775173] 'process raft request' (duration: 230.674338ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:04.72155Z","caller":"traceutil/trace.go:171","msg":"trace[469413536] linearizableReadLoop","detail":"{readStateIndex:8086; appliedIndex:8085; }","duration":"127.602905ms","start":"2026-03-09T01:54:04.593925Z","end":"2026-03-09T01:54:04.721528Z","steps":["trace[469413536] 'read index received' (duration: 127.38938ms)","trace[469413536] 'applied index is now lower than readState.Index' (duration: 212.505µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:54:04.721641Z","caller":"traceutil/trace.go:171","msg":"trace[317626789] transaction","detail":"{read_only:false; response_revision:7799; number_of_response:1; }","duration":"150.356729ms","start":"2026-03-09T01:54:04.571242Z","end":"2026-03-09T01:54:04.721599Z","steps":["trace[317626789] 'process raft request' (duration: 150.141565ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:54:04.722016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.058595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-03-09T01:54:04.722122Z","caller":"traceutil/trace.go:171","msg":"trace[1246400117] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:7799; }","duration":"128.248179ms","start":"2026-03-09T01:54:04.593845Z","end":"2026-03-09T01:54:04.722094Z","steps":["trace[1246400117] 'agreement among raft nodes before linearized reading' (duration: 127.82569ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:04.853972Z","caller":"traceutil/trace.go:171","msg":"trace[730990625] transaction","detail":"{read_only:false; response_revision:7801; number_of_response:1; }","duration":"126.700156ms","start":"2026-03-09T01:54:04.727234Z","end":"2026-03-09T01:54:04.853934Z","steps":["trace[730990625] 'process raft request' (duration: 126.405019ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:04.854381Z","caller":"traceutil/trace.go:171","msg":"trace[147058325] linearizableReadLoop","detail":"{readStateIndex:8088; appliedIndex:8086; }","duration":"126.937201ms","start":"2026-03-09T01:54:04.727429Z","end":"2026-03-09T01:54:04.854367Z","steps":["trace[147058325] 'read index received' (duration: 13.136099ms)","trace[147058325] 'applied index is now lower than readState.Index' (duration: 113.799422ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:54:04.854468Z","caller":"traceutil/trace.go:171","msg":"trace[494971093] transaction","detail":"{read_only:false; response_revision:7803; number_of_response:1; }","duration":"118.759306ms","start":"2026-03-09T01:54:04.735668Z","end":"2026-03-09T01:54:04.854427Z","steps":["trace[494971093] 'process raft request' (duration: 118.605153ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:54:04.854632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.491942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:356"} {"level":"info","ts":"2026-03-09T01:54:04.854684Z","caller":"traceutil/trace.go:171","msg":"trace[2075854803] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:7803; }","duration":"127.615234ms","start":"2026-03-09T01:54:04.727055Z","end":"2026-03-09T01:54:04.85467Z","steps":["trace[2075854803] 'agreement among raft nodes before linearized reading' (duration: 127.39965ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:04.854673Z","caller":"traceutil/trace.go:171","msg":"trace[524935977] transaction","detail":"{read_only:false; response_revision:7802; number_of_response:1; }","duration":"126.779578ms","start":"2026-03-09T01:54:04.727819Z","end":"2026-03-09T01:54:04.854599Z","steps":["trace[524935977] 'process raft request' (duration: 126.035792ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:04.924787Z","caller":"traceutil/trace.go:171","msg":"trace[214412965] transaction","detail":"{read_only:false; response_revision:7804; number_of_response:1; }","duration":"146.858814ms","start":"2026-03-09T01:54:04.777901Z","end":"2026-03-09T01:54:04.92476Z","steps":["trace[214412965] 'process raft request' (duration: 146.498667ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:54:04.925264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.720703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19435"} {"level":"info","ts":"2026-03-09T01:54:04.925324Z","caller":"traceutil/trace.go:171","msg":"trace[2141322063] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:7804; }","duration":"195.832167ms","start":"2026-03-09T01:54:04.729479Z","end":"2026-03-09T01:54:04.925312Z","steps":["trace[2141322063] 'agreement among raft nodes before linearized reading' (duration: 195.618412ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:54:10.754582Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5687} {"level":"info","ts":"2026-03-09T01:54:10.841431Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5687,"took":"85.064149ms","hash":3362321405,"current-db-size-bytes":43577344,"current-db-size":"44 MB","current-db-size-in-use-bytes":23539712,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-03-09T01:54:10.841499Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3362321405,"revision":5687,"compact-revision":2736} {"level":"info","ts":"2026-03-09T01:55:01.922398Z","caller":"traceutil/trace.go:171","msg":"trace[696964360] transaction","detail":"{read_only:false; response_revision:8411; number_of_response:1; }","duration":"100.13049ms","start":"2026-03-09T01:55:01.822242Z","end":"2026-03-09T01:55:01.922372Z","steps":["trace[696964360] 'process raft request' (duration: 99.873854ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:58:02.44219Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-03-09T01:58:02.447087Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-03-09T01:58:02.447158Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-03-09T01:59:10.763546Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7849} {"level":"info","ts":"2026-03-09T01:59:10.830213Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7849,"took":"64.411537ms","hash":3876622840,"current-db-size-bytes":43577344,"current-db-size":"44 MB","current-db-size-in-use-bytes":26136576,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-03-09T01:59:10.830277Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3876622840,"revision":7849,"compact-revision":5687} {"level":"info","ts":"2026-03-09T02:04:10.768604Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9981} {"level":"info","ts":"2026-03-09T02:04:10.841317Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9981,"took":"70.893935ms","hash":4113033388,"current-db-size-bytes":43577344,"current-db-size":"44 MB","current-db-size-in-use-bytes":16642048,"current-db-size-in-use":"17 MB"} {"level":"info","ts":"2026-03-09T02:04:10.841401Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4113033388,"revision":9981,"compact-revision":7849}