{"level":"warn","ts":"2026-07-24T04:42:47.136873Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T04:42:47.136979Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.72: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.72:2380","--initial-cluster=instance=https://199.204.45.72:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.72:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.72: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-07-24T04:42:47.137048Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-24T04:42:47.137062Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-24T04:42:47.13709Z","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-07-24T04:42:47.137779Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"]} {"level":"info","ts":"2026-07-24T04:42:47.137848Z","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.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.72: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-07-24T04:42:47.140266Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.202079ms"} {"level":"info","ts":"2026-07-24T04:42:47.14972Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"50e03cebfd94cbba","cluster-id":"120c03c1d5b80f94"} {"level":"info","ts":"2026-07-24T04:42:47.149823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=()"} {"level":"info","ts":"2026-07-24T04:42:47.14986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 0"} {"level":"info","ts":"2026-07-24T04:42:47.149885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 50e03cebfd94cbba [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-24T04:42:47.149902Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became follower at term 1"} {"level":"info","ts":"2026-07-24T04:42:47.149953Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"warn","ts":"2026-07-24T04:42:47.15409Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-24T04:42:47.155553Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-24T04:42:47.156931Z","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-07-24T04:42:47.159007Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"50e03cebfd94cbba","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-24T04:42:47.159124Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"50e03cebfd94cbba","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-24T04:42:47.159281Z","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-07-24T04:42:47.159557Z","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-07-24T04:42:47.159588Z","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-07-24T04:42:47.162519Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba switched to configuration voters=(5827724902086790074)"} {"level":"info","ts":"2026-07-24T04:42:47.163395Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","added-peer-id":"50e03cebfd94cbba","added-peer-peer-urls":["https://199.204.45.72:2380"]} {"level":"info","ts":"2026-07-24T04:42:47.165261Z","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-07-24T04:42:47.165509Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-24T04:42:47.165569Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.72:2380"} {"level":"info","ts":"2026-07-24T04:42:47.165668Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"50e03cebfd94cbba","initial-advertise-peer-urls":["https://199.204.45.72:2380"],"listen-peer-urls":["https://199.204.45.72:2380"],"advertise-client-urls":["https://199.204.45.72:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.72:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-24T04:42:47.165721Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-24T04:42:47.350663Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba is starting a new election at term 1"} {"level":"info","ts":"2026-07-24T04:42:47.350715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became pre-candidate at term 1"} {"level":"info","ts":"2026-07-24T04:42:47.350749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgPreVoteResp from 50e03cebfd94cbba at term 1"} {"level":"info","ts":"2026-07-24T04:42:47.350777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became candidate at term 2"} {"level":"info","ts":"2026-07-24T04:42:47.350789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba received MsgVoteResp from 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-24T04:42:47.350807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"50e03cebfd94cbba became leader at term 2"} {"level":"info","ts":"2026-07-24T04:42:47.350824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 50e03cebfd94cbba elected leader 50e03cebfd94cbba at term 2"} {"level":"info","ts":"2026-07-24T04:42:47.352744Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"50e03cebfd94cbba","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.72:2379]}","request-path":"/0/members/50e03cebfd94cbba/attributes","cluster-id":"120c03c1d5b80f94","publish-timeout":"7s"} {"level":"info","ts":"2026-07-24T04:42:47.353196Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:42:47.353325Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:42:47.353349Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-24T04:42:47.353492Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-24T04:42:47.353577Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-24T04:42:47.354409Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"120c03c1d5b80f94","local-member-id":"50e03cebfd94cbba","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:42:47.35459Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:42:47.35465Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-24T04:42:47.355511Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-24T04:42:47.35815Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.72:2379"} {"level":"info","ts":"2026-07-24T04:43:12.109089Z","caller":"traceutil/trace.go:171","msg":"trace[233608163] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"101.634463ms","start":"2026-07-24T04:43:12.007413Z","end":"2026-07-24T04:43:12.109047Z","steps":["trace[233608163] 'process raft request' (duration: 93.863952ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:12.109849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.361531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-07-24T04:43:12.110253Z","caller":"traceutil/trace.go:171","msg":"trace[318806460] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:443; }","duration":"100.727947ms","start":"2026-07-24T04:43:12.009386Z","end":"2026-07-24T04:43:12.110114Z","steps":["trace[318806460] 'agreement among raft nodes before linearized reading' (duration: 99.657608ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:32.083458Z","caller":"traceutil/trace.go:171","msg":"trace[168778789] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"148.782294ms","start":"2026-07-24T04:43:31.934647Z","end":"2026-07-24T04:43:32.083429Z","steps":["trace[168778789] 'process raft request' (duration: 148.519638ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:43:32.252358Z","caller":"traceutil/trace.go:171","msg":"trace[638375927] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"317.563271ms","start":"2026-07-24T04:43:31.934754Z","end":"2026-07-24T04:43:32.252317Z","steps":["trace[638375927] 'process raft request' (duration: 287.820829ms)","trace[638375927] 'compare' (duration: 29.510328ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:43:32.252439Z","caller":"traceutil/trace.go:171","msg":"trace[248617025] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"221.816744ms","start":"2026-07-24T04:43:32.030582Z","end":"2026-07-24T04:43:32.252399Z","steps":["trace[248617025] 'read index received' (duration: 52.844144ms)","trace[248617025] 'applied index is now lower than readState.Index' (duration: 168.96949ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:43:32.252736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.13564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-4vsdv\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-24T04:43:32.252823Z","caller":"traceutil/trace.go:171","msg":"trace[1368477658] range","detail":"{range_begin:/registry/pods/kube-system/cilium-4vsdv; range_end:; response_count:1; response_revision:623; }","duration":"222.282453ms","start":"2026-07-24T04:43:32.030518Z","end":"2026-07-24T04:43:32.2528Z","steps":["trace[1368477658] 'agreement among raft nodes before linearized reading' (duration: 222.020698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:43:32.253074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:43:31.934728Z","time spent":"317.714733ms","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:43:48.079219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.466383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:43:48.079383Z","caller":"traceutil/trace.go:171","msg":"trace[285345354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:796; }","duration":"218.703358ms","start":"2026-07-24T04:43:47.860647Z","end":"2026-07-24T04:43:48.079351Z","steps":["trace[285345354] 'range keys from in-memory index tree' (duration: 218.353442ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:45:45.395201Z","caller":"traceutil/trace.go:171","msg":"trace[207683487] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"115.72725ms","start":"2026-07-24T04:45:45.279425Z","end":"2026-07-24T04:45:45.395152Z","steps":["trace[207683487] 'process raft request' (duration: 115.055288ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:46:39.871552Z","caller":"traceutil/trace.go:171","msg":"trace[141053994] transaction","detail":"{read_only:false; response_revision:2598; number_of_response:1; }","duration":"165.528957ms","start":"2026-07-24T04:46:39.706Z","end":"2026-07-24T04:46:39.871529Z","steps":["trace[141053994] 'process raft request' (duration: 165.398115ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:47:13.656291Z","caller":"traceutil/trace.go:171","msg":"trace[1985845418] transaction","detail":"{read_only:false; response_revision:2802; number_of_response:1; }","duration":"192.446698ms","start":"2026-07-24T04:47:13.463805Z","end":"2026-07-24T04:47:13.656252Z","steps":["trace[1985845418] 'process raft request' (duration: 176.432602ms)","trace[1985845418] 'compare' (duration: 15.715321ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:47:13.898522Z","caller":"traceutil/trace.go:171","msg":"trace[1697776877] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"166.490012ms","start":"2026-07-24T04:47:13.731995Z","end":"2026-07-24T04:47:13.898485Z","steps":["trace[1697776877] 'process raft request' (duration: 79.065041ms)","trace[1697776877] 'compare' (duration: 86.999873ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:47:13.911938Z","caller":"traceutil/trace.go:171","msg":"trace[1017327753] transaction","detail":"{read_only:false; response_revision:2804; number_of_response:1; }","duration":"114.865296ms","start":"2026-07-24T04:47:13.797017Z","end":"2026-07-24T04:47:13.911882Z","steps":["trace[1017327753] 'process raft request' (duration: 114.587461ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:49:49.787416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.783179ms","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-07-24T04:49:49.787533Z","caller":"traceutil/trace.go:171","msg":"trace[616184532] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:3823; }","duration":"115.958074ms","start":"2026-07-24T04:49:49.671552Z","end":"2026-07-24T04:49:49.78751Z","steps":["trace[616184532] 'range keys from in-memory index tree' (duration: 115.546155ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:51:17.099872Z","caller":"traceutil/trace.go:171","msg":"trace[1527903462] linearizableReadLoop","detail":"{readStateIndex:5086; appliedIndex:5084; }","duration":"121.896898ms","start":"2026-07-24T04:51:16.977944Z","end":"2026-07-24T04:51:17.099841Z","steps":["trace[1527903462] 'read index received' (duration: 121.691393ms)","trace[1527903462] 'applied index is now lower than readState.Index' (duration: 204.145µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:51:17.099918Z","caller":"traceutil/trace.go:171","msg":"trace[139520406] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"143.852885ms","start":"2026-07-24T04:51:16.956022Z","end":"2026-07-24T04:51:17.099874Z","steps":["trace[139520406] 'process raft request' (duration: 143.754672ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:51:17.099919Z","caller":"traceutil/trace.go:171","msg":"trace[2078880985] transaction","detail":"{read_only:false; response_revision:4900; number_of_response:1; }","duration":"149.610394ms","start":"2026-07-24T04:51:16.950256Z","end":"2026-07-24T04:51:17.099866Z","steps":["trace[2078880985] 'process raft request' (duration: 149.330588ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:51:17.100162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.221783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-24T04:51:17.100223Z","caller":"traceutil/trace.go:171","msg":"trace[1652648690] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4901; }","duration":"142.339966ms","start":"2026-07-24T04:51:16.957863Z","end":"2026-07-24T04:51:17.100203Z","steps":["trace[1652648690] 'agreement among raft nodes before linearized reading' (duration: 142.128042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:51:17.100553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.864589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:14495"} {"level":"info","ts":"2026-07-24T04:51:17.100669Z","caller":"traceutil/trace.go:171","msg":"trace[365831948] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4901; }","duration":"159.049174ms","start":"2026-07-24T04:51:16.941588Z","end":"2026-07-24T04:51:17.100637Z","steps":["trace[365831948] 'agreement among raft nodes before linearized reading' (duration: 158.660617ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:51:17.10202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.44608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-07-24T04:51:17.102155Z","caller":"traceutil/trace.go:171","msg":"trace[96028225] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4902; }","duration":"119.647214ms","start":"2026-07-24T04:51:16.982475Z","end":"2026-07-24T04:51:17.102122Z","steps":["trace[96028225] 'agreement among raft nodes before linearized reading' (duration: 119.236456ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:51:22.916123Z","caller":"traceutil/trace.go:171","msg":"trace[565014316] linearizableReadLoop","detail":"{readStateIndex:5272; appliedIndex:5271; }","duration":"158.537334ms","start":"2026-07-24T04:51:22.757544Z","end":"2026-07-24T04:51:22.916081Z","steps":["trace[565014316] 'read index received' (duration: 115.334823ms)","trace[565014316] 'applied index is now lower than readState.Index' (duration: 43.200391ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:51:22.916155Z","caller":"traceutil/trace.go:171","msg":"trace[1078667529] transaction","detail":"{read_only:false; response_revision:5080; number_of_response:1; }","duration":"230.860248ms","start":"2026-07-24T04:51:22.685235Z","end":"2026-07-24T04:51:22.916096Z","steps":["trace[1078667529] 'process raft request' (duration: 187.596036ms)","trace[1078667529] 'compare' (duration: 43.015487ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:51:22.91643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.159306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3b27616c-fae0-4924-9b0a-b5f011aa543d\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-07-24T04:51:22.916567Z","caller":"traceutil/trace.go:171","msg":"trace[256254463] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3b27616c-fae0-4924-9b0a-b5f011aa543d; range_end:; response_count:1; response_revision:5080; }","duration":"159.343268ms","start":"2026-07-24T04:51:22.757196Z","end":"2026-07-24T04:51:22.916539Z","steps":["trace[256254463] 'agreement among raft nodes before linearized reading' (duration: 158.962672ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:51:22.916683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.413667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-07-24T04:51:22.916743Z","caller":"traceutil/trace.go:171","msg":"trace[208937282] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5080; }","duration":"232.515419ms","start":"2026-07-24T04:51:22.68421Z","end":"2026-07-24T04:51:22.916725Z","steps":["trace[208937282] 'agreement among raft nodes before linearized reading' (duration: 232.098541ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:51:22.928769Z","caller":"traceutil/trace.go:171","msg":"trace[809229602] transaction","detail":"{read_only:false; response_revision:5081; number_of_response:1; }","duration":"163.526648ms","start":"2026-07-24T04:51:22.765207Z","end":"2026-07-24T04:51:22.928734Z","steps":["trace[809229602] 'process raft request' (duration: 163.238422ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:51:22.929004Z","caller":"traceutil/trace.go:171","msg":"trace[1058786101] transaction","detail":"{read_only:false; response_revision:5082; number_of_response:1; }","duration":"161.405847ms","start":"2026-07-24T04:51:22.767585Z","end":"2026-07-24T04:51:22.92899Z","steps":["trace[1058786101] 'process raft request' (duration: 161.02635ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:51:23.268443Z","caller":"traceutil/trace.go:171","msg":"trace[1614345546] transaction","detail":"{read_only:false; response_revision:5088; number_of_response:1; }","duration":"136.681618ms","start":"2026-07-24T04:51:23.131708Z","end":"2026-07-24T04:51:23.268389Z","steps":["trace[1614345546] 'process raft request' (duration: 104.869813ms)","trace[1614345546] 'compare' (duration: 31.586241ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:51:23.273512Z","caller":"traceutil/trace.go:171","msg":"trace[1739833321] transaction","detail":"{read_only:false; response_revision:5089; number_of_response:1; }","duration":"140.786756ms","start":"2026-07-24T04:51:23.132709Z","end":"2026-07-24T04:51:23.273496Z","steps":["trace[1739833321] 'process raft request' (duration: 140.692934ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:51:34.935259Z","caller":"traceutil/trace.go:171","msg":"trace[2100514251] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"111.78742ms","start":"2026-07-24T04:51:34.823433Z","end":"2026-07-24T04:51:34.93522Z","steps":["trace[2100514251] 'process raft request' (duration: 90.638147ms)","trace[2100514251] 'compare' (duration: 20.950209ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:51:35.103826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.998491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-07-24T04:51:35.103916Z","caller":"traceutil/trace.go:171","msg":"trace[1095517958] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5333; }","duration":"124.121684ms","start":"2026-07-24T04:51:34.979773Z","end":"2026-07-24T04:51:35.103895Z","steps":["trace[1095517958] 'agreement among raft nodes before linearized reading' (duration: 123.871619ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:51:35.104304Z","caller":"traceutil/trace.go:171","msg":"trace[28713930] transaction","detail":"{read_only:false; response_revision:5332; number_of_response:1; }","duration":"127.749244ms","start":"2026-07-24T04:51:34.976544Z","end":"2026-07-24T04:51:35.104294Z","steps":["trace[28713930] 'process raft request' (duration: 93.98154ms)","trace[28713930] 'compare' (duration: 32.899367ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:51:35.104484Z","caller":"traceutil/trace.go:171","msg":"trace[90555775] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"110.015525ms","start":"2026-07-24T04:51:34.994461Z","end":"2026-07-24T04:51:35.104477Z","steps":["trace[90555775] 'process raft request' (duration: 109.107938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:51:35.104618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.714658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls\" ","response":"range_response_count:1 size:2591"} {"level":"info","ts":"2026-07-24T04:51:35.104638Z","caller":"traceutil/trace.go:171","msg":"trace[1864401052] range","detail":"{range_begin:/registry/cert-manager.io/certificates/monitoring/prometheus-kube-prometheus-stack-prometheus-0-tls; range_end:; response_count:1; response_revision:5333; }","duration":"121.776299ms","start":"2026-07-24T04:51:34.982855Z","end":"2026-07-24T04:51:35.104631Z","steps":["trace[1864401052] 'agreement among raft nodes before linearized reading' (duration: 121.707918ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:51:35.504886Z","caller":"traceutil/trace.go:171","msg":"trace[1712814927] linearizableReadLoop","detail":"{readStateIndex:5544; appliedIndex:5542; }","duration":"313.818657ms","start":"2026-07-24T04:51:35.191029Z","end":"2026-07-24T04:51:35.504848Z","steps":["trace[1712814927] 'read index received' (duration: 9.887248ms)","trace[1712814927] 'applied index is now lower than readState.Index' (duration: 303.930109ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:51:35.504943Z","caller":"traceutil/trace.go:171","msg":"trace[245576387] transaction","detail":"{read_only:false; response_revision:5345; number_of_response:1; }","duration":"318.467926ms","start":"2026-07-24T04:51:35.186433Z","end":"2026-07-24T04:51:35.5049Z","steps":["trace[245576387] 'process raft request' (duration: 318.107858ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:51:35.50497Z","caller":"traceutil/trace.go:171","msg":"trace[1904197851] transaction","detail":"{read_only:false; response_revision:5346; number_of_response:1; }","duration":"311.664116ms","start":"2026-07-24T04:51:35.193263Z","end":"2026-07-24T04:51:35.504927Z","steps":["trace[1904197851] 'process raft request' (duration: 311.527764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:51:35.50512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:51:35.186423Z","time spent":"318.609148ms","remote":"127.0.0.1:57248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":909,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:51:35.505154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:51:35.193254Z","time spent":"311.806219ms","remote":"127.0.0.1:57248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":902,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-24T04:51:35.505254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.195224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-07-24T04:51:35.505375Z","caller":"traceutil/trace.go:171","msg":"trace[814096465] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5346; }","duration":"314.341416ms","start":"2026-07-24T04:51:35.19101Z","end":"2026-07-24T04:51:35.505351Z","steps":["trace[814096465] 'agreement among raft nodes before linearized reading' (duration: 314.00819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:51:35.505431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:51:35.191002Z","time spent":"314.418677ms","remote":"127.0.0.1:57268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":672,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" "} {"level":"info","ts":"2026-07-24T04:51:35.778951Z","caller":"traceutil/trace.go:171","msg":"trace[30583881] linearizableReadLoop","detail":"{readStateIndex:5547; appliedIndex:5545; }","duration":"273.942377ms","start":"2026-07-24T04:51:35.504983Z","end":"2026-07-24T04:51:35.778925Z","steps":["trace[30583881] 'read index received' (duration: 273.275575ms)","trace[30583881] 'applied index is now lower than readState.Index' (duration: 665.082µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:51:35.77912Z","caller":"traceutil/trace.go:171","msg":"trace[983393827] transaction","detail":"{read_only:false; response_revision:5348; number_of_response:1; }","duration":"459.560184ms","start":"2026-07-24T04:51:35.3195Z","end":"2026-07-24T04:51:35.77906Z","steps":["trace[983393827] 'process raft request' (duration: 459.348489ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:51:35.779177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.359762ms","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-07-24T04:51:35.77927Z","caller":"traceutil/trace.go:171","msg":"trace[838497187] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5348; }","duration":"277.464734ms","start":"2026-07-24T04:51:35.501785Z","end":"2026-07-24T04:51:35.779249Z","steps":["trace[838497187] 'agreement among raft nodes before linearized reading' (duration: 277.196318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:51:35.779288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.052219ms","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-07-24T04:51:35.779331Z","caller":"traceutil/trace.go:171","msg":"trace[896529141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5348; }","duration":"442.173202ms","start":"2026-07-24T04:51:35.337148Z","end":"2026-07-24T04:51:35.779322Z","steps":["trace[896529141] 'agreement among raft nodes before linearized reading' (duration: 441.901577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:51:35.779337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:51:35.319465Z","time spent":"459.768917ms","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:51:35.779358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:51:35.337113Z","time spent":"442.241093ms","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-24T04:51:35.779566Z","caller":"traceutil/trace.go:171","msg":"trace[856265865] transaction","detail":"{read_only:false; response_revision:5347; number_of_response:1; }","duration":"577.884307ms","start":"2026-07-24T04:51:35.201613Z","end":"2026-07-24T04:51:35.779497Z","steps":["trace[856265865] 'process raft request' (duration: 576.660463ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:51:35.780103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:51:35.201601Z","time spent":"578.007379ms","remote":"127.0.0.1:53242","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7067,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T04:51:35.780231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"502.767006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:57 size:2225401"} {"level":"info","ts":"2026-07-24T04:51:35.780298Z","caller":"traceutil/trace.go:171","msg":"trace[1564681266] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:57; response_revision:5348; }","duration":"502.881028ms","start":"2026-07-24T04:51:35.277397Z","end":"2026-07-24T04:51:35.780278Z","steps":["trace[1564681266] 'agreement among raft nodes before linearized reading' (duration: 501.676246ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:51:35.780349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:51:35.277373Z","time spent":"502.964299ms","remote":"127.0.0.1:57270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":57,"response size":2225423,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-07-24T04:51:35.792512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.246845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-07-24T04:51:35.792588Z","caller":"traceutil/trace.go:171","msg":"trace[2111102161] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:5349; }","duration":"282.383828ms","start":"2026-07-24T04:51:35.510187Z","end":"2026-07-24T04:51:35.79257Z","steps":["trace[2111102161] 'agreement among raft nodes before linearized reading' (duration: 282.197604ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:51:43.951408Z","caller":"traceutil/trace.go:171","msg":"trace[421140340] linearizableReadLoop","detail":"{readStateIndex:5746; appliedIndex:5744; }","duration":"118.68632ms","start":"2026-07-24T04:51:43.832684Z","end":"2026-07-24T04:51:43.95137Z","steps":["trace[421140340] 'read index received' (duration: 18.340699ms)","trace[421140340] 'applied index is now lower than readState.Index' (duration: 100.343771ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:51:43.951512Z","caller":"traceutil/trace.go:171","msg":"trace[1833821366] transaction","detail":"{read_only:false; response_revision:5543; number_of_response:1; }","duration":"131.608936ms","start":"2026-07-24T04:51:43.819847Z","end":"2026-07-24T04:51:43.951456Z","steps":["trace[1833821366] 'process raft request' (duration: 127.484218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:51:43.951767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.957119ms","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-07-24T04:51:43.951851Z","caller":"traceutil/trace.go:171","msg":"trace[1274082358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5543; }","duration":"116.086711ms","start":"2026-07-24T04:51:43.835742Z","end":"2026-07-24T04:51:43.951829Z","steps":["trace[1274082358] 'agreement among raft nodes before linearized reading' (duration: 115.845257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:51:43.951773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.040058ms","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-07-24T04:51:43.952117Z","caller":"traceutil/trace.go:171","msg":"trace[2056715860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5543; }","duration":"119.476185ms","start":"2026-07-24T04:51:43.832616Z","end":"2026-07-24T04:51:43.952092Z","steps":["trace[2056715860] 'agreement among raft nodes before linearized reading' (duration: 118.904354ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:52:47.902427Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2990} {"level":"info","ts":"2026-07-24T04:52:47.977293Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2990,"took":"72.501737ms","hash":4293739411,"current-db-size-bytes":58003456,"current-db-size":"58 MB","current-db-size-in-use-bytes":33996800,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-24T04:52:47.977492Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4293739411,"revision":2990,"compact-revision":-1} {"level":"info","ts":"2026-07-24T04:57:42.18267Z","caller":"traceutil/trace.go:171","msg":"trace[1223820327] linearizableReadLoop","detail":"{readStateIndex:8571; appliedIndex:8570; }","duration":"323.809941ms","start":"2026-07-24T04:57:41.858835Z","end":"2026-07-24T04:57:42.182645Z","steps":["trace[1223820327] 'read index received' (duration: 323.628847ms)","trace[1223820327] 'applied index is now lower than readState.Index' (duration: 179.523µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:57:42.182857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.001395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:57:42.182952Z","caller":"traceutil/trace.go:171","msg":"trace[118082141] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8280; }","duration":"324.146798ms","start":"2026-07-24T04:57:41.858785Z","end":"2026-07-24T04:57:42.182932Z","steps":["trace[118082141] 'agreement among raft nodes before linearized reading' (duration: 323.970225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:57:42.182966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.928712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-24T04:57:42.182998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:57:41.858764Z","time spent":"324.226459ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T04:57:42.183021Z","caller":"traceutil/trace.go:171","msg":"trace[1220315413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8280; }","duration":"316.002663ms","start":"2026-07-24T04:57:41.867003Z","end":"2026-07-24T04:57:42.183006Z","steps":["trace[1220315413] 'agreement among raft nodes before linearized reading' (duration: 315.81401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:57:42.183053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:57:41.866963Z","time spent":"316.084115ms","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-24T04:57:42.183041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.525905ms","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-07-24T04:57:42.183163Z","caller":"traceutil/trace.go:171","msg":"trace[1018185410] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8280; }","duration":"102.737129ms","start":"2026-07-24T04:57:42.080402Z","end":"2026-07-24T04:57:42.183139Z","steps":["trace[1018185410] 'agreement among raft nodes before linearized reading' (duration: 102.423273ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:57:42.182713Z","caller":"traceutil/trace.go:171","msg":"trace[1696005310] transaction","detail":"{read_only:false; response_revision:8280; number_of_response:1; }","duration":"334.086865ms","start":"2026-07-24T04:57:41.848593Z","end":"2026-07-24T04:57:42.18268Z","steps":["trace[1696005310] 'process raft request' (duration: 333.858941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:57:42.183432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T04:57:41.848563Z","time spent":"334.79413ms","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T04:57:47.910819Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6081} {"level":"info","ts":"2026-07-24T04:57:48.014941Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6081,"took":"101.821101ms","hash":836204345,"current-db-size-bytes":58003456,"current-db-size":"58 MB","current-db-size-in-use-bytes":24002560,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-24T04:57:48.015094Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":836204345,"revision":6081,"compact-revision":2990} {"level":"info","ts":"2026-07-24T04:57:54.784457Z","caller":"traceutil/trace.go:171","msg":"trace[1472299046] linearizableReadLoop","detail":"{readStateIndex:8641; appliedIndex:8640; }","duration":"137.070129ms","start":"2026-07-24T04:57:54.647355Z","end":"2026-07-24T04:57:54.784425Z","steps":["trace[1472299046] 'read index received' (duration: 136.859535ms)","trace[1472299046] 'applied index is now lower than readState.Index' (duration: 209.194µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:57:54.784515Z","caller":"traceutil/trace.go:171","msg":"trace[964008186] transaction","detail":"{read_only:false; response_revision:8346; number_of_response:1; }","duration":"292.335224ms","start":"2026-07-24T04:57:54.492145Z","end":"2026-07-24T04:57:54.784481Z","steps":["trace[964008186] 'process raft request' (duration: 292.08171ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T04:57:54.784669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.288284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-24T04:57:54.78472Z","caller":"traceutil/trace.go:171","msg":"trace[1459147670] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:8346; }","duration":"137.432946ms","start":"2026-07-24T04:57:54.647271Z","end":"2026-07-24T04:57:54.784704Z","steps":["trace[1459147670] 'agreement among raft nodes before linearized reading' (duration: 137.271153ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:58:37.211867Z","caller":"traceutil/trace.go:171","msg":"trace[1669291385] linearizableReadLoop","detail":"{readStateIndex:8981; appliedIndex:8980; }","duration":"188.010205ms","start":"2026-07-24T04:58:37.023817Z","end":"2026-07-24T04:58:37.211827Z","steps":["trace[1669291385] 'read index received' (duration: 165.321645ms)","trace[1669291385] 'applied index is now lower than readState.Index' (duration: 22.6875ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T04:58:37.211951Z","caller":"traceutil/trace.go:171","msg":"trace[1031144524] transaction","detail":"{read_only:false; response_revision:8672; number_of_response:1; }","duration":"237.2806ms","start":"2026-07-24T04:58:36.974625Z","end":"2026-07-24T04:58:37.211906Z","steps":["trace[1031144524] 'process raft request' (duration: 214.590279ms)","trace[1031144524] 'compare' (duration: 22.261273ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T04:58:37.212219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.384763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T04:58:37.21244Z","caller":"traceutil/trace.go:171","msg":"trace[1539412450] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:8672; }","duration":"188.647748ms","start":"2026-07-24T04:58:37.023743Z","end":"2026-07-24T04:58:37.212391Z","steps":["trace[1539412450] 'agreement among raft nodes before linearized reading' (duration: 188.332741ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:58:37.216568Z","caller":"traceutil/trace.go:171","msg":"trace[201234357] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"105.488961ms","start":"2026-07-24T04:58:37.111045Z","end":"2026-07-24T04:58:37.216534Z","steps":["trace[201234357] 'process raft request' (duration: 104.92822ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:58:53.171343Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023eb.wal"} {"level":"info","ts":"2026-07-24T04:59:49.161989Z","caller":"traceutil/trace.go:171","msg":"trace[1967469247] transaction","detail":"{read_only:false; response_revision:9396; number_of_response:1; }","duration":"128.412118ms","start":"2026-07-24T04:59:49.033545Z","end":"2026-07-24T04:59:49.161958Z","steps":["trace[1967469247] 'process raft request' (duration: 128.241085ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T04:59:49.16252Z","caller":"traceutil/trace.go:171","msg":"trace[941712471] transaction","detail":"{read_only:false; response_revision:9395; number_of_response:1; }","duration":"128.350238ms","start":"2026-07-24T04:59:49.033514Z","end":"2026-07-24T04:59:49.161864Z","steps":["trace[941712471] 'process raft request' (duration: 117.396924ms)","trace[941712471] 'compare' (duration: 10.694819ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:00:25.317882Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T05:00:25.323079Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-24T05:00:25.323194Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-24T05:02:47.919144Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8308} {"level":"info","ts":"2026-07-24T05:02:48.004726Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8308,"took":"82.485224ms","hash":4101665395,"current-db-size-bytes":58003456,"current-db-size":"58 MB","current-db-size-in-use-bytes":26570752,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-07-24T05:02:48.004807Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4101665395,"revision":8308,"compact-revision":6081} {"level":"info","ts":"2026-07-24T05:07:47.926706Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10800} {"level":"info","ts":"2026-07-24T05:07:48.012981Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10800,"took":"82.907825ms","hash":1732982439,"current-db-size-bytes":58003456,"current-db-size":"58 MB","current-db-size-in-use-bytes":27930624,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-24T05:07:48.013082Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1732982439,"revision":10800,"compact-revision":8308} {"level":"info","ts":"2026-07-24T05:09:18.392744Z","caller":"traceutil/trace.go:171","msg":"trace[1828428911] transaction","detail":"{read_only:false; response_revision:13639; number_of_response:1; }","duration":"108.494929ms","start":"2026-07-24T05:09:18.284218Z","end":"2026-07-24T05:09:18.392713Z","steps":["trace[1828428911] 'process raft request' (duration: 108.070661ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:09:18.411538Z","caller":"traceutil/trace.go:171","msg":"trace[256777407] transaction","detail":"{read_only:false; response_revision:13641; number_of_response:1; }","duration":"103.759786ms","start":"2026-07-24T05:09:18.307757Z","end":"2026-07-24T05:09:18.411516Z","steps":["trace[256777407] 'process raft request' (duration: 103.639684ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:09:18.411787Z","caller":"traceutil/trace.go:171","msg":"trace[456541969] transaction","detail":"{read_only:false; response_revision:13640; number_of_response:1; }","duration":"111.074231ms","start":"2026-07-24T05:09:18.300629Z","end":"2026-07-24T05:09:18.411704Z","steps":["trace[456541969] 'process raft request' (duration: 109.267685ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:09:58.69001Z","caller":"traceutil/trace.go:171","msg":"trace[94261533] transaction","detail":"{read_only:false; response_revision:13875; number_of_response:1; }","duration":"117.024549ms","start":"2026-07-24T05:09:58.572953Z","end":"2026-07-24T05:09:58.689978Z","steps":["trace[94261533] 'process raft request' (duration: 116.615891ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:09:59.37879Z","caller":"traceutil/trace.go:171","msg":"trace[364396380] linearizableReadLoop","detail":"{readStateIndex:14355; appliedIndex:14354; }","duration":"257.377921ms","start":"2026-07-24T05:09:59.121379Z","end":"2026-07-24T05:09:59.378757Z","steps":["trace[364396380] 'read index received' (duration: 257.088146ms)","trace[364396380] 'applied index is now lower than readState.Index' (duration: 288.835µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:09:59.378893Z","caller":"traceutil/trace.go:171","msg":"trace[377242539] transaction","detail":"{read_only:false; response_revision:13880; number_of_response:1; }","duration":"335.128721ms","start":"2026-07-24T05:09:59.043737Z","end":"2026-07-24T05:09:59.378865Z","steps":["trace[377242539] 'process raft request' (duration: 334.830426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:09:59.379069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:09:59.043715Z","time spent":"335.241414ms","remote":"127.0.0.1:57330","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12709,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:09:59.37905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.632076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzonegroups/\" range_end:\"/registry/ceph.rook.io/cephobjectzonegroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-24T05:09:59.379164Z","caller":"traceutil/trace.go:171","msg":"trace[785901107] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzonegroups/; range_end:/registry/ceph.rook.io/cephobjectzonegroups0; response_count:0; response_revision:13880; }","duration":"257.804569ms","start":"2026-07-24T05:09:59.121329Z","end":"2026-07-24T05:09:59.379134Z","steps":["trace[785901107] 'agreement among raft nodes before linearized reading' (duration: 257.566505ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:12:47.971707Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13011} {"level":"info","ts":"2026-07-24T05:12:48.061596Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13011,"took":"86.338123ms","hash":2050045971,"current-db-size-bytes":58003456,"current-db-size":"58 MB","current-db-size-in-use-bytes":31518720,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-07-24T05:12:48.061681Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2050045971,"revision":13011,"compact-revision":10800} {"level":"info","ts":"2026-07-24T05:16:34.031963Z","caller":"traceutil/trace.go:171","msg":"trace[906160504] transaction","detail":"{read_only:false; response_revision:17248; number_of_response:1; }","duration":"362.236168ms","start":"2026-07-24T05:16:33.66968Z","end":"2026-07-24T05:16:34.031916Z","steps":["trace[906160504] 'process raft request' (duration: 361.932643ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:34.032076Z","caller":"traceutil/trace.go:171","msg":"trace[218157182] linearizableReadLoop","detail":"{readStateIndex:17830; appliedIndex:17829; }","duration":"187.104243ms","start":"2026-07-24T05:16:33.844924Z","end":"2026-07-24T05:16:34.032028Z","steps":["trace[218157182] 'read index received' (duration: 186.563403ms)","trace[218157182] 'applied index is now lower than readState.Index' (duration: 531.91µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:34.03221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:33.66965Z","time spent":"362.441863ms","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:16:34.032346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.195219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T05:16:34.032501Z","caller":"traceutil/trace.go:171","msg":"trace[1035762265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17248; }","duration":"172.337163ms","start":"2026-07-24T05:16:33.860041Z","end":"2026-07-24T05:16:34.032378Z","steps":["trace[1035762265] 'agreement among raft nodes before linearized reading' (duration: 172.205419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:34.032504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.166208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-7hs2g\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-07-24T05:16:34.032589Z","caller":"traceutil/trace.go:171","msg":"trace[806803001] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-7hs2g; range_end:; response_count:1; response_revision:17248; }","duration":"140.30409ms","start":"2026-07-24T05:16:33.892264Z","end":"2026-07-24T05:16:34.032568Z","steps":["trace[806803001] 'agreement among raft nodes before linearized reading' (duration: 140.076186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:34.032598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.645854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-07-24T05:16:34.032733Z","caller":"traceutil/trace.go:171","msg":"trace[809832896] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17248; }","duration":"187.850598ms","start":"2026-07-24T05:16:33.844854Z","end":"2026-07-24T05:16:34.032705Z","steps":["trace[809832896] 'agreement among raft nodes before linearized reading' (duration: 187.284946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:39.453601Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221386641680866,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T05:16:39.954273Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221386641680866,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T05:16:40.455409Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14680221386641680866,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-24T05:16:40.465126Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.513039042s","expected-duration":"1s"} {"level":"info","ts":"2026-07-24T05:16:40.465544Z","caller":"traceutil/trace.go:171","msg":"trace[790466618] transaction","detail":"{read_only:false; response_revision:17316; number_of_response:1; }","duration":"1.513570152s","start":"2026-07-24T05:16:38.951945Z","end":"2026-07-24T05:16:40.465515Z","steps":["trace[790466618] 'process raft request' (duration: 1.513377779s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.4657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:38.951673Z","time spent":"1.513942939s","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:16:40.482122Z","caller":"traceutil/trace.go:171","msg":"trace[1773159590] transaction","detail":"{read_only:false; response_revision:17318; number_of_response:1; }","duration":"582.730081ms","start":"2026-07-24T05:16:39.899364Z","end":"2026-07-24T05:16:40.482094Z","steps":["trace[1773159590] 'process raft request' (duration: 582.640889ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:40.482148Z","caller":"traceutil/trace.go:171","msg":"trace[362718329] transaction","detail":"{read_only:false; response_revision:17317; number_of_response:1; }","duration":"1.526155532s","start":"2026-07-24T05:16:38.955953Z","end":"2026-07-24T05:16:40.482109Z","steps":["trace[362718329] 'process raft request' (duration: 1.525922718s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.482288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:39.899345Z","time spent":"582.836183ms","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:16:40.482366Z","caller":"traceutil/trace.go:171","msg":"trace[896178474] linearizableReadLoop","detail":"{readStateIndex:17907; appliedIndex:17901; }","duration":"1.529004497s","start":"2026-07-24T05:16:38.953343Z","end":"2026-07-24T05:16:40.482347Z","steps":["trace[896178474] 'read index received' (duration: 1.511977322s)","trace[896178474] 'applied index is now lower than readState.Index' (duration: 17.026045ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:40.482375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:38.955938Z","time spent":"1.526313455s","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-24T05:16:40.482529Z","caller":"traceutil/trace.go:171","msg":"trace[1293720440] transaction","detail":"{read_only:false; response_revision:17319; number_of_response:1; }","duration":"426.294122ms","start":"2026-07-24T05:16:40.05619Z","end":"2026-07-24T05:16:40.482484Z","steps":["trace[1293720440] 'process raft request' (duration: 425.862504ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.482645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.529293752s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"warn","ts":"2026-07-24T05:16:40.48265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.995418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T05:16:40.482695Z","caller":"traceutil/trace.go:171","msg":"trace[485352543] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17321; }","duration":"1.529356813s","start":"2026-07-24T05:16:38.953324Z","end":"2026-07-24T05:16:40.482681Z","steps":["trace[485352543] 'agreement among raft nodes before linearized reading' (duration: 1.52922218s)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:40.482706Z","caller":"traceutil/trace.go:171","msg":"trace[1660633511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17321; }","duration":"625.04845ms","start":"2026-07-24T05:16:39.857641Z","end":"2026-07-24T05:16:40.48269Z","steps":["trace[1660633511] 'agreement among raft nodes before linearized reading' (duration: 624.940317ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.482715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.525762785s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-07-24T05:16:40.482733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:40.056157Z","time spent":"426.467876ms","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-24T05:16:40.482751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:39.85758Z","time spent":"625.160512ms","remote":"127.0.0.1:57200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-24T05:16:40.482792Z","caller":"traceutil/trace.go:171","msg":"trace[418768258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17321; }","duration":"1.525851687s","start":"2026-07-24T05:16:38.95692Z","end":"2026-07-24T05:16:40.482772Z","steps":["trace[418768258] 'agreement among raft nodes before linearized reading' (duration: 1.525647942s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.482845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23078326s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-sm795\" ","response":"range_response_count:1 size:22121"} {"level":"info","ts":"2026-07-24T05:16:40.482883Z","caller":"traceutil/trace.go:171","msg":"trace[1284924172] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-sm795; range_end:; response_count:1; response_revision:17321; }","duration":"1.230834051s","start":"2026-07-24T05:16:39.252039Z","end":"2026-07-24T05:16:40.482873Z","steps":["trace[1284924172] 'agreement among raft nodes before linearized reading' (duration: 1.230724819s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.482914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:39.252034Z","time spent":"1.230873872s","remote":"127.0.0.1:57330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22143,"request content":"key:\"/registry/pods/openstack/nova-compute-default-sm795\" "} {"level":"warn","ts":"2026-07-24T05:16:40.482733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:38.953316Z","time spent":"1.529409255s","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-24T05:16:40.483033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.410346001s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-07-24T05:16:40.483075Z","caller":"traceutil/trace.go:171","msg":"trace[360699515] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17321; }","duration":"1.410421352s","start":"2026-07-24T05:16:39.072642Z","end":"2026-07-24T05:16:40.483063Z","steps":["trace[360699515] 'agreement among raft nodes before linearized reading' (duration: 1.41031154s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.483114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:39.072621Z","time spent":"1.410486534s","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":723,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-24T05:16:40.483139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.476127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T05:16:40.483172Z","caller":"traceutil/trace.go:171","msg":"trace[1452602984] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:17321; }","duration":"915.526609ms","start":"2026-07-24T05:16:39.567635Z","end":"2026-07-24T05:16:40.483161Z","steps":["trace[1452602984] 'agreement among raft nodes before linearized reading' (duration: 915.460307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.48321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:39.567628Z","time spent":"915.574059ms","remote":"127.0.0.1:57320","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":13,"response size":30,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-07-24T05:16:40.483319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.074392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-vpn-agent-default-r4jh6\" ","response":"range_response_count:1 size:15441"} {"level":"warn","ts":"2026-07-24T05:16:40.483377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.376402772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-07-24T05:16:40.483419Z","caller":"traceutil/trace.go:171","msg":"trace[776268168] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17321; }","duration":"1.376501983s","start":"2026-07-24T05:16:39.106907Z","end":"2026-07-24T05:16:40.483409Z","steps":["trace[776268168] 'agreement among raft nodes before linearized reading' (duration: 1.376402181s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.483453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:39.106885Z","time spent":"1.376560945s","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":723,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-24T05:16:40.483478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.521581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"warn","ts":"2026-07-24T05:16:40.483508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.225662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-67zw4\" ","response":"range_response_count:1 size:15432"} {"level":"info","ts":"2026-07-24T05:16:40.483557Z","caller":"traceutil/trace.go:171","msg":"trace[965979540] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-metadata-agent-default-67zw4; range_end:; response_count:1; response_revision:17321; }","duration":"137.313333ms","start":"2026-07-24T05:16:40.346232Z","end":"2026-07-24T05:16:40.483545Z","steps":["trace[965979540] 'agreement among raft nodes before linearized reading' (duration: 137.17832ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:40.483385Z","caller":"traceutil/trace.go:171","msg":"trace[322635124] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-vpn-agent-default-r4jh6; range_end:; response_count:1; response_revision:17321; }","duration":"100.181584ms","start":"2026-07-24T05:16:40.383186Z","end":"2026-07-24T05:16:40.483368Z","steps":["trace[322635124] 'agreement among raft nodes before linearized reading' (duration: 100.003221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.48361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.947766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-lqm7h\" ","response":"range_response_count:1 size:20650"} {"level":"info","ts":"2026-07-24T05:16:40.483639Z","caller":"traceutil/trace.go:171","msg":"trace[937453300] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-lqm7h; range_end:; response_count:1; response_revision:17321; }","duration":"960.025457ms","start":"2026-07-24T05:16:39.523607Z","end":"2026-07-24T05:16:40.483632Z","steps":["trace[937453300] 'agreement among raft nodes before linearized reading' (duration: 959.935376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.483651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.435999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-7hs2g\" ","response":"range_response_count:1 size:9799"} {"level":"warn","ts":"2026-07-24T05:16:40.483672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:39.523587Z","time spent":"960.07923ms","remote":"127.0.0.1:57330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":20672,"request content":"key:\"/registry/pods/openstack/libvirt-libvirt-default-lqm7h\" "} {"level":"info","ts":"2026-07-24T05:16:40.483695Z","caller":"traceutil/trace.go:171","msg":"trace[1279321320] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-7hs2g; range_end:; response_count:1; response_revision:17321; }","duration":"377.51192ms","start":"2026-07-24T05:16:40.106171Z","end":"2026-07-24T05:16:40.483683Z","steps":["trace[1279321320] 'agreement among raft nodes before linearized reading' (duration: 377.327047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.483745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:40.106131Z","time spent":"377.604902ms","remote":"127.0.0.1:57330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":9821,"request content":"key:\"/registry/pods/openstack/nova-cell-setup-7hs2g\" "} {"level":"warn","ts":"2026-07-24T05:16:40.483781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.416978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-24T05:16:40.483808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.132643085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-server-74c96f5d86-xgrjx.18c521fd1de78695\" ","response":"range_response_count:1 size:849"} {"level":"info","ts":"2026-07-24T05:16:40.483832Z","caller":"traceutil/trace.go:171","msg":"trace[732217902] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17321; }","duration":"423.50078ms","start":"2026-07-24T05:16:40.060319Z","end":"2026-07-24T05:16:40.48382Z","steps":["trace[732217902] 'agreement among raft nodes before linearized reading' (duration: 423.374837ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.482849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:38.956913Z","time spent":"1.525923498s","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-24T05:16:40.483884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:40.060306Z","time spent":"423.563181ms","remote":"127.0.0.1:57410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-24T05:16:40.484074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.726083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T05:16:40.483844Z","caller":"traceutil/trace.go:171","msg":"trace[733920251] range","detail":"{range_begin:/registry/events/openstack/neutron-server-74c96f5d86-xgrjx.18c521fd1de78695; range_end:; response_count:1; response_revision:17321; }","duration":"1.132710477s","start":"2026-07-24T05:16:39.351124Z","end":"2026-07-24T05:16:40.483835Z","steps":["trace[733920251] 'agreement among raft nodes before linearized reading' (duration: 1.132621055s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.484166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:39.351104Z","time spent":"1.133052304s","remote":"127.0.0.1:57248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":871,"request content":"key:\"/registry/events/openstack/neutron-server-74c96f5d86-xgrjx.18c521fd1de78695\" "} {"level":"warn","ts":"2026-07-24T05:16:40.48418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.724226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-24T05:16:40.484206Z","caller":"traceutil/trace.go:171","msg":"trace[2108984721] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17321; }","duration":"376.777029ms","start":"2026-07-24T05:16:40.107421Z","end":"2026-07-24T05:16:40.484198Z","steps":["trace[2108984721] 'agreement among raft nodes before linearized reading' (duration: 376.721178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.484244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:40.107404Z","time spent":"376.832179ms","remote":"127.0.0.1:57330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12234,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-24T05:16:40.484199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.894801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1991"} {"level":"info","ts":"2026-07-24T05:16:40.483521Z","caller":"traceutil/trace.go:171","msg":"trace[965077501] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17321; }","duration":"923.597193ms","start":"2026-07-24T05:16:39.559909Z","end":"2026-07-24T05:16:40.483506Z","steps":["trace[965077501] 'agreement among raft nodes before linearized reading' (duration: 923.492871ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:40.484335Z","caller":"traceutil/trace.go:171","msg":"trace[1688201291] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:17321; }","duration":"521.081324ms","start":"2026-07-24T05:16:39.963245Z","end":"2026-07-24T05:16:40.484326Z","steps":["trace[1688201291] 'agreement among raft nodes before linearized reading' (duration: 520.85972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.484373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:39.559886Z","time spent":"924.47908ms","remote":"127.0.0.1:57322","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":723,"request content":"key:\"/registry/services/endpoints/openstack/neutron-server\" "} {"level":"warn","ts":"2026-07-24T05:16:40.484382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:39.963222Z","time spent":"521.152056ms","remote":"127.0.0.1:57346","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2013,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-07-24T05:16:40.484124Z","caller":"traceutil/trace.go:171","msg":"trace[1888678272] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:17321; }","duration":"279.821745ms","start":"2026-07-24T05:16:40.20429Z","end":"2026-07-24T05:16:40.484112Z","steps":["trace[1888678272] 'agreement among raft nodes before linearized reading' (duration: 279.746643ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.48479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.526002249s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2vb7b\" ","response":"range_response_count:1 size:6521"} {"level":"info","ts":"2026-07-24T05:16:40.484838Z","caller":"traceutil/trace.go:171","msg":"trace[1617538831] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2vb7b; range_end:; response_count:1; response_revision:17321; }","duration":"1.52606304s","start":"2026-07-24T05:16:38.958765Z","end":"2026-07-24T05:16:40.484828Z","steps":["trace[1617538831] 'agreement among raft nodes before linearized reading' (duration: 1.525945008s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.484875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:38.958759Z","time spent":"1.526109511s","remote":"127.0.0.1:57330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6543,"request content":"key:\"/registry/pods/openstack/keepalived-2vb7b\" "} {"level":"warn","ts":"2026-07-24T05:16:40.488602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.23656591s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-sm795\" ","response":"range_response_count:1 size:22121"} {"level":"info","ts":"2026-07-24T05:16:40.488657Z","caller":"traceutil/trace.go:171","msg":"trace[1870760323] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-sm795; range_end:; response_count:1; response_revision:17321; }","duration":"1.236662992s","start":"2026-07-24T05:16:39.251981Z","end":"2026-07-24T05:16:40.488644Z","steps":["trace[1870760323] 'agreement among raft nodes before linearized reading' (duration: 1.230893772s)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.488687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-24T05:16:39.251963Z","time spent":"1.236717744s","remote":"127.0.0.1:57330","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22143,"request content":"key:\"/registry/pods/openstack/nova-compute-default-sm795\" "} {"level":"info","ts":"2026-07-24T05:16:40.60613Z","caller":"traceutil/trace.go:171","msg":"trace[73386830] transaction","detail":"{read_only:false; response_revision:17323; number_of_response:1; }","duration":"115.939345ms","start":"2026-07-24T05:16:40.49015Z","end":"2026-07-24T05:16:40.60609Z","steps":["trace[73386830] 'process raft request' (duration: 115.671769ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:40.606197Z","caller":"traceutil/trace.go:171","msg":"trace[920257411] transaction","detail":"{read_only:false; response_revision:17324; number_of_response:1; }","duration":"115.570068ms","start":"2026-07-24T05:16:40.490602Z","end":"2026-07-24T05:16:40.606172Z","steps":["trace[920257411] 'process raft request' (duration: 115.399075ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:40.606217Z","caller":"traceutil/trace.go:171","msg":"trace[1038885604] transaction","detail":"{read_only:false; response_revision:17325; number_of_response:1; }","duration":"115.396525ms","start":"2026-07-24T05:16:40.490793Z","end":"2026-07-24T05:16:40.60619Z","steps":["trace[1038885604] 'process raft request' (duration: 115.278173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.606421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.028736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-24T05:16:40.606486Z","caller":"traceutil/trace.go:171","msg":"trace[1879370651] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17326; }","duration":"116.121648ms","start":"2026-07-24T05:16:40.490345Z","end":"2026-07-24T05:16:40.606467Z","steps":["trace[1879370651] 'agreement among raft nodes before linearized reading' (duration: 115.959685ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.606563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.054787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/openstack/network\" ","response":"range_response_count:1 size:694"} {"level":"info","ts":"2026-07-24T05:16:40.606632Z","caller":"traceutil/trace.go:171","msg":"trace[1976970678] range","detail":"{range_begin:/registry/ingress/openstack/network; range_end:; response_count:1; response_revision:17326; }","duration":"116.146129ms","start":"2026-07-24T05:16:40.490466Z","end":"2026-07-24T05:16:40.606612Z","steps":["trace[1976970678] 'agreement among raft nodes before linearized reading' (duration: 115.980615ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:40.606725Z","caller":"traceutil/trace.go:171","msg":"trace[794759488] transaction","detail":"{read_only:false; response_revision:17326; number_of_response:1; }","duration":"115.011047ms","start":"2026-07-24T05:16:40.49164Z","end":"2026-07-24T05:16:40.606651Z","steps":["trace[794759488] 'process raft request' (duration: 114.493268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.608214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.845954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:937583"} {"level":"info","ts":"2026-07-24T05:16:40.608266Z","caller":"traceutil/trace.go:171","msg":"trace[1049063481] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17326; }","duration":"114.912935ms","start":"2026-07-24T05:16:40.493341Z","end":"2026-07-24T05:16:40.608254Z","steps":["trace[1049063481] 'agreement among raft nodes before linearized reading' (duration: 113.008049ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.608288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.520946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:937583"} {"level":"info","ts":"2026-07-24T05:16:40.608325Z","caller":"traceutil/trace.go:171","msg":"trace[365589858] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17326; }","duration":"116.606087ms","start":"2026-07-24T05:16:40.491709Z","end":"2026-07-24T05:16:40.608315Z","steps":["trace[365589858] 'agreement among raft nodes before linearized reading' (duration: 114.726431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.608372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.687631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:937583"} {"level":"info","ts":"2026-07-24T05:16:40.608475Z","caller":"traceutil/trace.go:171","msg":"trace[1537972903] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17326; }","duration":"113.815624ms","start":"2026-07-24T05:16:40.494635Z","end":"2026-07-24T05:16:40.60845Z","steps":["trace[1537972903] 'agreement among raft nodes before linearized reading' (duration: 111.953749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.608684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.046836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:937583"} {"level":"warn","ts":"2026-07-24T05:16:40.608754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.083038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:937583"} {"level":"info","ts":"2026-07-24T05:16:40.608767Z","caller":"traceutil/trace.go:171","msg":"trace[534696585] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17326; }","duration":"116.161889ms","start":"2026-07-24T05:16:40.492588Z","end":"2026-07-24T05:16:40.60875Z","steps":["trace[534696585] 'agreement among raft nodes before linearized reading' (duration: 113.825064ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:40.60881Z","caller":"traceutil/trace.go:171","msg":"trace[71988554] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17326; }","duration":"116.146809ms","start":"2026-07-24T05:16:40.49265Z","end":"2026-07-24T05:16:40.608797Z","steps":["trace[71988554] 'agreement among raft nodes before linearized reading' (duration: 113.723902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.609081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.976738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:937583"} {"level":"info","ts":"2026-07-24T05:16:40.609137Z","caller":"traceutil/trace.go:171","msg":"trace[1193814611] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17326; }","duration":"114.043349ms","start":"2026-07-24T05:16:40.49508Z","end":"2026-07-24T05:16:40.609124Z","steps":["trace[1193814611] 'agreement among raft nodes before linearized reading' (duration: 111.49328ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:40.712685Z","caller":"traceutil/trace.go:171","msg":"trace[67270728] transaction","detail":"{read_only:false; response_revision:17328; number_of_response:1; }","duration":"100.204374ms","start":"2026-07-24T05:16:40.612448Z","end":"2026-07-24T05:16:40.712653Z","steps":["trace[67270728] 'process raft request' (duration: 99.934509ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:40.864712Z","caller":"traceutil/trace.go:171","msg":"trace[1478228425] transaction","detail":"{read_only:false; response_revision:17330; number_of_response:1; }","duration":"146.337084ms","start":"2026-07-24T05:16:40.718337Z","end":"2026-07-24T05:16:40.864674Z","steps":["trace[1478228425] 'process raft request' (duration: 126.983826ms)","trace[1478228425] 'compare' (duration: 19.039393ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:40.86487Z","caller":"traceutil/trace.go:171","msg":"trace[35520530] transaction","detail":"{read_only:false; response_revision:17331; number_of_response:1; }","duration":"145.212343ms","start":"2026-07-24T05:16:40.719629Z","end":"2026-07-24T05:16:40.864841Z","steps":["trace[35520530] 'process raft request' (duration: 144.934358ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:40.864866Z","caller":"traceutil/trace.go:171","msg":"trace[1692428023] linearizableReadLoop","detail":"{readStateIndex:17917; appliedIndex:17914; }","duration":"143.072283ms","start":"2026-07-24T05:16:40.721742Z","end":"2026-07-24T05:16:40.864815Z","steps":["trace[1692428023] 'read index received' (duration: 47.601189ms)","trace[1692428023] 'applied index is now lower than readState.Index' (duration: 95.467324ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:40.865101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.326908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-07-24T05:16:40.865168Z","caller":"traceutil/trace.go:171","msg":"trace[1344647827] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:17331; }","duration":"143.43869ms","start":"2026-07-24T05:16:40.721709Z","end":"2026-07-24T05:16:40.865148Z","steps":["trace[1344647827] 'agreement among raft nodes before linearized reading' (duration: 143.228316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:40.865314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.558734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T05:16:40.865367Z","caller":"traceutil/trace.go:171","msg":"trace[1844088913] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:17331; }","duration":"108.676536ms","start":"2026-07-24T05:16:40.756678Z","end":"2026-07-24T05:16:40.865354Z","steps":["trace[1844088913] 'agreement among raft nodes before linearized reading' (duration: 108.547834ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:42.59632Z","caller":"traceutil/trace.go:171","msg":"trace[851647563] transaction","detail":"{read_only:false; response_revision:17337; number_of_response:1; }","duration":"108.148606ms","start":"2026-07-24T05:16:42.488143Z","end":"2026-07-24T05:16:42.596292Z","steps":["trace[851647563] 'process raft request' (duration: 86.253807ms)","trace[851647563] 'compare' (duration: 21.695235ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:42.596709Z","caller":"traceutil/trace.go:171","msg":"trace[608668773] transaction","detail":"{read_only:false; response_revision:17338; number_of_response:1; }","duration":"106.534205ms","start":"2026-07-24T05:16:42.490156Z","end":"2026-07-24T05:16:42.59669Z","steps":["trace[608668773] 'process raft request' (duration: 106.090197ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:42.596716Z","caller":"traceutil/trace.go:171","msg":"trace[103752022] linearizableReadLoop","detail":"{readStateIndex:17924; appliedIndex:17922; }","duration":"108.39363ms","start":"2026-07-24T05:16:42.488295Z","end":"2026-07-24T05:16:42.596688Z","steps":["trace[103752022] 'read index received' (duration: 54.769806ms)","trace[103752022] 'applied index is now lower than readState.Index' (duration: 53.622594ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:42.596749Z","caller":"traceutil/trace.go:171","msg":"trace[644346632] transaction","detail":"{read_only:false; response_revision:17339; number_of_response:1; }","duration":"104.931075ms","start":"2026-07-24T05:16:42.491785Z","end":"2026-07-24T05:16:42.596716Z","steps":["trace[644346632] 'process raft request' (duration: 104.782192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:42.59689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.578813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-07-24T05:16:42.59693Z","caller":"traceutil/trace.go:171","msg":"trace[555637098] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17339; }","duration":"108.658416ms","start":"2026-07-24T05:16:42.488263Z","end":"2026-07-24T05:16:42.596922Z","steps":["trace[555637098] 'agreement among raft nodes before linearized reading' (duration: 108.511073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:42.596991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.875011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-07-24T05:16:42.597036Z","caller":"traceutil/trace.go:171","msg":"trace[218920705] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17339; }","duration":"107.974413ms","start":"2026-07-24T05:16:42.489052Z","end":"2026-07-24T05:16:42.597026Z","steps":["trace[218920705] 'agreement among raft nodes before linearized reading' (duration: 107.799629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:42.597197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.651538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"warn","ts":"2026-07-24T05:16:42.597206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.314333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-07-24T05:16:42.597231Z","caller":"traceutil/trace.go:171","msg":"trace[1362390264] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17339; }","duration":"105.7061ms","start":"2026-07-24T05:16:42.491515Z","end":"2026-07-24T05:16:42.597221Z","steps":["trace[1362390264] 'agreement among raft nodes before linearized reading' (duration: 105.615678ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:42.597246Z","caller":"traceutil/trace.go:171","msg":"trace[82377753] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:17339; }","duration":"103.406945ms","start":"2026-07-24T05:16:42.493826Z","end":"2026-07-24T05:16:42.597233Z","steps":["trace[82377753] 'agreement among raft nodes before linearized reading' (duration: 103.241002ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:42.80658Z","caller":"traceutil/trace.go:171","msg":"trace[1635077202] linearizableReadLoop","detail":"{readStateIndex:17928; appliedIndex:17927; }","duration":"186.160506ms","start":"2026-07-24T05:16:42.620389Z","end":"2026-07-24T05:16:42.80655Z","steps":["trace[1635077202] 'read index received' (duration: 144.585812ms)","trace[1635077202] 'applied index is now lower than readState.Index' (duration: 41.573304ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:42.806722Z","caller":"traceutil/trace.go:171","msg":"trace[1411386882] transaction","detail":"{read_only:false; response_revision:17341; number_of_response:1; }","duration":"203.065569ms","start":"2026-07-24T05:16:42.60361Z","end":"2026-07-24T05:16:42.806675Z","steps":["trace[1411386882] 'process raft request' (duration: 161.471335ms)","trace[1411386882] 'compare' (duration: 41.273518ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:42.806925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.370042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-24T05:16:42.806973Z","caller":"traceutil/trace.go:171","msg":"trace[1156042372] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:17341; }","duration":"194.462684ms","start":"2026-07-24T05:16:42.612499Z","end":"2026-07-24T05:16:42.806961Z","steps":["trace[1156042372] 'agreement among raft nodes before linearized reading' (duration: 194.233739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:42.807051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.878581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-24T05:16:42.807061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.489714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"warn","ts":"2026-07-24T05:16:42.807084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.70284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-sm795\" ","response":"range_response_count:1 size:22121"} {"level":"info","ts":"2026-07-24T05:16:42.807142Z","caller":"traceutil/trace.go:171","msg":"trace[818922733] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:17341; }","duration":"196.991403ms","start":"2026-07-24T05:16:42.610129Z","end":"2026-07-24T05:16:42.807121Z","steps":["trace[818922733] 'agreement among raft nodes before linearized reading' (duration: 196.709217ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:42.807155Z","caller":"traceutil/trace.go:171","msg":"trace[170915153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:17341; }","duration":"196.598115ms","start":"2026-07-24T05:16:42.610533Z","end":"2026-07-24T05:16:42.807131Z","steps":["trace[170915153] 'agreement among raft nodes before linearized reading' (duration: 196.35466ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:42.807177Z","caller":"traceutil/trace.go:171","msg":"trace[1439252855] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-sm795; range_end:; response_count:1; response_revision:17341; }","duration":"171.821152ms","start":"2026-07-24T05:16:42.635334Z","end":"2026-07-24T05:16:42.807155Z","steps":["trace[1439252855] 'agreement among raft nodes before linearized reading' (duration: 171.560426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:42.807266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.84778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-lqm7h\" ","response":"range_response_count:1 size:20650"} {"level":"info","ts":"2026-07-24T05:16:42.807302Z","caller":"traceutil/trace.go:171","msg":"trace[141296524] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-lqm7h; range_end:; response_count:1; response_revision:17341; }","duration":"163.90098ms","start":"2026-07-24T05:16:42.643394Z","end":"2026-07-24T05:16:42.807295Z","steps":["trace[141296524] 'agreement among raft nodes before linearized reading' (duration: 163.811889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:42.807327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.928715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-sm795\" ","response":"range_response_count:1 size:22121"} {"level":"warn","ts":"2026-07-24T05:16:42.807187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.281227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-7hs2g\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-07-24T05:16:42.807489Z","caller":"traceutil/trace.go:171","msg":"trace[780427831] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-7hs2g; range_end:; response_count:1; response_revision:17341; }","duration":"165.583623ms","start":"2026-07-24T05:16:42.64188Z","end":"2026-07-24T05:16:42.807464Z","steps":["trace[780427831] 'agreement among raft nodes before linearized reading' (duration: 165.237946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:42.807372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.13914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-metadata-agent-default-67zw4\" ","response":"range_response_count:1 size:15432"} {"level":"info","ts":"2026-07-24T05:16:42.807939Z","caller":"traceutil/trace.go:171","msg":"trace[1008261440] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-metadata-agent-default-67zw4; range_end:; response_count:1; response_revision:17341; }","duration":"170.733171ms","start":"2026-07-24T05:16:42.637191Z","end":"2026-07-24T05:16:42.807924Z","steps":["trace[1008261440] 'agreement among raft nodes before linearized reading' (duration: 170.095719ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:42.807372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.391025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ovn-vpn-agent-default-r4jh6\" ","response":"range_response_count:1 size:15441"} {"level":"info","ts":"2026-07-24T05:16:42.808246Z","caller":"traceutil/trace.go:171","msg":"trace[1726196274] range","detail":"{range_begin:/registry/pods/openstack/neutron-ovn-vpn-agent-default-r4jh6; range_end:; response_count:1; response_revision:17341; }","duration":"171.256531ms","start":"2026-07-24T05:16:42.63697Z","end":"2026-07-24T05:16:42.808227Z","steps":["trace[1726196274] 'agreement among raft nodes before linearized reading' (duration: 170.308393ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:42.807382Z","caller":"traceutil/trace.go:171","msg":"trace[1384486404] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-sm795; range_end:; response_count:1; response_revision:17341; }","duration":"160.044497ms","start":"2026-07-24T05:16:42.647325Z","end":"2026-07-24T05:16:42.80737Z","steps":["trace[1384486404] 'agreement among raft nodes before linearized reading' (duration: 159.798362ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:44.715216Z","caller":"traceutil/trace.go:171","msg":"trace[459681124] linearizableReadLoop","detail":"{readStateIndex:17934; appliedIndex:17933; }","duration":"112.451768ms","start":"2026-07-24T05:16:44.602721Z","end":"2026-07-24T05:16:44.715173Z","steps":["trace[459681124] 'read index received' (duration: 68.353136ms)","trace[459681124] 'applied index is now lower than readState.Index' (duration: 44.097002ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:44.715308Z","caller":"traceutil/trace.go:171","msg":"trace[782373290] transaction","detail":"{read_only:false; response_revision:17347; number_of_response:1; }","duration":"112.936116ms","start":"2026-07-24T05:16:44.602318Z","end":"2026-07-24T05:16:44.715254Z","steps":["trace[782373290] 'process raft request' (duration: 68.62291ms)","trace[782373290] 'compare' (duration: 44.051142ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:44.715491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.743424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/neutron-server\" ","response":"range_response_count:1 size:701"} {"level":"info","ts":"2026-07-24T05:16:44.715597Z","caller":"traceutil/trace.go:171","msg":"trace[1904794768] range","detail":"{range_begin:/registry/services/endpoints/openstack/neutron-server; range_end:; response_count:1; response_revision:17347; }","duration":"112.879696ms","start":"2026-07-24T05:16:44.602696Z","end":"2026-07-24T05:16:44.715576Z","steps":["trace[1904794768] 'agreement among raft nodes before linearized reading' (duration: 112.625842ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:44.776735Z","caller":"traceutil/trace.go:171","msg":"trace[1828022682] transaction","detail":"{read_only:false; response_revision:17348; number_of_response:1; }","duration":"173.710308ms","start":"2026-07-24T05:16:44.603003Z","end":"2026-07-24T05:16:44.776713Z","steps":["trace[1828022682] 'process raft request' (duration: 173.466474ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:44.776901Z","caller":"traceutil/trace.go:171","msg":"trace[301612997] transaction","detail":"{read_only:false; response_revision:17349; number_of_response:1; }","duration":"173.777839ms","start":"2026-07-24T05:16:44.603096Z","end":"2026-07-24T05:16:44.776874Z","steps":["trace[301612997] 'process raft request' (duration: 173.570586ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:44.777058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.20819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-2vb7b\" ","response":"range_response_count:1 size:6521"} {"level":"info","ts":"2026-07-24T05:16:44.777103Z","caller":"traceutil/trace.go:171","msg":"trace[947531627] range","detail":"{range_begin:/registry/pods/openstack/keepalived-2vb7b; range_end:; response_count:1; response_revision:17349; }","duration":"138.271061ms","start":"2026-07-24T05:16:44.63882Z","end":"2026-07-24T05:16:44.777091Z","steps":["trace[947531627] 'agreement among raft nodes before linearized reading' (duration: 138.053996ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:44.77759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.285347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T05:16:44.777657Z","caller":"traceutil/trace.go:171","msg":"trace[1929298780] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17349; }","duration":"153.351298ms","start":"2026-07-24T05:16:44.624286Z","end":"2026-07-24T05:16:44.777637Z","steps":["trace[1929298780] 'agreement among raft nodes before linearized reading' (duration: 152.580773ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:44.923556Z","caller":"traceutil/trace.go:171","msg":"trace[271008241] transaction","detail":"{read_only:false; response_revision:17351; number_of_response:1; }","duration":"106.718339ms","start":"2026-07-24T05:16:44.816804Z","end":"2026-07-24T05:16:44.923522Z","steps":["trace[271008241] 'process raft request' (duration: 57.636761ms)","trace[271008241] 'compare' (duration: 48.846403ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:16:45.130489Z","caller":"traceutil/trace.go:171","msg":"trace[1576032838] transaction","detail":"{read_only:false; response_revision:17352; number_of_response:1; }","duration":"202.295534ms","start":"2026-07-24T05:16:44.928165Z","end":"2026-07-24T05:16:45.13046Z","steps":["trace[1576032838] 'process raft request' (duration: 202.07086ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:45.151032Z","caller":"traceutil/trace.go:171","msg":"trace[823659677] transaction","detail":"{read_only:false; response_revision:17353; number_of_response:1; }","duration":"222.118814ms","start":"2026-07-24T05:16:44.928885Z","end":"2026-07-24T05:16:45.151004Z","steps":["trace[823659677] 'process raft request' (duration: 221.606753ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:45.151127Z","caller":"traceutil/trace.go:171","msg":"trace[702843500] transaction","detail":"{read_only:false; response_revision:17354; number_of_response:1; }","duration":"221.020823ms","start":"2026-07-24T05:16:44.930087Z","end":"2026-07-24T05:16:45.151108Z","steps":["trace[702843500] 'process raft request' (duration: 220.786858ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:16:45.151533Z","caller":"traceutil/trace.go:171","msg":"trace[20715555] linearizableReadLoop","detail":"{readStateIndex:17940; appliedIndex:17938; }","duration":"220.866499ms","start":"2026-07-24T05:16:44.930101Z","end":"2026-07-24T05:16:45.150968Z","steps":["trace[20715555] 'read index received' (duration: 200.046101ms)","trace[20715555] 'applied index is now lower than readState.Index' (duration: 20.818968ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:45.152199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.083042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:937583"} {"level":"info","ts":"2026-07-24T05:16:45.152237Z","caller":"traceutil/trace.go:171","msg":"trace[394583905] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17354; }","duration":"222.162384ms","start":"2026-07-24T05:16:44.930066Z","end":"2026-07-24T05:16:45.152228Z","steps":["trace[394583905] 'agreement among raft nodes before linearized reading' (duration: 221.228486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:45.152803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.007739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-07-24T05:16:45.152885Z","caller":"traceutil/trace.go:171","msg":"trace[82626695] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-sb-0; range_end:; response_count:1; response_revision:17354; }","duration":"212.126192ms","start":"2026-07-24T05:16:44.940742Z","end":"2026-07-24T05:16:45.152868Z","steps":["trace[82626695] 'agreement among raft nodes before linearized reading' (duration: 211.955619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:45.153484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.444159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:937583"} {"level":"info","ts":"2026-07-24T05:16:45.153547Z","caller":"traceutil/trace.go:171","msg":"trace[849631020] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17354; }","duration":"222.590092ms","start":"2026-07-24T05:16:44.930943Z","end":"2026-07-24T05:16:45.153533Z","steps":["trace[849631020] 'agreement among raft nodes before linearized reading' (duration: 221.597473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:45.159315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.23661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:937583"} {"level":"info","ts":"2026-07-24T05:16:45.159665Z","caller":"traceutil/trace.go:171","msg":"trace[747829684] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17354; }","duration":"228.579626ms","start":"2026-07-24T05:16:44.931055Z","end":"2026-07-24T05:16:45.159635Z","steps":["trace[747829684] 'agreement among raft nodes before linearized reading' (duration: 221.275327ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:45.163174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.99463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:937583"} {"level":"info","ts":"2026-07-24T05:16:45.163232Z","caller":"traceutil/trace.go:171","msg":"trace[1918434673] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17354; }","duration":"233.068921ms","start":"2026-07-24T05:16:44.93015Z","end":"2026-07-24T05:16:45.163219Z","steps":["trace[1918434673] 'agreement among raft nodes before linearized reading' (duration: 221.537811ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:16:45.167277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.525759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:937583"} {"level":"info","ts":"2026-07-24T05:16:45.167362Z","caller":"traceutil/trace.go:171","msg":"trace[1725105987] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17354; }","duration":"235.643411ms","start":"2026-07-24T05:16:44.931697Z","end":"2026-07-24T05:16:45.16734Z","steps":["trace[1725105987] 'agreement among raft nodes before linearized reading' (duration: 220.41068ms)","trace[1725105987] 'range keys from bolt db' (duration: 14.926875ms)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:16:45.167383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.188894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:95 size:937583"} {"level":"info","ts":"2026-07-24T05:16:45.167413Z","caller":"traceutil/trace.go:171","msg":"trace[929120273] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:95; response_revision:17354; }","duration":"234.245954ms","start":"2026-07-24T05:16:44.933159Z","end":"2026-07-24T05:16:45.167405Z","steps":["trace[929120273] 'agreement among raft nodes before linearized reading' (duration: 219.934021ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:17:19.24616Z","caller":"traceutil/trace.go:171","msg":"trace[408233442] linearizableReadLoop","detail":"{readStateIndex:18230; appliedIndex:18229; }","duration":"124.348336ms","start":"2026-07-24T05:17:19.121784Z","end":"2026-07-24T05:17:19.246133Z","steps":["trace[408233442] 'read index received' (duration: 124.155022ms)","trace[408233442] 'applied index is now lower than readState.Index' (duration: 192.534µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:17:19.246263Z","caller":"traceutil/trace.go:171","msg":"trace[632158440] transaction","detail":"{read_only:false; response_revision:17634; number_of_response:1; }","duration":"124.576419ms","start":"2026-07-24T05:17:19.121649Z","end":"2026-07-24T05:17:19.246226Z","steps":["trace[632158440] 'process raft request' (duration: 124.317285ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:17:19.24644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.635081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-07-24T05:17:19.246483Z","caller":"traceutil/trace.go:171","msg":"trace[509574515] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17634; }","duration":"124.736092ms","start":"2026-07-24T05:17:19.121733Z","end":"2026-07-24T05:17:19.246469Z","steps":["trace[509574515] 'agreement among raft nodes before linearized reading' (duration: 124.496888ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:17:25.171323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.673787ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-24T05:17:25.171461Z","caller":"traceutil/trace.go:171","msg":"trace[2145715036] transaction","detail":"{read_only:false; response_revision:17684; number_of_response:1; }","duration":"193.533557ms","start":"2026-07-24T05:17:24.977906Z","end":"2026-07-24T05:17:25.171439Z","steps":["trace[2145715036] 'process raft request' (duration: 63.562244ms)","trace[2145715036] 'compare' (duration: 129.423983ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:17:25.171523Z","caller":"traceutil/trace.go:171","msg":"trace[376789829] transaction","detail":"{read_only:false; response_revision:17685; number_of_response:1; }","duration":"148.547618ms","start":"2026-07-24T05:17:25.022968Z","end":"2026-07-24T05:17:25.171516Z","steps":["trace[376789829] 'process raft request' (duration: 148.446236ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:17:25.171801Z","caller":"traceutil/trace.go:171","msg":"trace[1947674023] transaction","detail":"{read_only:false; response_revision:17686; number_of_response:1; }","duration":"147.053209ms","start":"2026-07-24T05:17:25.024717Z","end":"2026-07-24T05:17:25.17177Z","steps":["trace[1947674023] 'process raft request' (duration: 146.774234ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:17:25.403639Z","caller":"traceutil/trace.go:171","msg":"trace[651975977] linearizableReadLoop","detail":"{readStateIndex:18285; appliedIndex:18284; }","duration":"108.077775ms","start":"2026-07-24T05:17:25.295542Z","end":"2026-07-24T05:17:25.403619Z","steps":["trace[651975977] 'read index received' (duration: 107.873451ms)","trace[651975977] 'applied index is now lower than readState.Index' (duration: 203.524µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:17:25.403824Z","caller":"traceutil/trace.go:171","msg":"trace[1596761123] transaction","detail":"{read_only:false; response_revision:17688; number_of_response:1; }","duration":"113.473717ms","start":"2026-07-24T05:17:25.290341Z","end":"2026-07-24T05:17:25.403815Z","steps":["trace[1596761123] 'process raft request' (duration: 113.147801ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:17:25.404075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.528863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-07-24T05:17:25.404122Z","caller":"traceutil/trace.go:171","msg":"trace[23863819] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:17688; }","duration":"108.619864ms","start":"2026-07-24T05:17:25.295493Z","end":"2026-07-24T05:17:25.404113Z","steps":["trace[23863819] 'agreement among raft nodes before linearized reading' (duration: 108.488631ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:17:47.978753Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15525} {"level":"info","ts":"2026-07-24T05:17:48.084169Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15525,"took":"101.296445ms","hash":3008503407,"current-db-size-bytes":58003456,"current-db-size":"58 MB","current-db-size-in-use-bytes":33591296,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-24T05:17:48.084234Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3008503407,"revision":15525,"compact-revision":13011} {"level":"info","ts":"2026-07-24T05:20:49.925127Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"50e03cebfd94cbba","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-24T05:20:49.930778Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-24T05:20:49.931Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-24T05:22:37.438544Z","caller":"traceutil/trace.go:171","msg":"trace[495667789] transaction","detail":"{read_only:false; response_revision:20096; number_of_response:1; }","duration":"155.663723ms","start":"2026-07-24T05:22:37.282839Z","end":"2026-07-24T05:22:37.438503Z","steps":["trace[495667789] 'process raft request' (duration: 142.141254ms)","trace[495667789] 'compare' (duration: 13.330515ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:22:37.447532Z","caller":"traceutil/trace.go:171","msg":"trace[269320975] transaction","detail":"{read_only:false; response_revision:20097; number_of_response:1; }","duration":"164.270727ms","start":"2026-07-24T05:22:37.283215Z","end":"2026-07-24T05:22:37.447486Z","steps":["trace[269320975] 'process raft request' (duration: 157.328714ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:22:37.447779Z","caller":"traceutil/trace.go:171","msg":"trace[463689835] transaction","detail":"{read_only:false; response_revision:20098; number_of_response:1; }","duration":"164.054503ms","start":"2026-07-24T05:22:37.283631Z","end":"2026-07-24T05:22:37.447686Z","steps":["trace[463689835] 'process raft request' (duration: 163.709026ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:22:37.588092Z","caller":"traceutil/trace.go:171","msg":"trace[1586412462] transaction","detail":"{read_only:false; response_revision:20099; number_of_response:1; }","duration":"136.108488ms","start":"2026-07-24T05:22:37.451942Z","end":"2026-07-24T05:22:37.588051Z","steps":["trace[1586412462] 'process raft request' (duration: 125.630787ms)","trace[1586412462] 'compare' (duration: 10.257207ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:22:37.777115Z","caller":"traceutil/trace.go:171","msg":"trace[802545335] transaction","detail":"{read_only:false; response_revision:20100; number_of_response:1; }","duration":"150.812589ms","start":"2026-07-24T05:22:37.626262Z","end":"2026-07-24T05:22:37.777075Z","steps":["trace[802545335] 'process raft request' (duration: 108.937438ms)","trace[802545335] 'compare' (duration: 41.721728ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:22:47.985115Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18033} {"level":"info","ts":"2026-07-24T05:22:48.100016Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18033,"took":"110.860764ms","hash":3754795290,"current-db-size-bytes":58003456,"current-db-size":"58 MB","current-db-size-in-use-bytes":29687808,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-24T05:22:48.100079Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3754795290,"revision":18033,"compact-revision":15525} {"level":"warn","ts":"2026-07-24T05:26:18.777157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.524912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"warn","ts":"2026-07-24T05:26:18.777263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.354181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/openstack/ceph\" ","response":"range_response_count:1 size:7914"} {"level":"info","ts":"2026-07-24T05:26:18.777274Z","caller":"traceutil/trace.go:171","msg":"trace[2017809120] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:22470; }","duration":"225.676005ms","start":"2026-07-24T05:26:18.551575Z","end":"2026-07-24T05:26:18.777251Z","steps":["trace[2017809120] 'range keys from in-memory index tree' (duration: 225.380088ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:26:18.777345Z","caller":"traceutil/trace.go:171","msg":"trace[44165422] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/openstack/ceph; range_end:; response_count:1; response_revision:22470; }","duration":"210.443023ms","start":"2026-07-24T05:26:18.566886Z","end":"2026-07-24T05:26:18.777329Z","steps":["trace[44165422] 'range keys from in-memory index tree' (duration: 210.253609ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:26:18.836731Z","caller":"traceutil/trace.go:171","msg":"trace[1017452571] linearizableReadLoop","detail":"{readStateIndex:23224; appliedIndex:23223; }","duration":"203.499608ms","start":"2026-07-24T05:26:18.633208Z","end":"2026-07-24T05:26:18.836708Z","steps":["trace[1017452571] 'read index received' (duration: 203.355996ms)","trace[1017452571] 'applied index is now lower than readState.Index' (duration: 141.142µs)"],"step_count":2} {"level":"warn","ts":"2026-07-24T05:26:18.837107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.894075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-24T05:26:18.837183Z","caller":"traceutil/trace.go:171","msg":"trace[1029197011] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22471; }","duration":"204.000898ms","start":"2026-07-24T05:26:18.633165Z","end":"2026-07-24T05:26:18.837166Z","steps":["trace[1029197011] 'agreement among raft nodes before linearized reading' (duration: 203.788094ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:26:18.837174Z","caller":"traceutil/trace.go:171","msg":"trace[210234341] transaction","detail":"{read_only:false; response_revision:22471; number_of_response:1; }","duration":"252.996336ms","start":"2026-07-24T05:26:18.584134Z","end":"2026-07-24T05:26:18.83713Z","steps":["trace[210234341] 'process raft request' (duration: 252.410865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:26:18.837641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.62287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-07-24T05:26:18.837879Z","caller":"traceutil/trace.go:171","msg":"trace[1912320519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22471; }","duration":"193.816424ms","start":"2026-07-24T05:26:18.643932Z","end":"2026-07-24T05:26:18.837749Z","steps":["trace[1912320519] 'agreement among raft nodes before linearized reading' (duration: 193.1205ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:27:34.430911Z","caller":"traceutil/trace.go:171","msg":"trace[66727699] linearizableReadLoop","detail":"{readStateIndex:24045; appliedIndex:24044; }","duration":"249.040209ms","start":"2026-07-24T05:27:34.181823Z","end":"2026-07-24T05:27:34.430863Z","steps":["trace[66727699] 'read index received' (duration: 248.768334ms)","trace[66727699] 'applied index is now lower than readState.Index' (duration: 270.065µs)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:27:34.431068Z","caller":"traceutil/trace.go:171","msg":"trace[667466807] transaction","detail":"{read_only:false; response_revision:23270; number_of_response:1; }","duration":"254.251079ms","start":"2026-07-24T05:27:34.176735Z","end":"2026-07-24T05:27:34.430986Z","steps":["trace[667466807] 'process raft request' (duration: 253.873712ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:34.431215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.357575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-24T05:27:34.431313Z","caller":"traceutil/trace.go:171","msg":"trace[1307052612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:23270; }","duration":"249.500148ms","start":"2026-07-24T05:27:34.181791Z","end":"2026-07-24T05:27:34.431291Z","steps":["trace[1307052612] 'agreement among raft nodes before linearized reading' (duration: 249.228183ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:27:34.4596Z","caller":"traceutil/trace.go:171","msg":"trace[1517034137] transaction","detail":"{read_only:false; response_revision:23271; number_of_response:1; }","duration":"163.349784ms","start":"2026-07-24T05:27:34.296223Z","end":"2026-07-24T05:27:34.459572Z","steps":["trace[1517034137] 'process raft request' (duration: 163.1235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:34.459959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.146515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-24T05:27:34.460009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.770126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T05:27:34.460041Z","caller":"traceutil/trace.go:171","msg":"trace[1578061294] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:23272; }","duration":"181.265167ms","start":"2026-07-24T05:27:34.278756Z","end":"2026-07-24T05:27:34.460022Z","steps":["trace[1578061294] 'agreement among raft nodes before linearized reading' (duration: 181.109944ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:27:34.460057Z","caller":"traceutil/trace.go:171","msg":"trace[1004769905] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:23272; }","duration":"181.848318ms","start":"2026-07-24T05:27:34.278195Z","end":"2026-07-24T05:27:34.460043Z","steps":["trace[1004769905] 'agreement among raft nodes before linearized reading' (duration: 181.737486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:34.460183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.881738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-07-24T05:27:34.460158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.97241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T05:27:34.461494Z","caller":"traceutil/trace.go:171","msg":"trace[1441824574] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:0; response_revision:23272; }","duration":"183.369967ms","start":"2026-07-24T05:27:34.278087Z","end":"2026-07-24T05:27:34.461457Z","steps":["trace[1441824574] 'agreement among raft nodes before linearized reading' (duration: 181.934069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:34.460199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.131234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T05:27:34.461819Z","caller":"traceutil/trace.go:171","msg":"trace[1777099418] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:23272; }","duration":"172.846257ms","start":"2026-07-24T05:27:34.288961Z","end":"2026-07-24T05:27:34.461807Z","steps":["trace[1777099418] 'agreement among raft nodes before linearized reading' (duration: 171.105364ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:34.460218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.379359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T05:27:34.462052Z","caller":"traceutil/trace.go:171","msg":"trace[340996504] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:23272; }","duration":"183.218034ms","start":"2026-07-24T05:27:34.278799Z","end":"2026-07-24T05:27:34.462017Z","steps":["trace[340996504] 'agreement among raft nodes before linearized reading' (duration: 181.351959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:34.46022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.385083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T05:27:34.462201Z","caller":"traceutil/trace.go:171","msg":"trace[1796698306] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:23272; }","duration":"211.380811ms","start":"2026-07-24T05:27:34.250796Z","end":"2026-07-24T05:27:34.462177Z","steps":["trace[1796698306] 'agreement among raft nodes before linearized reading' (duration: 209.338382ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:27:34.460613Z","caller":"traceutil/trace.go:171","msg":"trace[396994512] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:23272; }","duration":"181.93737ms","start":"2026-07-24T05:27:34.278271Z","end":"2026-07-24T05:27:34.460208Z","steps":["trace[396994512] 'agreement among raft nodes before linearized reading' (duration: 181.890679ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:34.460763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.060992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-24T05:27:34.462352Z","caller":"traceutil/trace.go:171","msg":"trace[563220157] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:23272; }","duration":"247.681544ms","start":"2026-07-24T05:27:34.214647Z","end":"2026-07-24T05:27:34.462328Z","steps":["trace[563220157] 'agreement among raft nodes before linearized reading' (duration: 245.183106ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:27:48.005006Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20151} {"level":"warn","ts":"2026-07-24T05:27:48.60714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.660553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T05:27:48.607311Z","caller":"traceutil/trace.go:171","msg":"trace[1302824503] range","detail":"{range_begin:/registry/jobs/openstack/magnum-rabbit-init; range_end:; response_count:0; response_revision:23405; }","duration":"107.897428ms","start":"2026-07-24T05:27:48.499387Z","end":"2026-07-24T05:27:48.607284Z","steps":["trace[1302824503] 'agreement among raft nodes before linearized reading' (duration: 70.877051ms)","trace[1302824503] 'range keys from in-memory index tree' (duration: 36.801283ms)"],"step_count":2} {"level":"info","ts":"2026-07-24T05:27:48.633544Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20151,"took":"624.543341ms","hash":3254878986,"current-db-size-bytes":58003456,"current-db-size":"58 MB","current-db-size-in-use-bytes":43196416,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-07-24T05:27:48.633621Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3254878986,"revision":20151,"compact-revision":18033} {"level":"info","ts":"2026-07-24T05:27:48.633975Z","caller":"traceutil/trace.go:171","msg":"trace[1281823808] transaction","detail":"{read_only:false; response_revision:23406; number_of_response:1; }","duration":"105.172615ms","start":"2026-07-24T05:27:48.528736Z","end":"2026-07-24T05:27:48.633908Z","steps":["trace[1281823808] 'process raft request' (duration: 41.48703ms)","trace[1281823808] 'compare' (duration: 36.836623ms)","trace[1281823808] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/capi-system/controller-leader-election-capi; req_size:519; } (duration: 26.622907ms)"],"step_count":3} {"level":"warn","ts":"2026-07-24T05:27:48.634072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.773712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T05:27:48.634133Z","caller":"traceutil/trace.go:171","msg":"trace[1340584321] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-user; range_end:; response_count:0; response_revision:23408; }","duration":"133.875174ms","start":"2026-07-24T05:27:48.500241Z","end":"2026-07-24T05:27:48.634116Z","steps":["trace[1340584321] 'agreement among raft nodes before linearized reading' (duration: 133.746402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:48.634114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.484574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-domain-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T05:27:48.633995Z","caller":"traceutil/trace.go:171","msg":"trace[270123122] transaction","detail":"{read_only:false; response_revision:23407; number_of_response:1; }","duration":"103.905211ms","start":"2026-07-24T05:27:48.530049Z","end":"2026-07-24T05:27:48.633954Z","steps":["trace[270123122] 'process raft request' (duration: 103.774999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:48.634256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.602447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-24T05:27:48.634207Z","caller":"traceutil/trace.go:171","msg":"trace[626981496] range","detail":"{range_begin:/registry/jobs/openstack/magnum-domain-ks-user; range_end:; response_count:0; response_revision:23408; }","duration":"134.607337ms","start":"2026-07-24T05:27:48.499576Z","end":"2026-07-24T05:27:48.634183Z","steps":["trace[626981496] 'agreement among raft nodes before linearized reading' (duration: 134.443034ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:27:48.63431Z","caller":"traceutil/trace.go:171","msg":"trace[306610372] range","detail":"{range_begin:/registry/jobs/openstack/magnum-ks-endpoints; range_end:; response_count:0; response_revision:23408; }","duration":"134.710179ms","start":"2026-07-24T05:27:48.499589Z","end":"2026-07-24T05:27:48.634299Z","steps":["trace[306610372] 'agreement among raft nodes before linearized reading' (duration: 134.589656ms)"],"step_count":1} {"level":"warn","ts":"2026-07-24T05:27:48.634411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.580377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/magnum-db-sync\" ","response":"range_response_count:1 size:5082"} {"level":"info","ts":"2026-07-24T05:27:48.634466Z","caller":"traceutil/trace.go:171","msg":"trace[1925163867] range","detail":"{range_begin:/registry/jobs/openstack/magnum-db-sync; range_end:; response_count:1; response_revision:23408; }","duration":"134.663598ms","start":"2026-07-24T05:27:48.499786Z","end":"2026-07-24T05:27:48.634449Z","steps":["trace[1925163867] 'agreement among raft nodes before linearized reading' (duration: 134.269041ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:27:49.166026Z","caller":"traceutil/trace.go:171","msg":"trace[970597519] transaction","detail":"{read_only:false; response_revision:23410; number_of_response:1; }","duration":"205.850676ms","start":"2026-07-24T05:27:48.960148Z","end":"2026-07-24T05:27:49.165998Z","steps":["trace[970597519] 'process raft request' (duration: 205.270314ms)"],"step_count":1} {"level":"info","ts":"2026-07-24T05:32:48.01251Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23402} {"level":"info","ts":"2026-07-24T05:32:48.156948Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23402,"took":"140.639923ms","hash":2029940617,"current-db-size-bytes":58003456,"current-db-size":"58 MB","current-db-size-in-use-bytes":31088640,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-24T05:32:48.15707Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2029940617,"revision":23402,"compact-revision":20151}