{"level":"warn","ts":"2026-10-02T09:05:02.257487Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T09:05:02.257602Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.24: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.24:2380","--initial-cluster=instance=https://199.204.45.24:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.24:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.24:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-10-02T09:05:02.257717Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-02T09:05:02.257746Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.24:2380"]} {"level":"info","ts":"2026-10-02T09:05:02.257789Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-02T09:05:02.258991Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.24:2379"]} {"level":"info","ts":"2026-10-02T09:05:02.259193Z","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":8,"max-cpu-available":8,"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.24:2380"],"listen-peer-urls":["https://199.204.45.24:2380"],"advertise-client-urls":["https://199.204.45.24:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.24:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.24:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-10-02T09:05:02.304574Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"44.94758ms"} {"level":"info","ts":"2026-10-02T09:05:02.338685Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2100e0337b5be84b","cluster-id":"e85d36dc6ee4c031"} {"level":"info","ts":"2026-10-02T09:05:02.338854Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2100e0337b5be84b switched to configuration voters=()"} {"level":"info","ts":"2026-10-02T09:05:02.338949Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2100e0337b5be84b became follower at term 0"} {"level":"info","ts":"2026-10-02T09:05:02.339036Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2100e0337b5be84b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-02T09:05:02.339091Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2100e0337b5be84b became follower at term 1"} {"level":"info","ts":"2026-10-02T09:05:02.339182Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2100e0337b5be84b switched to configuration voters=(2378147114969196619)"} {"level":"warn","ts":"2026-10-02T09:05:02.398068Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-02T09:05:02.41566Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-02T09:05:02.423177Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-10-02T09:05:02.4399Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2100e0337b5be84b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-02T09:05:02.440299Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2100e0337b5be84b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-02T09:05:02.44047Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T09:05:02.440566Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T09:05:02.44058Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-10-02T09:05:02.460123Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-10-02T09:05:02.460327Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.24:2380"} {"level":"info","ts":"2026-10-02T09:05:02.460379Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.24:2380"} {"level":"info","ts":"2026-10-02T09:05:02.460656Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2100e0337b5be84b","initial-advertise-peer-urls":["https://199.204.45.24:2380"],"listen-peer-urls":["https://199.204.45.24:2380"],"advertise-client-urls":["https://199.204.45.24:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.24:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-02T09:05:02.460705Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-02T09:05:02.465301Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2100e0337b5be84b switched to configuration voters=(2378147114969196619)"} {"level":"info","ts":"2026-10-02T09:05:02.465467Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e85d36dc6ee4c031","local-member-id":"2100e0337b5be84b","added-peer-id":"2100e0337b5be84b","added-peer-peer-urls":["https://199.204.45.24:2380"]} {"level":"info","ts":"2026-10-02T09:05:03.340357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2100e0337b5be84b is starting a new election at term 1"} {"level":"info","ts":"2026-10-02T09:05:03.340434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2100e0337b5be84b became pre-candidate at term 1"} {"level":"info","ts":"2026-10-02T09:05:03.340475Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2100e0337b5be84b received MsgPreVoteResp from 2100e0337b5be84b at term 1"} {"level":"info","ts":"2026-10-02T09:05:03.340496Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2100e0337b5be84b became candidate at term 2"} {"level":"info","ts":"2026-10-02T09:05:03.340505Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2100e0337b5be84b received MsgVoteResp from 2100e0337b5be84b at term 2"} {"level":"info","ts":"2026-10-02T09:05:03.340518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2100e0337b5be84b became leader at term 2"} {"level":"info","ts":"2026-10-02T09:05:03.340538Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2100e0337b5be84b elected leader 2100e0337b5be84b at term 2"} {"level":"info","ts":"2026-10-02T09:05:03.341614Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:05:03.34303Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T09:05:03.343032Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2100e0337b5be84b","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.24:2379]}","request-path":"/0/members/2100e0337b5be84b/attributes","cluster-id":"e85d36dc6ee4c031","publish-timeout":"7s"} {"level":"info","ts":"2026-10-02T09:05:03.343297Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-02T09:05:03.343593Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-02T09:05:03.343622Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-02T09:05:03.345526Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.24:2379"} {"level":"info","ts":"2026-10-02T09:05:03.345659Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e85d36dc6ee4c031","local-member-id":"2100e0337b5be84b","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:05:03.345797Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:05:03.345835Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-02T09:05:03.34681Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-02T09:05:21.636111Z","caller":"traceutil/trace.go:171","msg":"trace[1045131562] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"130.559099ms","start":"2026-10-02T09:05:21.505518Z","end":"2026-10-02T09:05:21.636077Z","steps":["trace[1045131562] 'process raft request' (duration: 130.354822ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:05:45.292042Z","caller":"traceutil/trace.go:171","msg":"trace[769116243] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"186.919705ms","start":"2026-10-02T09:05:45.105093Z","end":"2026-10-02T09:05:45.292012Z","steps":["trace[769116243] 'read index received' (duration: 186.77583ms)","trace[769116243] 'applied index is now lower than readState.Index' (duration: 142.635µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:05:45.292404Z","caller":"traceutil/trace.go:171","msg":"trace[945532689] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"263.169774ms","start":"2026-10-02T09:05:45.029192Z","end":"2026-10-02T09:05:45.292362Z","steps":["trace[945532689] 'process raft request' (duration: 262.584004ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:45.292785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.553176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-02T09:05:45.292871Z","caller":"traceutil/trace.go:171","msg":"trace[1757901294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"187.827186ms","start":"2026-10-02T09:05:45.105023Z","end":"2026-10-02T09:05:45.29285Z","steps":["trace[1757901294] 'agreement among raft nodes before linearized reading' (duration: 187.110321ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:05:45.425173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.391114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:05:45.425317Z","caller":"traceutil/trace.go:171","msg":"trace[709409245] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:630; }","duration":"116.520369ms","start":"2026-10-02T09:05:45.308715Z","end":"2026-10-02T09:05:45.425236Z","steps":["trace[709409245] 'agreement among raft nodes before linearized reading' (duration: 71.547374ms)","trace[709409245] 'range keys from in-memory index tree' (duration: 44.84343ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:05:45.425985Z","caller":"traceutil/trace.go:171","msg":"trace[130966042] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"108.55558ms","start":"2026-10-02T09:05:45.317406Z","end":"2026-10-02T09:05:45.425961Z","steps":["trace[130966042] 'process raft request' (duration: 102.967484ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:06:30.964533Z","caller":"traceutil/trace.go:171","msg":"trace[1717843337] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"195.958427ms","start":"2026-10-02T09:06:30.768545Z","end":"2026-10-02T09:06:30.964503Z","steps":["trace[1717843337] 'process raft request' (duration: 165.981636ms)","trace[1717843337] 'compare' (duration: 29.664149ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:07:18.013341Z","caller":"traceutil/trace.go:171","msg":"trace[1498912533] transaction","detail":"{read_only:false; response_revision:1859; number_of_response:1; }","duration":"233.025245ms","start":"2026-10-02T09:07:17.780291Z","end":"2026-10-02T09:07:18.013316Z","steps":["trace[1498912533] 'process raft request' (duration: 232.739274ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:07:18.013754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.999875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c2cb616f-e321-4d6c-b089-6e87058392d7\" ","response":"range_response_count:1 size:3746"} {"level":"info","ts":"2026-10-02T09:07:18.013862Z","caller":"traceutil/trace.go:171","msg":"trace[1001099425] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c2cb616f-e321-4d6c-b089-6e87058392d7; range_end:; response_count:1; response_revision:1859; }","duration":"211.155291ms","start":"2026-10-02T09:07:17.802661Z","end":"2026-10-02T09:07:18.013816Z","steps":["trace[1001099425] 'agreement among raft nodes before linearized reading' (duration: 210.983684ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:07:18.013885Z","caller":"traceutil/trace.go:171","msg":"trace[2070222196] linearizableReadLoop","detail":"{readStateIndex:1932; appliedIndex:1931; }","duration":"210.463923ms","start":"2026-10-02T09:07:17.802731Z","end":"2026-10-02T09:07:18.013195Z","steps":["trace[2070222196] 'read index received' (duration: 210.251145ms)","trace[2070222196] 'applied index is now lower than readState.Index' (duration: 211.868µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:07:18.051982Z","caller":"traceutil/trace.go:171","msg":"trace[506291679] transaction","detail":"{read_only:false; response_revision:1860; number_of_response:1; }","duration":"174.003037ms","start":"2026-10-02T09:07:17.877953Z","end":"2026-10-02T09:07:18.051956Z","steps":["trace[506291679] 'process raft request' (duration: 173.806659ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:07:18.052369Z","caller":"traceutil/trace.go:171","msg":"trace[1091327054] transaction","detail":"{read_only:false; response_revision:1861; number_of_response:1; }","duration":"162.630308ms","start":"2026-10-02T09:07:17.889729Z","end":"2026-10-02T09:07:18.05236Z","steps":["trace[1091327054] 'process raft request' (duration: 162.18351ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:07:40.861334Z","caller":"traceutil/trace.go:171","msg":"trace[533087576] transaction","detail":"{read_only:false; response_revision:2045; number_of_response:1; }","duration":"121.701989ms","start":"2026-10-02T09:07:40.739567Z","end":"2026-10-02T09:07:40.861269Z","steps":["trace[533087576] 'process raft request' (duration: 121.396456ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:07:46.891378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.114288ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:07:46.891492Z","caller":"traceutil/trace.go:171","msg":"trace[1221032597] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"256.92046ms","start":"2026-10-02T09:07:46.634543Z","end":"2026-10-02T09:07:46.891463Z","steps":["trace[1221032597] 'process raft request' (duration: 99.133196ms)","trace[1221032597] 'compare' (duration: 156.459032ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:08:59.051519Z","caller":"traceutil/trace.go:171","msg":"trace[1321229257] linearizableReadLoop","detail":"{readStateIndex:2655; appliedIndex:2654; }","duration":"129.242097ms","start":"2026-10-02T09:08:58.922254Z","end":"2026-10-02T09:08:59.051496Z","steps":["trace[1321229257] 'read index received' (duration: 129.109586ms)","trace[1321229257] 'applied index is now lower than readState.Index' (duration: 131.331µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:08:59.05159Z","caller":"traceutil/trace.go:171","msg":"trace[1673772778] transaction","detail":"{read_only:false; response_revision:2559; number_of_response:1; }","duration":"177.452923ms","start":"2026-10-02T09:08:58.874104Z","end":"2026-10-02T09:08:59.051557Z","steps":["trace[1673772778] 'process raft request' (duration: 177.247551ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:08:59.051734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.45674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-10-02T09:08:59.05179Z","caller":"traceutil/trace.go:171","msg":"trace[2084896534] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2559; }","duration":"129.567612ms","start":"2026-10-02T09:08:58.922207Z","end":"2026-10-02T09:08:59.051775Z","steps":["trace[2084896534] 'agreement among raft nodes before linearized reading' (duration: 129.38402ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:09:24.834326Z","caller":"traceutil/trace.go:171","msg":"trace[379585599] transaction","detail":"{read_only:false; response_revision:2703; number_of_response:1; }","duration":"109.885689ms","start":"2026-10-02T09:09:24.724406Z","end":"2026-10-02T09:09:24.834292Z","steps":["trace[379585599] 'process raft request' (duration: 109.674186ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:09:30.302493Z","caller":"traceutil/trace.go:171","msg":"trace[1971741803] transaction","detail":"{read_only:false; response_revision:2732; number_of_response:1; }","duration":"151.602402ms","start":"2026-10-02T09:09:30.150867Z","end":"2026-10-02T09:09:30.30247Z","steps":["trace[1971741803] 'process raft request' (duration: 151.440969ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:13:59.993703Z","caller":"traceutil/trace.go:171","msg":"trace[1753416891] transaction","detail":"{read_only:false; response_revision:4746; number_of_response:1; }","duration":"161.729424ms","start":"2026-10-02T09:13:59.831919Z","end":"2026-10-02T09:13:59.993649Z","steps":["trace[1753416891] 'process raft request' (duration: 101.834697ms)","trace[1753416891] 'compare' (duration: 59.749953ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:14:08.684346Z","caller":"traceutil/trace.go:171","msg":"trace[397971224] linearizableReadLoop","detail":"{readStateIndex:5156; appliedIndex:5155; }","duration":"138.393603ms","start":"2026-10-02T09:14:08.545934Z","end":"2026-10-02T09:14:08.684328Z","steps":["trace[397971224] 'read index received' (duration: 138.243118ms)","trace[397971224] 'applied index is now lower than readState.Index' (duration: 149.715µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:14:08.684506Z","caller":"traceutil/trace.go:171","msg":"trace[1854893632] transaction","detail":"{read_only:false; response_revision:4967; number_of_response:1; }","duration":"203.430897ms","start":"2026-10-02T09:14:08.481057Z","end":"2026-10-02T09:14:08.684488Z","steps":["trace[1854893632] 'process raft request' (duration: 203.069626ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:08.684672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.720194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-10-02T09:14:08.684783Z","caller":"traceutil/trace.go:171","msg":"trace[2008913561] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4967; }","duration":"138.825687ms","start":"2026-10-02T09:14:08.545901Z","end":"2026-10-02T09:14:08.684727Z","steps":["trace[2008913561] 'agreement among raft nodes before linearized reading' (duration: 138.6073ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:14:09.072531Z","caller":"traceutil/trace.go:171","msg":"trace[47868351] transaction","detail":"{read_only:false; response_revision:4971; number_of_response:1; }","duration":"363.734479ms","start":"2026-10-02T09:14:08.708775Z","end":"2026-10-02T09:14:09.072509Z","steps":["trace[47868351] 'process raft request' (duration: 363.687317ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:14:09.072604Z","caller":"traceutil/trace.go:171","msg":"trace[1621842369] transaction","detail":"{read_only:false; response_revision:4969; number_of_response:1; }","duration":"448.728079ms","start":"2026-10-02T09:14:08.62386Z","end":"2026-10-02T09:14:09.072588Z","steps":["trace[1621842369] 'process raft request' (duration: 448.528483ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:09.072658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:08.70876Z","time spent":"363.843013ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:14:09.072693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:08.623836Z","time spent":"448.800752ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T09:14:09.072842Z","caller":"traceutil/trace.go:171","msg":"trace[1991714754] linearizableReadLoop","detail":"{readStateIndex:5158; appliedIndex:5156; }","duration":"388.379779ms","start":"2026-10-02T09:14:08.684454Z","end":"2026-10-02T09:14:09.072833Z","steps":["trace[1991714754] 'read index received' (duration: 363.522173ms)","trace[1991714754] 'applied index is now lower than readState.Index' (duration: 24.856636ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:14:09.072838Z","caller":"traceutil/trace.go:171","msg":"trace[684303750] transaction","detail":"{read_only:false; response_revision:4968; number_of_response:1; }","duration":"485.642732ms","start":"2026-10-02T09:14:08.587145Z","end":"2026-10-02T09:14:09.072787Z","steps":["trace[684303750] 'process raft request' (duration: 460.818616ms)","trace[684303750] 'compare' (duration: 24.250426ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:14:09.072899Z","caller":"traceutil/trace.go:171","msg":"trace[853566759] transaction","detail":"{read_only:false; response_revision:4970; number_of_response:1; }","duration":"384.044307ms","start":"2026-10-02T09:14:08.688849Z","end":"2026-10-02T09:14:09.072893Z","steps":["trace[853566759] 'process raft request' (duration: 383.590541ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:09.072939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:08.688833Z","time spent":"384.084239ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:14:09.072975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:08.587123Z","time spent":"485.789756ms","remote":"127.0.0.1:60486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":876,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-02T09:14:09.073048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.258352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-10-02T09:14:09.073075Z","caller":"traceutil/trace.go:171","msg":"trace[1040025719] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4971; }","duration":"433.305054ms","start":"2026-10-02T09:14:08.639761Z","end":"2026-10-02T09:14:09.073066Z","steps":["trace[1040025719] 'agreement among raft nodes before linearized reading' (duration: 433.223231ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:09.073095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:08.639751Z","time spent":"433.339334ms","remote":"127.0.0.1:60726","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-10-02T09:14:09.073148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.560638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cbd600a4-b956-421e-af8b-9c72a3dc5a9e\" ","response":"range_response_count:1 size:3645"} {"level":"warn","ts":"2026-10-02T09:14:09.073182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.02235ms","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-10-02T09:14:09.073201Z","caller":"traceutil/trace.go:171","msg":"trace[133008259] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4971; }","duration":"258.073732ms","start":"2026-10-02T09:14:08.81512Z","end":"2026-10-02T09:14:09.073194Z","steps":["trace[133008259] 'agreement among raft nodes before linearized reading' (duration: 258.02348ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:09.073208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.515086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cbd600a4-b956-421e-af8b-9c72a3dc5a9e\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-10-02T09:14:09.073202Z","caller":"traceutil/trace.go:171","msg":"trace[1273334291] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cbd600a4-b956-421e-af8b-9c72a3dc5a9e; range_end:; response_count:1; response_revision:4971; }","duration":"304.658421ms","start":"2026-10-02T09:14:08.768528Z","end":"2026-10-02T09:14:09.073187Z","steps":["trace[1273334291] 'agreement among raft nodes before linearized reading' (duration: 304.515986ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:14:09.073233Z","caller":"traceutil/trace.go:171","msg":"trace[348935242] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cbd600a4-b956-421e-af8b-9c72a3dc5a9e; range_end:; response_count:1; response_revision:4971; }","duration":"520.558427ms","start":"2026-10-02T09:14:08.552667Z","end":"2026-10-02T09:14:09.073225Z","steps":["trace[348935242] 'agreement among raft nodes before linearized reading' (duration: 520.498295ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:09.073253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:08.552657Z","time spent":"520.590609ms","remote":"127.0.0.1:60604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cbd600a4-b956-421e-af8b-9c72a3dc5a9e\" "} {"level":"warn","ts":"2026-10-02T09:14:09.073247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:08.768413Z","time spent":"304.822146ms","remote":"127.0.0.1:60604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cbd600a4-b956-421e-af8b-9c72a3dc5a9e\" "} {"level":"warn","ts":"2026-10-02T09:14:11.476822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.025883ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:14:11.477029Z","caller":"traceutil/trace.go:171","msg":"trace[988560876] linearizableReadLoop","detail":"{readStateIndex:5179; appliedIndex:5176; }","duration":"232.542284ms","start":"2026-10-02T09:14:11.244471Z","end":"2026-10-02T09:14:11.477013Z","steps":["trace[988560876] 'read index received' (duration: 56.152µs)","trace[988560876] 'applied index is now lower than readState.Index' (duration: 232.485022ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:14:11.47717Z","caller":"traceutil/trace.go:171","msg":"trace[350483404] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"328.393474ms","start":"2026-10-02T09:14:11.148721Z","end":"2026-10-02T09:14:11.477114Z","steps":["trace[350483404] 'process raft request' (duration: 72.966458ms)","trace[350483404] 'compare' (duration: 254.919069ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:14:11.477101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.645677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:14:11.477258Z","caller":"traceutil/trace.go:171","msg":"trace[785514486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4990; }","duration":"232.840153ms","start":"2026-10-02T09:14:11.244406Z","end":"2026-10-02T09:14:11.477246Z","steps":["trace[785514486] 'agreement among raft nodes before linearized reading' (duration: 232.651317ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:11.477335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:11.148701Z","time spent":"328.541709ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T09:14:11.477352Z","caller":"traceutil/trace.go:171","msg":"trace[524575348] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"328.493377ms","start":"2026-10-02T09:14:11.148841Z","end":"2026-10-02T09:14:11.477335Z","steps":["trace[524575348] 'process raft request' (duration: 328.071593ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:11.477436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:11.148824Z","time spent":"328.5685ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T09:14:11.477461Z","caller":"traceutil/trace.go:171","msg":"trace[1670516174] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"328.371473ms","start":"2026-10-02T09:14:11.149068Z","end":"2026-10-02T09:14:11.47744Z","steps":["trace[1670516174] 'process raft request' (duration: 327.905328ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:11.477574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:11.149051Z","time spent":"328.473116ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T09:14:11.626424Z","caller":"traceutil/trace.go:171","msg":"trace[2047714549] linearizableReadLoop","detail":"{readStateIndex:5180; appliedIndex:5179; }","duration":"147.482928ms","start":"2026-10-02T09:14:11.47889Z","end":"2026-10-02T09:14:11.626373Z","steps":["trace[2047714549] 'read index received' (duration: 147.310022ms)","trace[2047714549] 'applied index is now lower than readState.Index' (duration: 171.736µs)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:14:11.626434Z","caller":"traceutil/trace.go:171","msg":"trace[1785114168] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"377.025113ms","start":"2026-10-02T09:14:11.249383Z","end":"2026-10-02T09:14:11.626408Z","steps":["trace[1785114168] 'process raft request' (duration: 376.865838ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:11.626569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.658454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:14:11.626902Z","caller":"traceutil/trace.go:171","msg":"trace[140271619] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4991; }","duration":"148.002156ms","start":"2026-10-02T09:14:11.478883Z","end":"2026-10-02T09:14:11.626885Z","steps":["trace[140271619] 'agreement among raft nodes before linearized reading' (duration: 147.608663ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:11.626842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:11.249351Z","time spent":"377.401385ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:14:13.582942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.070657ms","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":"warn","ts":"2026-10-02T09:14:13.58308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.252484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-02T09:14:13.583164Z","caller":"traceutil/trace.go:171","msg":"trace[2039067834] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5017; }","duration":"102.369828ms","start":"2026-10-02T09:14:13.480772Z","end":"2026-10-02T09:14:13.583141Z","steps":["trace[2039067834] 'range keys from in-memory index tree' (duration: 101.150697ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:14:13.583022Z","caller":"traceutil/trace.go:171","msg":"trace[1101826762] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5017; }","duration":"102.231874ms","start":"2026-10-02T09:14:13.480767Z","end":"2026-10-02T09:14:13.582999Z","steps":["trace[1101826762] 'range keys from in-memory index tree' (duration: 101.149838ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:13.583349Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.43884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-10-02T09:14:13.58342Z","caller":"traceutil/trace.go:171","msg":"trace[120380651] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5017; }","duration":"102.615057ms","start":"2026-10-02T09:14:13.480793Z","end":"2026-10-02T09:14:13.583408Z","steps":["trace[120380651] 'range keys from in-memory index tree' (duration: 101.095556ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:24.922071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.714984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:14:24.922191Z","caller":"traceutil/trace.go:171","msg":"trace[1986504169] linearizableReadLoop","detail":"{readStateIndex:5347; appliedIndex:5346; }","duration":"264.887108ms","start":"2026-10-02T09:14:24.657283Z","end":"2026-10-02T09:14:24.922171Z","steps":["trace[1986504169] 'read index received' (duration: 129.929695ms)","trace[1986504169] 'applied index is now lower than readState.Index' (duration: 134.955362ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:14:24.922244Z","caller":"traceutil/trace.go:171","msg":"trace[358895213] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"282.364207ms","start":"2026-10-02T09:14:24.639841Z","end":"2026-10-02T09:14:24.922205Z","steps":["trace[358895213] 'process raft request' (duration: 147.431485ms)","trace[358895213] 'compare' (duration: 134.556009ms)"],"step_count":2} {"level":"warn","ts":"2026-10-02T09:14:24.922402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.089534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"info","ts":"2026-10-02T09:14:24.922454Z","caller":"traceutil/trace.go:171","msg":"trace[566181505] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5152; }","duration":"265.184947ms","start":"2026-10-02T09:14:24.657255Z","end":"2026-10-02T09:14:24.92244Z","steps":["trace[566181505] 'agreement among raft nodes before linearized reading' (duration: 265.013452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:24.923095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.617144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9635"} {"level":"info","ts":"2026-10-02T09:14:24.923166Z","caller":"traceutil/trace.go:171","msg":"trace[297230974] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5152; }","duration":"236.728288ms","start":"2026-10-02T09:14:24.686421Z","end":"2026-10-02T09:14:24.923149Z","steps":["trace[297230974] 'agreement among raft nodes before linearized reading' (duration: 235.9313ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:14:25.07403Z","caller":"traceutil/trace.go:171","msg":"trace[557183102] linearizableReadLoop","detail":"{readStateIndex:5350; appliedIndex:5348; }","duration":"123.904157ms","start":"2026-10-02T09:14:24.950103Z","end":"2026-10-02T09:14:25.074007Z","steps":["trace[557183102] 'read index received' (duration: 29.145834ms)","trace[557183102] 'applied index is now lower than readState.Index' (duration: 94.757503ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:14:25.074292Z","caller":"traceutil/trace.go:171","msg":"trace[1369273813] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"144.577671ms","start":"2026-10-02T09:14:24.929698Z","end":"2026-10-02T09:14:25.074276Z","steps":["trace[1369273813] 'process raft request' (duration: 104.372692ms)","trace[1369273813] 'compare' (duration: 39.463855ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:14:25.074565Z","caller":"traceutil/trace.go:171","msg":"trace[1535070346] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"138.857232ms","start":"2026-10-02T09:14:24.935698Z","end":"2026-10-02T09:14:25.074555Z","steps":["trace[1535070346] 'process raft request' (duration: 138.2114ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:25.075155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.008304ms","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-10-02T09:14:25.075202Z","caller":"traceutil/trace.go:171","msg":"trace[2061575260] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5155; }","duration":"125.115658ms","start":"2026-10-02T09:14:24.950072Z","end":"2026-10-02T09:14:25.075188Z","steps":["trace[2061575260] 'agreement among raft nodes before linearized reading' (duration: 124.017841ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:25.385367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.716345ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-02T09:14:25.385643Z","caller":"traceutil/trace.go:171","msg":"trace[173327673] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"302.231388ms","start":"2026-10-02T09:14:25.083386Z","end":"2026-10-02T09:14:25.385617Z","steps":["trace[173327673] 'process raft request' (duration: 190.14407ms)","trace[173327673] 'compare' (duration: 111.56271ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:14:25.385733Z","caller":"traceutil/trace.go:171","msg":"trace[158096105] transaction","detail":"{read_only:false; response_revision:5158; number_of_response:1; }","duration":"302.274289ms","start":"2026-10-02T09:14:25.083436Z","end":"2026-10-02T09:14:25.38571Z","steps":["trace[158096105] 'process raft request' (duration: 302.084963ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:14:25.386011Z","caller":"traceutil/trace.go:171","msg":"trace[349608715] transaction","detail":"{read_only:false; response_revision:5159; number_of_response:1; }","duration":"301.356699ms","start":"2026-10-02T09:14:25.084632Z","end":"2026-10-02T09:14:25.385989Z","steps":["trace[349608715] 'process raft request' (duration: 300.987297ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:25.386124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:25.084618Z","time spent":"301.447922ms","remote":"127.0.0.1:60740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1428,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:14:25.386178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:25.083406Z","time spent":"302.564538ms","remote":"127.0.0.1:60586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-02T09:14:25.385825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-02T09:14:25.083367Z","time spent":"302.369042ms","remote":"127.0.0.1:60586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-02T09:14:25.386744Z","caller":"traceutil/trace.go:171","msg":"trace[1887790140] linearizableReadLoop","detail":"{readStateIndex:5356; appliedIndex:5351; }","duration":"149.376061ms","start":"2026-10-02T09:14:25.237338Z","end":"2026-10-02T09:14:25.386714Z","steps":["trace[1887790140] 'read index received' (duration: 36.207908ms)","trace[1887790140] 'applied index is now lower than readState.Index' (duration: 113.166443ms)"],"step_count":2} {"level":"info","ts":"2026-10-02T09:14:25.386895Z","caller":"traceutil/trace.go:171","msg":"trace[1553020958] transaction","detail":"{read_only:false; response_revision:5161; number_of_response:1; }","duration":"191.151073ms","start":"2026-10-02T09:14:25.195729Z","end":"2026-10-02T09:14:25.38688Z","steps":["trace[1553020958] 'process raft request' (duration: 190.633446ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:25.386989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.573409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-02T09:14:25.387067Z","caller":"traceutil/trace.go:171","msg":"trace[1573884441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5161; }","duration":"143.677013ms","start":"2026-10-02T09:14:25.243371Z","end":"2026-10-02T09:14:25.387048Z","steps":["trace[1573884441] 'agreement among raft nodes before linearized reading' (duration: 143.572079ms)"],"step_count":1} {"level":"warn","ts":"2026-10-02T09:14:25.387413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.074374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-10-02T09:14:25.387465Z","caller":"traceutil/trace.go:171","msg":"trace[1274969425] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5161; }","duration":"150.172267ms","start":"2026-10-02T09:14:25.23728Z","end":"2026-10-02T09:14:25.387452Z","steps":["trace[1274969425] 'agreement among raft nodes before linearized reading' (duration: 150.024822ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:14:25.386562Z","caller":"traceutil/trace.go:171","msg":"trace[2048933306] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"191.538236ms","start":"2026-10-02T09:14:25.194983Z","end":"2026-10-02T09:14:25.386521Z","steps":["trace[2048933306] 'process raft request' (duration: 190.940336ms)"],"step_count":1} {"level":"info","ts":"2026-10-02T09:15:03.372872Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2905} {"level":"info","ts":"2026-10-02T09:15:03.45245Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2905,"took":"78.303826ms","hash":1087611583,"current-db-size-bytes":52719616,"current-db-size":"53 MB","current-db-size-in-use-bytes":28950528,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-10-02T09:15:03.452518Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1087611583,"revision":2905,"compact-revision":-1} {"level":"info","ts":"2026-10-02T09:20:03.380466Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5497} {"level":"info","ts":"2026-10-02T09:20:03.456358Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5497,"took":"74.423732ms","hash":4198634080,"current-db-size-bytes":52719616,"current-db-size":"53 MB","current-db-size-in-use-bytes":18735104,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-02T09:20:03.456419Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4198634080,"revision":5497,"compact-revision":2905}