{"level":"warn","ts":"2026-06-09T17:11:05.471731Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T17:11:05.47181Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.235:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.235:2380","--initial-cluster=instance=https://199.204.45.235:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.235:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.235:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-06-09T17:11:05.471878Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T17:11:05.471896Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-06-09T17:11:05.471916Z","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-06-09T17:11:05.472631Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"]} {"level":"info","ts":"2026-06-09T17:11:05.472741Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.235:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-06-09T17:11:05.47702Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.011578ms"} {"level":"info","ts":"2026-06-09T17:11:05.485269Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"aab6040abcf11","cluster-id":"2bae7d3d8857c059"} {"level":"info","ts":"2026-06-09T17:11:05.485405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=()"} {"level":"info","ts":"2026-06-09T17:11:05.485455Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 0"} {"level":"info","ts":"2026-06-09T17:11:05.485492Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft aab6040abcf11 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-09T17:11:05.485527Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became follower at term 1"} {"level":"info","ts":"2026-06-09T17:11:05.485604Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"warn","ts":"2026-06-09T17:11:05.491336Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-09T17:11:05.493095Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-09T17:11:05.494081Z","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-06-09T17:11:05.497209Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"aab6040abcf11","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-09T17:11:05.497314Z","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-06-09T17:11:05.497312Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"aab6040abcf11","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-09T17:11:05.497388Z","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-06-09T17:11:05.497405Z","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-06-09T17:11:05.499025Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 switched to configuration voters=(3003179657318161)"} {"level":"info","ts":"2026-06-09T17:11:05.499318Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","added-peer-id":"aab6040abcf11","added-peer-peer-urls":["https://199.204.45.235:2380"]} {"level":"info","ts":"2026-06-09T17:11:05.501724Z","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-06-09T17:11:05.502019Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-06-09T17:11:05.502066Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.235:2380"} {"level":"info","ts":"2026-06-09T17:11:05.50223Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"aab6040abcf11","initial-advertise-peer-urls":["https://199.204.45.235:2380"],"listen-peer-urls":["https://199.204.45.235:2380"],"advertise-client-urls":["https://199.204.45.235:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.235:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-09T17:11:05.502272Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-09T17:11:05.886266Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 is starting a new election at term 1"} {"level":"info","ts":"2026-06-09T17:11:05.886326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-09T17:11:05.886348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgPreVoteResp from aab6040abcf11 at term 1"} {"level":"info","ts":"2026-06-09T17:11:05.886374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became candidate at term 2"} {"level":"info","ts":"2026-06-09T17:11:05.886385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 received MsgVoteResp from aab6040abcf11 at term 2"} {"level":"info","ts":"2026-06-09T17:11:05.886402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"aab6040abcf11 became leader at term 2"} {"level":"info","ts":"2026-06-09T17:11:05.886416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: aab6040abcf11 elected leader aab6040abcf11 at term 2"} {"level":"info","ts":"2026-06-09T17:11:05.887326Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:11:05.888209Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T17:11:05.888204Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"aab6040abcf11","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.235:2379]}","request-path":"/0/members/aab6040abcf11/attributes","cluster-id":"2bae7d3d8857c059","publish-timeout":"7s"} {"level":"info","ts":"2026-06-09T17:11:05.888268Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T17:11:05.888607Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-09T17:11:05.888709Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-09T17:11:05.888763Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2bae7d3d8857c059","local-member-id":"aab6040abcf11","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:11:05.888837Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:11:05.888859Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T17:11:05.890374Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.235:2379"} {"level":"info","ts":"2026-06-09T17:11:05.891962Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-09T17:11:07.335587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.492341ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-09T17:11:07.335661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.357229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-09T17:11:07.335731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.051752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T17:11:07.335764Z","caller":"traceutil/trace.go:171","msg":"trace[235373246] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"121.483402ms","start":"2026-06-09T17:11:07.214257Z","end":"2026-06-09T17:11:07.33574Z","steps":["trace[235373246] 'range keys from in-memory index tree' (duration: 121.306828ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:07.335755Z","caller":"traceutil/trace.go:171","msg":"trace[1546413259] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"121.680815ms","start":"2026-06-09T17:11:07.214045Z","end":"2026-06-09T17:11:07.335726Z","steps":["trace[1546413259] 'range keys from in-memory index tree' (duration: 121.456221ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:07.335823Z","caller":"traceutil/trace.go:171","msg":"trace[227105331] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:3; }","duration":"122.195645ms","start":"2026-06-09T17:11:07.213602Z","end":"2026-06-09T17:11:07.335798Z","steps":["trace[227105331] 'range keys from in-memory index tree' (duration: 121.9662ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:07.33588Z","caller":"traceutil/trace.go:171","msg":"trace[843463708] linearizableReadLoop","detail":"{readStateIndex:7; appliedIndex:6; }","duration":"106.699197ms","start":"2026-06-09T17:11:07.229146Z","end":"2026-06-09T17:11:07.335845Z","steps":["trace[843463708] 'read index received' (duration: 104.666848ms)","trace[843463708] 'applied index is now lower than readState.Index' (duration: 2.030809ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:11:07.335888Z","caller":"traceutil/trace.go:171","msg":"trace[1072271566] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"106.759288ms","start":"2026-06-09T17:11:07.229098Z","end":"2026-06-09T17:11:07.335857Z","steps":["trace[1072271566] 'process raft request' (duration: 104.631138ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:11:07.335774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.872248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-09T17:11:07.336256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.054924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T17:11:07.336315Z","caller":"traceutil/trace.go:171","msg":"trace[916215195] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:0; response_revision:3; }","duration":"109.45671ms","start":"2026-06-09T17:11:07.226815Z","end":"2026-06-09T17:11:07.336272Z","steps":["trace[916215195] 'range keys from in-memory index tree' (duration: 108.799347ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:07.336432Z","caller":"traceutil/trace.go:171","msg":"trace[719317055] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:4; }","duration":"107.282448ms","start":"2026-06-09T17:11:07.229116Z","end":"2026-06-09T17:11:07.336398Z","steps":["trace[719317055] 'agreement among raft nodes before linearized reading' (duration: 106.85533ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:07.342381Z","caller":"traceutil/trace.go:171","msg":"trace[456542042] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"112.874086ms","start":"2026-06-09T17:11:07.229493Z","end":"2026-06-09T17:11:07.342367Z","steps":["trace[456542042] 'process raft request' (duration: 112.665252ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:07.34239Z","caller":"traceutil/trace.go:171","msg":"trace[689205554] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"112.870407ms","start":"2026-06-09T17:11:07.229507Z","end":"2026-06-09T17:11:07.342378Z","steps":["trace[689205554] 'process raft request' (duration: 112.728614ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:07.342483Z","caller":"traceutil/trace.go:171","msg":"trace[1825053824] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"112.540219ms","start":"2026-06-09T17:11:07.22993Z","end":"2026-06-09T17:11:07.34247Z","steps":["trace[1825053824] 'process raft request' (duration: 112.426687ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:07.342527Z","caller":"traceutil/trace.go:171","msg":"trace[2055998774] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"112.55955ms","start":"2026-06-09T17:11:07.229939Z","end":"2026-06-09T17:11:07.342499Z","steps":["trace[2055998774] 'process raft request' (duration: 112.443608ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:07.34258Z","caller":"traceutil/trace.go:171","msg":"trace[484121074] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"100.428176ms","start":"2026-06-09T17:11:07.242144Z","end":"2026-06-09T17:11:07.342572Z","steps":["trace[484121074] 'process raft request' (duration: 100.326344ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:07.34259Z","caller":"traceutil/trace.go:171","msg":"trace[1270334821] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"112.804144ms","start":"2026-06-09T17:11:07.229717Z","end":"2026-06-09T17:11:07.342521Z","steps":["trace[1270334821] 'process raft request' (duration: 112.615331ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:11:07.342735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.927107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-06-09T17:11:07.342735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.657102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T17:11:07.342738Z","caller":"traceutil/trace.go:171","msg":"trace[1393419208] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"105.043055ms","start":"2026-06-09T17:11:07.237618Z","end":"2026-06-09T17:11:07.342661Z","steps":["trace[1393419208] 'process raft request' (duration: 104.806ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:07.342806Z","caller":"traceutil/trace.go:171","msg":"trace[1550081631] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"113.033328ms","start":"2026-06-09T17:11:07.229756Z","end":"2026-06-09T17:11:07.342789Z","steps":["trace[1550081631] 'agreement among raft nodes before linearized reading' (duration: 112.919116ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:07.342829Z","caller":"traceutil/trace.go:171","msg":"trace[1545925111] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"112.779684ms","start":"2026-06-09T17:11:07.230028Z","end":"2026-06-09T17:11:07.342807Z","steps":["trace[1545925111] 'agreement among raft nodes before linearized reading' (duration: 112.57125ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:19.909208Z","caller":"traceutil/trace.go:171","msg":"trace[2097963687] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"211.919815ms","start":"2026-06-09T17:11:19.697229Z","end":"2026-06-09T17:11:19.909149Z","steps":["trace[2097963687] 'process raft request' (duration: 211.672321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:11:49.81476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.165016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T17:11:49.814842Z","caller":"traceutil/trace.go:171","msg":"trace[1209781394] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"171.281949ms","start":"2026-06-09T17:11:49.643541Z","end":"2026-06-09T17:11:49.814823Z","steps":["trace[1209781394] 'range keys from in-memory index tree' (duration: 170.999374ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:11:50.32575Z","caller":"traceutil/trace.go:171","msg":"trace[304441481] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"450.938562ms","start":"2026-06-09T17:11:49.87477Z","end":"2026-06-09T17:11:50.325709Z","steps":["trace[304441481] 'process raft request' (duration: 426.812881ms)","trace[304441481] 'compare' (duration: 22.577673ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:11:50.326496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:11:49.874751Z","time spent":"451.074365ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:12:04.548093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.072585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:12:04.5482Z","caller":"traceutil/trace.go:171","msg":"trace[422999173] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:754; }","duration":"166.223718ms","start":"2026-06-09T17:12:04.381959Z","end":"2026-06-09T17:12:04.548183Z","steps":["trace[422999173] 'range keys from in-memory index tree' (duration: 166.002924ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:13:20.429813Z","caller":"traceutil/trace.go:171","msg":"trace[867826713] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"313.857243ms","start":"2026-06-09T17:13:20.115937Z","end":"2026-06-09T17:13:20.429794Z","steps":["trace[867826713] 'process raft request' (duration: 313.745291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:13:20.429978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:13:20.115908Z","time spent":"313.969445ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:13:20.515216Z","caller":"traceutil/trace.go:171","msg":"trace[1770980748] linearizableReadLoop","detail":"{readStateIndex:1886; appliedIndex:1884; }","duration":"297.822686ms","start":"2026-06-09T17:13:20.217364Z","end":"2026-06-09T17:13:20.515187Z","steps":["trace[1770980748] 'read index received' (duration: 212.341181ms)","trace[1770980748] 'applied index is now lower than readState.Index' (duration: 85.480345ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:13:20.515369Z","caller":"traceutil/trace.go:171","msg":"trace[817685943] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"303.501855ms","start":"2026-06-09T17:13:20.211834Z","end":"2026-06-09T17:13:20.515336Z","steps":["trace[817685943] 'process raft request' (duration: 232.074369ms)","trace[817685943] 'compare' (duration: 71.155381ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:13:20.5156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:13:20.211807Z","time spent":"303.68873ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:13:20.515616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.3517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:13:20.515702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.307646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"info","ts":"2026-06-09T17:13:20.515804Z","caller":"traceutil/trace.go:171","msg":"trace[1600348009] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1817; }","duration":"298.454879ms","start":"2026-06-09T17:13:20.217334Z","end":"2026-06-09T17:13:20.515789Z","steps":["trace[1600348009] 'agreement among raft nodes before linearized reading' (duration: 298.050541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:13:20.515699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.405195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T17:13:20.516106Z","caller":"traceutil/trace.go:171","msg":"trace[1080508252] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1817; }","duration":"141.854263ms","start":"2026-06-09T17:13:20.374211Z","end":"2026-06-09T17:13:20.516065Z","steps":["trace[1080508252] 'agreement among raft nodes before linearized reading' (duration: 141.274813ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:13:20.515729Z","caller":"traceutil/trace.go:171","msg":"trace[880336794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1817; }","duration":"134.483292ms","start":"2026-06-09T17:13:20.381213Z","end":"2026-06-09T17:13:20.515696Z","steps":["trace[880336794] 'agreement among raft nodes before linearized reading' (duration: 134.307639ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:13:20.815909Z","caller":"traceutil/trace.go:171","msg":"trace[1537394895] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"143.487395ms","start":"2026-06-09T17:13:20.672384Z","end":"2026-06-09T17:13:20.815872Z","steps":["trace[1537394895] 'process raft request' (duration: 109.219879ms)","trace[1537394895] 'compare' (duration: 34.043201ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:13:33.645402Z","caller":"traceutil/trace.go:171","msg":"trace[692684082] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"152.949965ms","start":"2026-06-09T17:13:33.492401Z","end":"2026-06-09T17:13:33.645351Z","steps":["trace[692684082] 'process raft request' (duration: 133.588365ms)","trace[692684082] 'compare' (duration: 19.179906ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:14:04.338652Z","caller":"traceutil/trace.go:171","msg":"trace[453886940] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"178.732717ms","start":"2026-06-09T17:14:04.15989Z","end":"2026-06-09T17:14:04.338622Z","steps":["trace[453886940] 'process raft request' (duration: 90.055847ms)","trace[453886940] 'compare' (duration: 88.330134ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:14:04.338781Z","caller":"traceutil/trace.go:171","msg":"trace[1912063297] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"150.517133ms","start":"2026-06-09T17:14:04.188249Z","end":"2026-06-09T17:14:04.338766Z","steps":["trace[1912063297] 'process raft request' (duration: 150.36297ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:14:04.338814Z","caller":"traceutil/trace.go:171","msg":"trace[328657205] linearizableReadLoop","detail":"{readStateIndex:2189; appliedIndex:2188; }","duration":"160.941423ms","start":"2026-06-09T17:14:04.177832Z","end":"2026-06-09T17:14:04.338773Z","steps":["trace[328657205] 'read index received' (duration: 72.220392ms)","trace[328657205] 'applied index is now lower than readState.Index' (duration: 88.717971ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:14:04.402912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.029959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:14:04.403064Z","caller":"traceutil/trace.go:171","msg":"trace[2031894135] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:2112; }","duration":"225.271793ms","start":"2026-06-09T17:14:04.177753Z","end":"2026-06-09T17:14:04.403025Z","steps":["trace[2031894135] 'agreement among raft nodes before linearized reading' (duration: 161.198508ms)","trace[2031894135] 'count revisions from in-memory index tree' (duration: 63.842481ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:14:04.402935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.39393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:14:04.403297Z","caller":"traceutil/trace.go:171","msg":"trace[321464633] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2112; }","duration":"122.778108ms","start":"2026-06-09T17:14:04.280443Z","end":"2026-06-09T17:14:04.403221Z","steps":["trace[321464633] 'agreement among raft nodes before linearized reading' (duration: 122.35881ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:14:04.9443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.31987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"warn","ts":"2026-06-09T17:14:04.944363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.006021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T17:14:04.944395Z","caller":"traceutil/trace.go:171","msg":"trace[247043443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2114; }","duration":"166.081572ms","start":"2026-06-09T17:14:04.778303Z","end":"2026-06-09T17:14:04.944385Z","steps":["trace[247043443] 'range keys from in-memory index tree' (duration: 165.888298ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:14:04.9444Z","caller":"traceutil/trace.go:171","msg":"trace[652349022] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2114; }","duration":"161.438192ms","start":"2026-06-09T17:14:04.782929Z","end":"2026-06-09T17:14:04.944367Z","steps":["trace[652349022] 'range keys from in-memory index tree' (duration: 161.117586ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:15:34.506741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.547063ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:26"} {"level":"info","ts":"2026-06-09T17:18:00.160962Z","caller":"traceutil/trace.go:171","msg":"trace[666014926] transaction","detail":"{read_only:false; response_revision:3758; number_of_response:1; }","duration":"110.559516ms","start":"2026-06-09T17:18:00.050299Z","end":"2026-06-09T17:18:00.160858Z","steps":["trace[666014926] 'process raft request' (duration: 110.360743ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:18:00.164938Z","caller":"traceutil/trace.go:171","msg":"trace[795981500] transaction","detail":"{read_only:false; response_revision:3759; number_of_response:1; }","duration":"112.850111ms","start":"2026-06-09T17:18:00.05207Z","end":"2026-06-09T17:18:00.164873Z","steps":["trace[795981500] 'process raft request' (duration: 112.699598ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:19:21.029895Z","caller":"traceutil/trace.go:171","msg":"trace[1611763306] transaction","detail":"{read_only:false; response_revision:4766; number_of_response:1; }","duration":"234.406956ms","start":"2026-06-09T17:19:20.795457Z","end":"2026-06-09T17:19:21.029864Z","steps":["trace[1611763306] 'process raft request' (duration: 234.188932ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:19:25.886064Z","caller":"traceutil/trace.go:171","msg":"trace[470727884] linearizableReadLoop","detail":"{readStateIndex:5110; appliedIndex:5109; }","duration":"154.450607ms","start":"2026-06-09T17:19:25.731586Z","end":"2026-06-09T17:19:25.886036Z","steps":["trace[470727884] 'read index received' (duration: 75.266381ms)","trace[470727884] 'applied index is now lower than readState.Index' (duration: 79.181376ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:19:25.886273Z","caller":"traceutil/trace.go:171","msg":"trace[2071839168] transaction","detail":"{read_only:false; response_revision:4931; number_of_response:1; }","duration":"126.581723ms","start":"2026-06-09T17:19:25.759679Z","end":"2026-06-09T17:19:25.886261Z","steps":["trace[2071839168] 'process raft request' (duration: 126.326237ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:19:25.886331Z","caller":"traceutil/trace.go:171","msg":"trace[326010715] transaction","detail":"{read_only:false; response_revision:4929; number_of_response:1; }","duration":"202.291282ms","start":"2026-06-09T17:19:25.6839Z","end":"2026-06-09T17:19:25.886191Z","steps":["trace[326010715] 'process raft request' (duration: 122.836751ms)","trace[326010715] 'compare' (duration: 79.008292ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:19:25.886492Z","caller":"traceutil/trace.go:171","msg":"trace[975875373] transaction","detail":"{read_only:false; response_revision:4930; number_of_response:1; }","duration":"126.863269ms","start":"2026-06-09T17:19:25.759599Z","end":"2026-06-09T17:19:25.886463Z","steps":["trace[975875373] 'process raft request' (duration: 126.341228ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:19:25.886699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.106169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:23 size:724266"} {"level":"info","ts":"2026-06-09T17:19:25.88675Z","caller":"traceutil/trace.go:171","msg":"trace[1294558257] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:23; response_revision:4930; }","duration":"155.208121ms","start":"2026-06-09T17:19:25.731528Z","end":"2026-06-09T17:19:25.886736Z","steps":["trace[1294558257] 'agreement among raft nodes before linearized reading' (duration: 154.62801ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:19:38.410002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.4515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:19:38.410112Z","caller":"traceutil/trace.go:171","msg":"trace[451165222] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:5214; }","duration":"184.604843ms","start":"2026-06-09T17:19:38.225485Z","end":"2026-06-09T17:19:38.41009Z","steps":["trace[451165222] 'count revisions from in-memory index tree' (duration: 184.173805ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:19:46.662516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.403115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:19:46.662592Z","caller":"traceutil/trace.go:171","msg":"trace[2016623186] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5378; }","duration":"281.527607ms","start":"2026-06-09T17:19:46.381047Z","end":"2026-06-09T17:19:46.662575Z","steps":["trace[2016623186] 'range keys from in-memory index tree' (duration: 281.311283ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:19:56.300161Z","caller":"traceutil/trace.go:171","msg":"trace[539903787] transaction","detail":"{read_only:false; response_revision:5539; number_of_response:1; }","duration":"110.689707ms","start":"2026-06-09T17:19:56.189442Z","end":"2026-06-09T17:19:56.300132Z","steps":["trace[539903787] 'process raft request' (duration: 95.203762ms)","trace[539903787] 'compare' (duration: 15.275211ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:19:56.300455Z","caller":"traceutil/trace.go:171","msg":"trace[1593045476] transaction","detail":"{read_only:false; response_revision:5540; number_of_response:1; }","duration":"110.798299ms","start":"2026-06-09T17:19:56.189551Z","end":"2026-06-09T17:19:56.300349Z","steps":["trace[1593045476] 'process raft request' (duration: 110.506354ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:19:56.300914Z","caller":"traceutil/trace.go:171","msg":"trace[707243460] transaction","detail":"{read_only:false; response_revision:5542; number_of_response:1; }","duration":"100.081205ms","start":"2026-06-09T17:19:56.200793Z","end":"2026-06-09T17:19:56.300874Z","steps":["trace[707243460] 'process raft request' (duration: 99.754139ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:19:56.300929Z","caller":"traceutil/trace.go:171","msg":"trace[1677960102] transaction","detail":"{read_only:false; response_revision:5541; number_of_response:1; }","duration":"110.945394ms","start":"2026-06-09T17:19:56.189949Z","end":"2026-06-09T17:19:56.300894Z","steps":["trace[1677960102] 'process raft request' (duration: 110.325021ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:21:05.91748Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2964} {"level":"info","ts":"2026-06-09T17:21:05.999768Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2964,"took":"80.656464ms","hash":271520235,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":34496512,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-09T17:21:05.999854Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":271520235,"revision":2964,"compact-revision":-1} {"level":"info","ts":"2026-06-09T17:25:34.953613Z","caller":"traceutil/trace.go:171","msg":"trace[1997413194] transaction","detail":"{read_only:false; response_revision:8130; number_of_response:1; }","duration":"163.444936ms","start":"2026-06-09T17:25:34.790144Z","end":"2026-06-09T17:25:34.953589Z","steps":["trace[1997413194] 'process raft request' (duration: 163.323384ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:25:45.839961Z","caller":"traceutil/trace.go:171","msg":"trace[981955469] linearizableReadLoop","detail":"{readStateIndex:8469; appliedIndex:8468; }","duration":"144.695059ms","start":"2026-06-09T17:25:45.695245Z","end":"2026-06-09T17:25:45.839941Z","steps":["trace[981955469] 'read index received' (duration: 133.564558ms)","trace[981955469] 'applied index is now lower than readState.Index' (duration: 11.129721ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:25:45.840076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.822401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:25:45.8401Z","caller":"traceutil/trace.go:171","msg":"trace[951035629] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:8186; }","duration":"144.899213ms","start":"2026-06-09T17:25:45.695193Z","end":"2026-06-09T17:25:45.840092Z","steps":["trace[951035629] 'agreement among raft nodes before linearized reading' (duration: 144.823262ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:25:45.840366Z","caller":"traceutil/trace.go:171","msg":"trace[155880346] transaction","detail":"{read_only:false; response_revision:8186; number_of_response:1; }","duration":"191.00287ms","start":"2026-06-09T17:25:45.649349Z","end":"2026-06-09T17:25:45.840352Z","steps":["trace[155880346] 'process raft request' (duration: 179.44431ms)","trace[155880346] 'compare' (duration: 10.797456ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:25:46.620981Z","caller":"traceutil/trace.go:171","msg":"trace[461174395] transaction","detail":"{read_only:false; response_revision:8188; number_of_response:1; }","duration":"130.974259ms","start":"2026-06-09T17:25:46.489983Z","end":"2026-06-09T17:25:46.620957Z","steps":["trace[461174395] 'process raft request' (duration: 130.807546ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:25:46.621074Z","caller":"traceutil/trace.go:171","msg":"trace[257554015] linearizableReadLoop","detail":"{readStateIndex:8471; appliedIndex:8470; }","duration":"126.968832ms","start":"2026-06-09T17:25:46.494079Z","end":"2026-06-09T17:25:46.621048Z","steps":["trace[257554015] 'read index received' (duration: 126.745078ms)","trace[257554015] 'applied index is now lower than readState.Index' (duration: 221.914µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:25:46.621232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.311509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:25:46.621293Z","caller":"traceutil/trace.go:171","msg":"trace[1350166973] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8188; }","duration":"117.37295ms","start":"2026-06-09T17:25:46.503891Z","end":"2026-06-09T17:25:46.621264Z","steps":["trace[1350166973] 'agreement among raft nodes before linearized reading' (duration: 117.284928ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:25:46.621378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.232598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-09T17:25:46.621457Z","caller":"traceutil/trace.go:171","msg":"trace[1061783282] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8188; }","duration":"127.39597ms","start":"2026-06-09T17:25:46.494048Z","end":"2026-06-09T17:25:46.621444Z","steps":["trace[1061783282] 'agreement among raft nodes before linearized reading' (duration: 127.108585ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:25:46.621621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.368387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:25:46.621664Z","caller":"traceutil/trace.go:171","msg":"trace[793255140] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8188; }","duration":"100.471978ms","start":"2026-06-09T17:25:46.521182Z","end":"2026-06-09T17:25:46.621654Z","steps":["trace[793255140] 'agreement among raft nodes before linearized reading' (duration: 100.413927ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:25:46.621879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.718814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystemmirrors/\" range_end:\"/registry/ceph.rook.io/cephfilesystemmirrors0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:25:46.62194Z","caller":"traceutil/trace.go:171","msg":"trace[1236211591] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystemmirrors/; range_end:/registry/ceph.rook.io/cephfilesystemmirrors0; response_count:0; response_revision:8188; }","duration":"100.816805ms","start":"2026-06-09T17:25:46.521109Z","end":"2026-06-09T17:25:46.621926Z","steps":["trace[1236211591] 'agreement among raft nodes before linearized reading' (duration: 100.724974ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:25:46.622031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.56741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-09T17:25:46.622119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.370411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:25:46.622125Z","caller":"traceutil/trace.go:171","msg":"trace[1184861043] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8188; }","duration":"101.703774ms","start":"2026-06-09T17:25:46.520404Z","end":"2026-06-09T17:25:46.622108Z","steps":["trace[1184861043] 'agreement among raft nodes before linearized reading' (duration: 101.576931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:25:46.6219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.948882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:25:46.622203Z","caller":"traceutil/trace.go:171","msg":"trace[68523314] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8188; }","duration":"118.269878ms","start":"2026-06-09T17:25:46.503921Z","end":"2026-06-09T17:25:46.622191Z","steps":["trace[68523314] 'agreement among raft nodes before linearized reading' (duration: 117.940821ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:25:46.622162Z","caller":"traceutil/trace.go:171","msg":"trace[1537626085] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:8188; }","duration":"117.434392ms","start":"2026-06-09T17:25:46.504717Z","end":"2026-06-09T17:25:46.622152Z","steps":["trace[1537626085] 'agreement among raft nodes before linearized reading' (duration: 117.371761ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:26:05.926273Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6126} {"level":"info","ts":"2026-06-09T17:26:06.033295Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6126,"took":"105.020736ms","hash":937391791,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":23457792,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-09T17:26:06.033357Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":937391791,"revision":6126,"compact-revision":2964} {"level":"info","ts":"2026-06-09T17:26:27.95791Z","caller":"traceutil/trace.go:171","msg":"trace[1380087815] transaction","detail":"{read_only:false; response_revision:8509; number_of_response:1; }","duration":"169.743605ms","start":"2026-06-09T17:26:27.788138Z","end":"2026-06-09T17:26:27.957881Z","steps":["trace[1380087815] 'process raft request' (duration: 169.685104ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:26:27.958027Z","caller":"traceutil/trace.go:171","msg":"trace[1147590761] transaction","detail":"{read_only:false; response_revision:8508; number_of_response:1; }","duration":"174.039237ms","start":"2026-06-09T17:26:27.783965Z","end":"2026-06-09T17:26:27.958005Z","steps":["trace[1147590761] 'process raft request' (duration: 116.690868ms)","trace[1147590761] 'compare' (duration: 57.025943ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:26:29.016001Z","caller":"traceutil/trace.go:171","msg":"trace[1893599928] transaction","detail":"{read_only:false; response_revision:8515; number_of_response:1; }","duration":"132.12641ms","start":"2026-06-09T17:26:28.883847Z","end":"2026-06-09T17:26:29.015973Z","steps":["trace[1893599928] 'process raft request' (duration: 131.872205ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:26:29.016233Z","caller":"traceutil/trace.go:171","msg":"trace[564249568] linearizableReadLoop","detail":"{readStateIndex:8813; appliedIndex:8813; }","duration":"127.264339ms","start":"2026-06-09T17:26:28.888725Z","end":"2026-06-09T17:26:29.015989Z","steps":["trace[564249568] 'read index received' (duration: 127.253639ms)","trace[564249568] 'applied index is now lower than readState.Index' (duration: 8.86µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:26:29.016281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.521933ms","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-06-09T17:26:29.016392Z","caller":"traceutil/trace.go:171","msg":"trace[1249536884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8515; }","duration":"127.699657ms","start":"2026-06-09T17:26:28.88867Z","end":"2026-06-09T17:26:29.01637Z","steps":["trace[1249536884] 'agreement among raft nodes before linearized reading' (duration: 127.398721ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:26:29.017351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.37392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-06-09T17:26:29.017395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.109708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-06-09T17:26:29.017415Z","caller":"traceutil/trace.go:171","msg":"trace[737617819] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:8515; }","duration":"119.473821ms","start":"2026-06-09T17:26:28.897925Z","end":"2026-06-09T17:26:29.017399Z","steps":["trace[737617819] 'agreement among raft nodes before linearized reading' (duration: 119.112564ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:26:29.01743Z","caller":"traceutil/trace.go:171","msg":"trace[11084235] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8515; }","duration":"116.217629ms","start":"2026-06-09T17:26:28.901202Z","end":"2026-06-09T17:26:29.017419Z","steps":["trace[11084235] 'agreement among raft nodes before linearized reading' (duration: 116.079777ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:26:29.017609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.660978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-06-09T17:26:29.017633Z","caller":"traceutil/trace.go:171","msg":"trace[2109328293] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8515; }","duration":"117.700128ms","start":"2026-06-09T17:26:28.899924Z","end":"2026-06-09T17:26:29.017624Z","steps":["trace[2109328293] 'agreement among raft nodes before linearized reading' (duration: 117.639977ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:27:10.918836Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002405.wal"} {"level":"info","ts":"2026-06-09T17:27:27.042004Z","caller":"traceutil/trace.go:171","msg":"trace[562468391] transaction","detail":"{read_only:false; response_revision:9137; number_of_response:1; }","duration":"166.027368ms","start":"2026-06-09T17:27:26.875951Z","end":"2026-06-09T17:27:27.041979Z","steps":["trace[562468391] 'process raft request' (duration: 165.889656ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:28:40.226062Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T17:28:40.231834Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-09T17:28:40.23192Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-09T17:31:05.932673Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8300} {"level":"info","ts":"2026-06-09T17:31:06.016175Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8300,"took":"81.297469ms","hash":3392846885,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":26746880,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-06-09T17:31:06.016236Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3392846885,"revision":8300,"compact-revision":6126} {"level":"info","ts":"2026-06-09T17:31:08.965462Z","caller":"traceutil/trace.go:171","msg":"trace[1659609379] linearizableReadLoop","detail":"{readStateIndex:11378; appliedIndex:11376; }","duration":"121.911793ms","start":"2026-06-09T17:31:08.843519Z","end":"2026-06-09T17:31:08.965431Z","steps":["trace[1659609379] 'read index received' (duration: 19.716846ms)","trace[1659609379] 'applied index is now lower than readState.Index' (duration: 102.193727ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:31:08.965618Z","caller":"traceutil/trace.go:171","msg":"trace[342500821] transaction","detail":"{read_only:false; response_revision:11009; number_of_response:1; }","duration":"141.268722ms","start":"2026-06-09T17:31:08.824319Z","end":"2026-06-09T17:31:08.965587Z","steps":["trace[342500821] 'process raft request' (duration: 109.791253ms)","trace[342500821] 'compare' (duration: 31.147663ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:31:08.965893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.338031ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:15 size:143743"} {"level":"info","ts":"2026-06-09T17:31:08.965946Z","caller":"traceutil/trace.go:171","msg":"trace[176832176] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:15; response_revision:11009; }","duration":"122.460534ms","start":"2026-06-09T17:31:08.843468Z","end":"2026-06-09T17:31:08.965929Z","steps":["trace[176832176] 'agreement among raft nodes before linearized reading' (duration: 122.122097ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:36:05.94181Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10994} {"level":"info","ts":"2026-06-09T17:36:06.02966Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10994,"took":"85.960765ms","hash":2900737911,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":27832320,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-09T17:36:06.029721Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2900737911,"revision":10994,"compact-revision":8300} {"level":"info","ts":"2026-06-09T17:37:17.539901Z","caller":"traceutil/trace.go:171","msg":"trace[1481931779] transaction","detail":"{read_only:false; response_revision:13499; number_of_response:1; }","duration":"201.941739ms","start":"2026-06-09T17:37:17.337937Z","end":"2026-06-09T17:37:17.539878Z","steps":["trace[1481931779] 'process raft request' (duration: 201.819907ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:37:17.540173Z","caller":"traceutil/trace.go:171","msg":"trace[219384350] linearizableReadLoop","detail":"{readStateIndex:13954; appliedIndex:13954; }","duration":"150.450021ms","start":"2026-06-09T17:37:17.389666Z","end":"2026-06-09T17:37:17.540116Z","steps":["trace[219384350] 'read index received' (duration: 150.42206ms)","trace[219384350] 'applied index is now lower than readState.Index' (duration: 23.371µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:37:17.544458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.761693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:37:17.544541Z","caller":"traceutil/trace.go:171","msg":"trace[736198802] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13499; }","duration":"154.897846ms","start":"2026-06-09T17:37:17.389622Z","end":"2026-06-09T17:37:17.54452Z","steps":["trace[736198802] 'agreement among raft nodes before linearized reading' (duration: 150.527262ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:37:17.546032Z","caller":"traceutil/trace.go:171","msg":"trace[851401777] transaction","detail":"{read_only:false; response_revision:13500; number_of_response:1; }","duration":"106.289491ms","start":"2026-06-09T17:37:17.439732Z","end":"2026-06-09T17:37:17.546021Z","steps":["trace[851401777] 'process raft request' (duration: 106.173819ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:37:33.87872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.407997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5683"} {"level":"info","ts":"2026-06-09T17:37:33.878788Z","caller":"traceutil/trace.go:171","msg":"trace[874800079] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:13601; }","duration":"468.495088ms","start":"2026-06-09T17:37:33.410276Z","end":"2026-06-09T17:37:33.878771Z","steps":["trace[874800079] 'range keys from in-memory index tree' (duration: 468.297654ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:37:33.878819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:37:33.41027Z","time spent":"468.542569ms","remote":"127.0.0.1:34378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5704,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-09T17:37:33.879384Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:37:33.433134Z","time spent":"446.241085ms","remote":"127.0.0.1:38234","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-06-09T17:37:33.879838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.187454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-ck4b2\" ","response":"range_response_count:1 size:12599"} {"level":"info","ts":"2026-06-09T17:37:33.880013Z","caller":"traceutil/trace.go:171","msg":"trace[611403756] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-ck4b2; range_end:; response_count:1; response_revision:13601; }","duration":"416.373308ms","start":"2026-06-09T17:37:33.463618Z","end":"2026-06-09T17:37:33.879991Z","steps":["trace[611403756] 'range keys from in-memory index tree' (duration: 415.96614ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:37:33.880068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:37:33.46354Z","time spent":"416.51682ms","remote":"127.0.0.1:34190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":12620,"request content":"key:\"/registry/pods/openstack/openvswitch-ck4b2\" "} {"level":"info","ts":"2026-06-09T17:37:33.915826Z","caller":"traceutil/trace.go:171","msg":"trace[113136630] transaction","detail":"{read_only:false; response_revision:13602; number_of_response:1; }","duration":"312.191366ms","start":"2026-06-09T17:37:33.603591Z","end":"2026-06-09T17:37:33.915782Z","steps":["trace[113136630] 'process raft request' (duration: 311.990802ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:37:33.916123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:37:33.603566Z","time spent":"312.34681ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:37:33.916201Z","caller":"traceutil/trace.go:171","msg":"trace[1440631096] linearizableReadLoop","detail":"{readStateIndex:14061; appliedIndex:14061; }","duration":"308.872273ms","start":"2026-06-09T17:37:33.607289Z","end":"2026-06-09T17:37:33.916161Z","steps":["trace[1440631096] 'read index received' (duration: 308.855813ms)","trace[1440631096] 'applied index is now lower than readState.Index' (duration: 13.65µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:37:33.916541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.238031ms","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-06-09T17:37:33.916582Z","caller":"traceutil/trace.go:171","msg":"trace[651021038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:13602; }","duration":"309.324482ms","start":"2026-06-09T17:37:33.607245Z","end":"2026-06-09T17:37:33.91657Z","steps":["trace[651021038] 'agreement among raft nodes before linearized reading' (duration: 309.099198ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:37:33.916615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:37:33.607234Z","time spent":"309.373253ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-09T17:37:34.0775Z","caller":"traceutil/trace.go:171","msg":"trace[1323566913] linearizableReadLoop","detail":"{readStateIndex:14062; appliedIndex:14061; }","duration":"161.162594ms","start":"2026-06-09T17:37:33.916313Z","end":"2026-06-09T17:37:34.077476Z","steps":["trace[1323566913] 'read index received' (duration: 161.083603ms)","trace[1323566913] 'applied index is now lower than readState.Index' (duration: 77.921µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:37:34.077625Z","caller":"traceutil/trace.go:171","msg":"trace[1940629724] transaction","detail":"{read_only:false; response_revision:13603; number_of_response:1; }","duration":"185.563718ms","start":"2026-06-09T17:37:33.892019Z","end":"2026-06-09T17:37:34.077583Z","steps":["trace[1940629724] 'process raft request' (duration: 185.304583ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:37:34.07789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.608102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-09T17:37:34.077982Z","caller":"traceutil/trace.go:171","msg":"trace[349885076] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:13603; }","duration":"415.756065ms","start":"2026-06-09T17:37:33.662204Z","end":"2026-06-09T17:37:34.077961Z","steps":["trace[349885076] 'agreement among raft nodes before linearized reading' (duration: 415.47587ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:37:34.07803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:37:33.662183Z","time spent":"415.837786ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T17:37:34.078335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.978195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:929839"} {"level":"info","ts":"2026-06-09T17:37:34.078408Z","caller":"traceutil/trace.go:171","msg":"trace[325817866] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:13603; }","duration":"410.105808ms","start":"2026-06-09T17:37:33.668285Z","end":"2026-06-09T17:37:34.078391Z","steps":["trace[325817866] 'agreement among raft nodes before linearized reading' (duration: 409.354453ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:37:34.078442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:37:33.668264Z","time spent":"410.1699ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":86,"response size":929860,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"info","ts":"2026-06-09T17:37:34.088021Z","caller":"traceutil/trace.go:171","msg":"trace[767985413] transaction","detail":"{read_only:false; response_revision:13604; number_of_response:1; }","duration":"166.726609ms","start":"2026-06-09T17:37:33.921274Z","end":"2026-06-09T17:37:34.088001Z","steps":["trace[767985413] 'process raft request' (duration: 166.595317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:37:34.091581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.52554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:37:34.091646Z","caller":"traceutil/trace.go:171","msg":"trace[658770226] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:13604; }","duration":"142.635872ms","start":"2026-06-09T17:37:33.948994Z","end":"2026-06-09T17:37:34.09163Z","steps":["trace[658770226] 'agreement among raft nodes before linearized reading' (duration: 142.433558ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:39:00.54653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.915934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:39:00.546616Z","caller":"traceutil/trace.go:171","msg":"trace[844122635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14730; }","duration":"167.039466ms","start":"2026-06-09T17:39:00.379559Z","end":"2026-06-09T17:39:00.546599Z","steps":["trace[844122635] 'range keys from in-memory index tree' (duration: 166.847491ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:39:06.427256Z","caller":"traceutil/trace.go:171","msg":"trace[1356529247] transaction","detail":"{read_only:false; response_revision:14760; number_of_response:1; }","duration":"112.794535ms","start":"2026-06-09T17:39:06.314438Z","end":"2026-06-09T17:39:06.427233Z","steps":["trace[1356529247] 'process raft request' (duration: 112.616051ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:41:05.948491Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13023} {"level":"info","ts":"2026-06-09T17:41:06.024718Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13023,"took":"73.507001ms","hash":2706591218,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":32018432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-09T17:41:06.024798Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2706591218,"revision":13023,"compact-revision":10994} {"level":"info","ts":"2026-06-09T17:42:59.065624Z","caller":"traceutil/trace.go:171","msg":"trace[946153527] linearizableReadLoop","detail":"{readStateIndex:17134; appliedIndex:17133; }","duration":"103.138396ms","start":"2026-06-09T17:42:58.962458Z","end":"2026-06-09T17:42:59.065596Z","steps":["trace[946153527] 'read index received' (duration: 102.939942ms)","trace[946153527] 'applied index is now lower than readState.Index' (duration: 197.094µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:59.065705Z","caller":"traceutil/trace.go:171","msg":"trace[1169780327] transaction","detail":"{read_only:false; response_revision:16584; number_of_response:1; }","duration":"178.95017ms","start":"2026-06-09T17:42:58.886725Z","end":"2026-06-09T17:42:59.065675Z","steps":["trace[1169780327] 'process raft request' (duration: 178.680215ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:59.065878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.39694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.235\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-06-09T17:42:59.065966Z","caller":"traceutil/trace.go:171","msg":"trace[966916308] range","detail":"{range_begin:/registry/masterleases/199.204.45.235; range_end:; response_count:1; response_revision:16584; }","duration":"103.525172ms","start":"2026-06-09T17:42:58.962423Z","end":"2026-06-09T17:42:59.065949Z","steps":["trace[966916308] 'agreement among raft nodes before linearized reading' (duration: 103.290598ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:59.293679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.21496ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:59.293896Z","caller":"traceutil/trace.go:171","msg":"trace[1075464435] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:16584; }","duration":"183.451725ms","start":"2026-06-09T17:42:59.110411Z","end":"2026-06-09T17:42:59.293863Z","steps":["trace[1075464435] 'range keys from in-memory index tree' (duration: 183.17997ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:59.293964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.987538ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-06-09T17:42:59.294108Z","caller":"traceutil/trace.go:171","msg":"trace[1742447010] linearizableReadLoop","detail":"{readStateIndex:17135; appliedIndex:17134; }","duration":"175.31192ms","start":"2026-06-09T17:42:59.118754Z","end":"2026-06-09T17:42:59.294066Z","steps":["trace[1742447010] 'read index received' (duration: 56.121µs)","trace[1742447010] 'applied index is now lower than readState.Index' (duration: 175.253739ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:59.294521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.757289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-6qr2r\" ","response":"range_response_count:1 size:22126"} {"level":"info","ts":"2026-06-09T17:42:59.294607Z","caller":"traceutil/trace.go:171","msg":"trace[337948464] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-6qr2r; range_end:; response_count:1; response_revision:16584; }","duration":"175.887551ms","start":"2026-06-09T17:42:59.1187Z","end":"2026-06-09T17:42:59.294587Z","steps":["trace[337948464] 'agreement among raft nodes before linearized reading' (duration: 175.501884ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:59.31529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.247821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-vzpnz\" ","response":"range_response_count:1 size:20866"} {"level":"info","ts":"2026-06-09T17:42:59.315378Z","caller":"traceutil/trace.go:171","msg":"trace[1304942276] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-vzpnz; range_end:; response_count:1; response_revision:16585; }","duration":"184.381853ms","start":"2026-06-09T17:42:59.130976Z","end":"2026-06-09T17:42:59.315358Z","steps":["trace[1304942276] 'agreement among raft nodes before linearized reading' (duration: 184.130579ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:46:05.955231Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15686} {"level":"info","ts":"2026-06-09T17:46:06.047407Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15686,"took":"89.419594ms","hash":3581957863,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":34758656,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-06-09T17:46:06.047486Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3581957863,"revision":15686,"compact-revision":13023} {"level":"info","ts":"2026-06-09T17:48:41.234926Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T17:48:41.240679Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-09T17:48:41.240876Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-09T17:49:16.134797Z","caller":"traceutil/trace.go:171","msg":"trace[207765556] transaction","detail":"{read_only:false; response_revision:19543; number_of_response:1; }","duration":"135.388632ms","start":"2026-06-09T17:49:15.999383Z","end":"2026-06-09T17:49:16.134771Z","steps":["trace[207765556] 'process raft request' (duration: 135.196038ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:49:26.487973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.17717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:49:26.488078Z","caller":"traceutil/trace.go:171","msg":"trace[942075564] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19596; }","duration":"105.300012ms","start":"2026-06-09T17:49:26.382756Z","end":"2026-06-09T17:49:26.488056Z","steps":["trace[942075564] 'range keys from in-memory index tree' (duration: 105.080889ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:49:32.565556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.823307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:49:32.565674Z","caller":"traceutil/trace.go:171","msg":"trace[266073494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:19625; }","duration":"183.98193ms","start":"2026-06-09T17:49:32.381664Z","end":"2026-06-09T17:49:32.565646Z","steps":["trace[266073494] 'range keys from in-memory index tree' (duration: 183.665765ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:49:32.565717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.301956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:26 size:234067"} {"level":"info","ts":"2026-06-09T17:49:32.565834Z","caller":"traceutil/trace.go:171","msg":"trace[532320410] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:26; response_revision:19625; }","duration":"166.464829ms","start":"2026-06-09T17:49:32.399342Z","end":"2026-06-09T17:49:32.565807Z","steps":["trace[532320410] 'range keys from in-memory index tree' (duration: 165.673555ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:49:33.078123Z","caller":"traceutil/trace.go:171","msg":"trace[385376351] transaction","detail":"{read_only:false; response_revision:19628; number_of_response:1; }","duration":"202.959449ms","start":"2026-06-09T17:49:32.875133Z","end":"2026-06-09T17:49:33.078092Z","steps":["trace[385376351] 'process raft request' (duration: 202.770016ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:49:33.138824Z","caller":"traceutil/trace.go:171","msg":"trace[2141110311] linearizableReadLoop","detail":"{readStateIndex:20281; appliedIndex:20279; }","duration":"169.66272ms","start":"2026-06-09T17:49:32.969138Z","end":"2026-06-09T17:49:33.138801Z","steps":["trace[2141110311] 'read index received' (duration: 108.769728ms)","trace[2141110311] 'applied index is now lower than readState.Index' (duration: 60.892332ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:49:33.1389Z","caller":"traceutil/trace.go:171","msg":"trace[1143709599] transaction","detail":"{read_only:false; response_revision:19629; number_of_response:1; }","duration":"205.245793ms","start":"2026-06-09T17:49:32.933622Z","end":"2026-06-09T17:49:33.138867Z","steps":["trace[1143709599] 'process raft request' (duration: 205.044799ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:49:33.139005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.851484ms","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-06-09T17:49:33.139036Z","caller":"traceutil/trace.go:171","msg":"trace[1005063665] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19629; }","duration":"169.944825ms","start":"2026-06-09T17:49:32.969084Z","end":"2026-06-09T17:49:33.139028Z","steps":["trace[1005063665] 'agreement among raft nodes before linearized reading' (duration: 169.822933ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:49:33.139533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.015221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-09T17:49:33.139616Z","caller":"traceutil/trace.go:171","msg":"trace[1608724045] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:19629; }","duration":"119.125124ms","start":"2026-06-09T17:49:33.020449Z","end":"2026-06-09T17:49:33.139575Z","steps":["trace[1608724045] 'agreement among raft nodes before linearized reading' (duration: 118.630744ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:51:05.962492Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18230} {"level":"info","ts":"2026-06-09T17:51:06.074834Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18230,"took":"108.510512ms","hash":1613569081,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":30736384,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-09T17:51:06.074885Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1613569081,"revision":18230,"compact-revision":15686} {"level":"info","ts":"2026-06-09T17:53:04.147254Z","caller":"traceutil/trace.go:171","msg":"trace[519797717] transaction","detail":"{read_only:false; response_revision:21932; number_of_response:1; }","duration":"319.25095ms","start":"2026-06-09T17:53:03.827968Z","end":"2026-06-09T17:53:04.147219Z","steps":["trace[519797717] 'process raft request' (duration: 239.584623ms)","trace[519797717] 'compare' (duration: 79.32955ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:53:04.14742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:53:03.827941Z","time spent":"319.402774ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:53:04.280448Z","caller":"traceutil/trace.go:171","msg":"trace[427263370] transaction","detail":"{read_only:false; response_revision:21933; number_of_response:1; }","duration":"354.437316ms","start":"2026-06-09T17:53:03.925988Z","end":"2026-06-09T17:53:04.280425Z","steps":["trace[427263370] 'process raft request' (duration: 352.598581ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:53:04.280601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:53:03.925964Z","time spent":"354.557948ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:05.97168Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20481} {"level":"info","ts":"2026-06-09T17:56:06.07817Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20481,"took":"102.824227ms","hash":3132414204,"current-db-size-bytes":58793984,"current-db-size":"59 MB","current-db-size-in-use-bytes":43593728,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-06-09T17:56:06.07823Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3132414204,"revision":20481,"compact-revision":18230} {"level":"info","ts":"2026-06-09T17:56:25.372998Z","caller":"traceutil/trace.go:171","msg":"trace[1920837971] linearizableReadLoop","detail":"{readStateIndex:24750; appliedIndex:24749; }","duration":"116.939806ms","start":"2026-06-09T17:56:25.256038Z","end":"2026-06-09T17:56:25.372977Z","steps":["trace[1920837971] 'read index received' (duration: 116.845214ms)","trace[1920837971] 'applied index is now lower than readState.Index' (duration: 93.852µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:25.373218Z","caller":"traceutil/trace.go:171","msg":"trace[1024828451] transaction","detail":"{read_only:false; response_revision:23973; number_of_response:1; }","duration":"316.419484ms","start":"2026-06-09T17:56:25.056789Z","end":"2026-06-09T17:56:25.373208Z","steps":["trace[1024828451] 'process raft request' (duration: 316.082838ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:25.373606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:25.056769Z","time spent":"316.474615ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:56:25.373697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.62335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-09T17:56:25.373805Z","caller":"traceutil/trace.go:171","msg":"trace[482796260] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:23973; }","duration":"117.785382ms","start":"2026-06-09T17:56:25.255993Z","end":"2026-06-09T17:56:25.373778Z","steps":["trace[482796260] 'agreement among raft nodes before linearized reading' (duration: 117.472066ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:25.730635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.553319ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:56:25.730849Z","caller":"traceutil/trace.go:171","msg":"trace[1319813393] transaction","detail":"{read_only:false; response_revision:23974; number_of_response:1; }","duration":"352.881998ms","start":"2026-06-09T17:56:25.377941Z","end":"2026-06-09T17:56:25.730823Z","steps":["trace[1319813393] 'process raft request' (duration: 132.076734ms)","trace[1319813393] 'compare' (duration: 220.368165ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:25.730972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:25.377922Z","time spent":"352.98271ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:56:26.001989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.423771ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:56:26.002121Z","caller":"traceutil/trace.go:171","msg":"trace[1119784483] transaction","detail":"{read_only:false; response_revision:23975; number_of_response:1; }","duration":"266.962432ms","start":"2026-06-09T17:56:25.735138Z","end":"2026-06-09T17:56:26.002101Z","steps":["trace[1119784483] 'process raft request' (duration: 134.024271ms)","trace[1119784483] 'compare' (duration: 132.002883ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:26.061761Z","caller":"traceutil/trace.go:171","msg":"trace[1732104542] linearizableReadLoop","detail":"{readStateIndex:24755; appliedIndex:24751; }","duration":"278.588314ms","start":"2026-06-09T17:56:25.783148Z","end":"2026-06-09T17:56:26.061736Z","steps":["trace[1732104542] 'read index received' (duration: 85.993667ms)","trace[1732104542] 'applied index is now lower than readState.Index' (duration: 192.593457ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:26.062058Z","caller":"traceutil/trace.go:171","msg":"trace[1724934968] transaction","detail":"{read_only:false; response_revision:23976; number_of_response:1; }","duration":"326.884683ms","start":"2026-06-09T17:56:25.735159Z","end":"2026-06-09T17:56:26.062044Z","steps":["trace[1724934968] 'process raft request' (duration: 326.311153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:26.062156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:25.735136Z","time spent":"326.950675ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:26.062323Z","caller":"traceutil/trace.go:171","msg":"trace[1705125650] transaction","detail":"{read_only:false; response_revision:23977; number_of_response:1; }","duration":"327.097787ms","start":"2026-06-09T17:56:25.735215Z","end":"2026-06-09T17:56:26.062313Z","steps":["trace[1705125650] 'process raft request' (duration: 326.389754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:26.062375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:25.73519Z","time spent":"327.155149ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:26.062489Z","caller":"traceutil/trace.go:171","msg":"trace[1784040439] transaction","detail":"{read_only:false; response_revision:23978; number_of_response:1; }","duration":"326.69967ms","start":"2026-06-09T17:56:25.73578Z","end":"2026-06-09T17:56:26.06248Z","steps":["trace[1784040439] 'process raft request' (duration: 325.884184ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:26.062549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:25.735773Z","time spent":"326.748031ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:26.062677Z","caller":"traceutil/trace.go:171","msg":"trace[1756139918] transaction","detail":"{read_only:false; response_revision:23979; number_of_response:1; }","duration":"224.793779ms","start":"2026-06-09T17:56:25.837875Z","end":"2026-06-09T17:56:26.062668Z","steps":["trace[1756139918] 'process raft request' (duration: 223.825091ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:26.062831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.678315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:26.062876Z","caller":"traceutil/trace.go:171","msg":"trace[364560300] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:23979; }","duration":"279.752156ms","start":"2026-06-09T17:56:25.783111Z","end":"2026-06-09T17:56:26.062864Z","steps":["trace[364560300] 'agreement among raft nodes before linearized reading' (duration: 279.683684ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:26.333827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.524354ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:56:26.333995Z","caller":"traceutil/trace.go:171","msg":"trace[1924287758] linearizableReadLoop","detail":"{readStateIndex:24758; appliedIndex:24756; }","duration":"272.153822ms","start":"2026-06-09T17:56:26.06183Z","end":"2026-06-09T17:56:26.333984Z","steps":["trace[1924287758] 'read index received' (duration: 46.379173ms)","trace[1924287758] 'applied index is now lower than readState.Index' (duration: 225.774139ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:26.33402Z","caller":"traceutil/trace.go:171","msg":"trace[1191485934] transaction","detail":"{read_only:false; response_revision:23980; number_of_response:1; }","duration":"290.397189ms","start":"2026-06-09T17:56:26.043609Z","end":"2026-06-09T17:56:26.334007Z","steps":["trace[1191485934] 'process raft request' (duration: 64.638831ms)","trace[1191485934] 'compare' (duration: 225.447332ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:26.334104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.154696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/magnum-api-6c48877fd-44ktk\" ","response":"range_response_count:1 size:8647"} {"level":"info","ts":"2026-06-09T17:56:26.334117Z","caller":"traceutil/trace.go:171","msg":"trace[1471021130] range","detail":"{range_begin:/registry/pods/openstack/magnum-api-6c48877fd-44ktk; range_end:; response_count:1; response_revision:23981; }","duration":"340.203446ms","start":"2026-06-09T17:56:25.993909Z","end":"2026-06-09T17:56:26.334113Z","steps":["trace[1471021130] 'agreement among raft nodes before linearized reading' (duration: 340.101494ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:26.334124Z","caller":"traceutil/trace.go:171","msg":"trace[1832209072] transaction","detail":"{read_only:false; response_revision:23981; number_of_response:1; }","duration":"273.783452ms","start":"2026-06-09T17:56:26.060333Z","end":"2026-06-09T17:56:26.334117Z","steps":["trace[1832209072] 'process raft request' (duration: 273.612749ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:26.334132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:25.993891Z","time spent":"340.237217ms","remote":"127.0.0.1:34190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":8668,"request content":"key:\"/registry/pods/openstack/magnum-api-6c48877fd-44ktk\" "} {"level":"info","ts":"2026-06-09T17:56:26.361923Z","caller":"traceutil/trace.go:171","msg":"trace[1783640665] transaction","detail":"{read_only:false; response_revision:23982; number_of_response:1; }","duration":"230.188412ms","start":"2026-06-09T17:56:26.131703Z","end":"2026-06-09T17:56:26.361891Z","steps":["trace[1783640665] 'process raft request' (duration: 230.032699ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:26.636617Z","caller":"traceutil/trace.go:171","msg":"trace[1020159454] transaction","detail":"{read_only:false; response_revision:23983; number_of_response:1; }","duration":"431.484415ms","start":"2026-06-09T17:56:26.205107Z","end":"2026-06-09T17:56:26.636591Z","steps":["trace[1020159454] 'process raft request' (duration: 376.10853ms)","trace[1020159454] 'compare' (duration: 54.361156ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:26.636765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:26.205073Z","time spent":"431.612737ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:26.637121Z","caller":"traceutil/trace.go:171","msg":"trace[1737011723] transaction","detail":"{read_only:false; response_revision:23984; number_of_response:1; }","duration":"294.295563ms","start":"2026-06-09T17:56:26.342812Z","end":"2026-06-09T17:56:26.637107Z","steps":["trace[1737011723] 'process raft request' (duration: 293.348566ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:26.637294Z","caller":"traceutil/trace.go:171","msg":"trace[272030667] linearizableReadLoop","detail":"{readStateIndex:24761; appliedIndex:24759; }","duration":"247.250577ms","start":"2026-06-09T17:56:26.390034Z","end":"2026-06-09T17:56:26.637284Z","steps":["trace[272030667] 'read index received' (duration: 191.137848ms)","trace[272030667] 'applied index is now lower than readState.Index' (duration: 56.111649ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:26.637642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.601193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:26.637691Z","caller":"traceutil/trace.go:171","msg":"trace[1224539704] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:23985; }","duration":"247.683185ms","start":"2026-06-09T17:56:26.389997Z","end":"2026-06-09T17:56:26.63768Z","steps":["trace[1224539704] 'agreement among raft nodes before linearized reading' (duration: 247.605144ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:26.637986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.836156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"warn","ts":"2026-06-09T17:56:26.638022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.39851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackservers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:26.638033Z","caller":"traceutil/trace.go:171","msg":"trace[2137128093] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:23985; }","duration":"240.918826ms","start":"2026-06-09T17:56:26.397103Z","end":"2026-06-09T17:56:26.638022Z","steps":["trace[2137128093] 'agreement among raft nodes before linearized reading' (duration: 240.782444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:26.638239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.650468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-09T17:56:26.63828Z","caller":"traceutil/trace.go:171","msg":"trace[573927476] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:23985; }","duration":"150.713539ms","start":"2026-06-09T17:56:26.487555Z","end":"2026-06-09T17:56:26.638269Z","steps":["trace[573927476] 'agreement among raft nodes before linearized reading' (duration: 150.607217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:26.638288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.360479ms","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":"warn","ts":"2026-06-09T17:56:26.638285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.089225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:26.638363Z","caller":"traceutil/trace.go:171","msg":"trace[1854186707] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:23985; }","duration":"186.48146ms","start":"2026-06-09T17:56:26.451863Z","end":"2026-06-09T17:56:26.638345Z","steps":["trace[1854186707] 'agreement among raft nodes before linearized reading' (duration: 186.285277ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:26.638389Z","caller":"traceutil/trace.go:171","msg":"trace[1028889706] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:23985; }","duration":"226.237808ms","start":"2026-06-09T17:56:26.412124Z","end":"2026-06-09T17:56:26.638362Z","steps":["trace[1028889706] 'agreement among raft nodes before linearized reading' (duration: 226.067325ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:26.638093Z","caller":"traceutil/trace.go:171","msg":"trace[1664850245] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackservers/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackservers0; response_count:0; response_revision:23985; }","duration":"143.463241ms","start":"2026-06-09T17:56:26.494584Z","end":"2026-06-09T17:56:26.638047Z","steps":["trace[1664850245] 'agreement among raft nodes before linearized reading' (duration: 143.39815ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:26.794898Z","caller":"traceutil/trace.go:171","msg":"trace[462247117] transaction","detail":"{read_only:false; response_revision:23986; number_of_response:1; }","duration":"150.639157ms","start":"2026-06-09T17:56:26.644237Z","end":"2026-06-09T17:56:26.794876Z","steps":["trace[462247117] 'process raft request' (duration: 115.174003ms)","trace[462247117] 'compare' (duration: 35.381983ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:27.037163Z","caller":"traceutil/trace.go:171","msg":"trace[1381872312] transaction","detail":"{read_only:false; response_revision:23987; number_of_response:1; }","duration":"392.667465ms","start":"2026-06-09T17:56:26.64447Z","end":"2026-06-09T17:56:27.037137Z","steps":["trace[1381872312] 'process raft request' (duration: 392.452411ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:27.037259Z","caller":"traceutil/trace.go:171","msg":"trace[1425563663] transaction","detail":"{read_only:false; response_revision:23989; number_of_response:1; }","duration":"392.524203ms","start":"2026-06-09T17:56:26.644726Z","end":"2026-06-09T17:56:27.03725Z","steps":["trace[1425563663] 'process raft request' (duration: 392.374001ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:27.037163Z","caller":"traceutil/trace.go:171","msg":"trace[87062016] transaction","detail":"{read_only:false; response_revision:23988; number_of_response:1; }","duration":"392.659066ms","start":"2026-06-09T17:56:26.644492Z","end":"2026-06-09T17:56:27.037151Z","steps":["trace[87062016] 'process raft request' (duration: 392.563044ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:27.037309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:26.644711Z","time spent":"392.567714ms","remote":"127.0.0.1:34302","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1167,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:56:27.037294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:26.644454Z","time spent":"392.770318ms","remote":"127.0.0.1:34356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:56:27.03746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:26.64448Z","time spent":"392.872659ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:27.037555Z","caller":"traceutil/trace.go:171","msg":"trace[567290068] transaction","detail":"{read_only:false; response_revision:23990; number_of_response:1; }","duration":"392.34003ms","start":"2026-06-09T17:56:26.645206Z","end":"2026-06-09T17:56:27.037546Z","steps":["trace[567290068] 'process raft request' (duration: 392.012224ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:27.037608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:26.645184Z","time spent":"392.398811ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:27.037615Z","caller":"traceutil/trace.go:171","msg":"trace[552926910] transaction","detail":"{read_only:false; response_revision:23991; number_of_response:1; }","duration":"388.373704ms","start":"2026-06-09T17:56:26.649221Z","end":"2026-06-09T17:56:27.037595Z","steps":["trace[552926910] 'process raft request' (duration: 388.240871ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:27.037688Z","caller":"traceutil/trace.go:171","msg":"trace[686110349] linearizableReadLoop","detail":"{readStateIndex:24767; appliedIndex:24762; }","duration":"392.36439ms","start":"2026-06-09T17:56:26.645316Z","end":"2026-06-09T17:56:27.03768Z","steps":["trace[686110349] 'read index received' (duration: 114.172634ms)","trace[686110349] 'applied index is now lower than readState.Index' (duration: 278.191116ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:27.037722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:26.649213Z","time spent":"388.451716ms","remote":"127.0.0.1:34536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7531,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:27.037739Z","caller":"traceutil/trace.go:171","msg":"trace[460618765] transaction","detail":"{read_only:false; response_revision:23992; number_of_response:1; }","duration":"337.22663ms","start":"2026-06-09T17:56:26.700502Z","end":"2026-06-09T17:56:27.037729Z","steps":["trace[460618765] 'process raft request' (duration: 337.043587ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:27.037789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:26.700478Z","time spent":"337.283641ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:56:27.037823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.506573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-0e5b527d-8582-46f2-be84-0ceda28ebf61\" ","response":"range_response_count:1 size:1320"} {"level":"info","ts":"2026-06-09T17:56:27.037852Z","caller":"traceutil/trace.go:171","msg":"trace[486818636] range","detail":"{range_begin:/registry/persistentvolumes/pvc-0e5b527d-8582-46f2-be84-0ceda28ebf61; range_end:; response_count:1; response_revision:23992; }","duration":"392.552304ms","start":"2026-06-09T17:56:26.645292Z","end":"2026-06-09T17:56:27.037844Z","steps":["trace[486818636] 'agreement among raft nodes before linearized reading' (duration: 392.438701ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:27.037877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:26.645275Z","time spent":"392.596804ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1341,"request content":"key:\"/registry/persistentvolumes/pvc-0e5b527d-8582-46f2-be84-0ceda28ebf61\" "} {"level":"warn","ts":"2026-06-09T17:56:27.059896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.729328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-09T17:56:27.059947Z","caller":"traceutil/trace.go:171","msg":"trace[1303004013] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:23993; }","duration":"349.82611ms","start":"2026-06-09T17:56:26.71011Z","end":"2026-06-09T17:56:27.059936Z","steps":["trace[1303004013] 'agreement among raft nodes before linearized reading' (duration: 349.648577ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:27.059971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:26.710094Z","time spent":"349.871731ms","remote":"127.0.0.1:34372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":178,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:56:27.060074Z","caller":"traceutil/trace.go:171","msg":"trace[1194489784] transaction","detail":"{read_only:false; response_revision:23993; number_of_response:1; }","duration":"201.349483ms","start":"2026-06-09T17:56:26.858698Z","end":"2026-06-09T17:56:27.060047Z","steps":["trace[1194489784] 'process raft request' (duration: 200.967506ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:27.060508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.747356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:31 size:268798"} {"level":"info","ts":"2026-06-09T17:56:27.060585Z","caller":"traceutil/trace.go:171","msg":"trace[2089965987] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:31; response_revision:23993; }","duration":"278.853079ms","start":"2026-06-09T17:56:26.781698Z","end":"2026-06-09T17:56:27.060551Z","steps":["trace[2089965987] 'agreement among raft nodes before linearized reading' (duration: 278.447641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:27.061994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.248417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:27.06204Z","caller":"traceutil/trace.go:171","msg":"trace[272351547] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:23993; }","duration":"351.306978ms","start":"2026-06-09T17:56:26.710722Z","end":"2026-06-09T17:56:27.062029Z","steps":["trace[272351547] 'agreement among raft nodes before linearized reading' (duration: 349.368911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:27.06207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:26.710716Z","time spent":"351.346989ms","remote":"127.0.0.1:34512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-09T17:56:30.287532Z","caller":"traceutil/trace.go:171","msg":"trace[1872500126] linearizableReadLoop","detail":"{readStateIndex:24791; appliedIndex:24790; }","duration":"209.808975ms","start":"2026-06-09T17:56:30.077699Z","end":"2026-06-09T17:56:30.287508Z","steps":["trace[1872500126] 'read index received' (duration: 33.59476ms)","trace[1872500126] 'applied index is now lower than readState.Index' (duration: 176.213375ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:30.287574Z","caller":"traceutil/trace.go:171","msg":"trace[427260913] transaction","detail":"{read_only:false; response_revision:24013; number_of_response:1; }","duration":"210.045599ms","start":"2026-06-09T17:56:30.077475Z","end":"2026-06-09T17:56:30.287521Z","steps":["trace[427260913] 'process raft request' (duration: 187.340107ms)","trace[427260913] 'compare' (duration: 22.560659ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:30.2877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.981488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:30.287738Z","caller":"traceutil/trace.go:171","msg":"trace[1294747044] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24013; }","duration":"210.06723ms","start":"2026-06-09T17:56:30.077658Z","end":"2026-06-09T17:56:30.287725Z","steps":["trace[1294747044] 'agreement among raft nodes before linearized reading' (duration: 209.981298ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:30.287826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.53079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-09T17:56:30.287893Z","caller":"traceutil/trace.go:171","msg":"trace[500422366] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24013; }","duration":"208.632671ms","start":"2026-06-09T17:56:30.079242Z","end":"2026-06-09T17:56:30.287875Z","steps":["trace[500422366] 'agreement among raft nodes before linearized reading' (duration: 208.425308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:30.288057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.863717ms","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-06-09T17:56:30.288083Z","caller":"traceutil/trace.go:171","msg":"trace[779429679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24013; }","duration":"207.903638ms","start":"2026-06-09T17:56:30.080171Z","end":"2026-06-09T17:56:30.288075Z","steps":["trace[779429679] 'agreement among raft nodes before linearized reading' (duration: 207.825077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:30.28822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.330646ms","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-06-09T17:56:30.28825Z","caller":"traceutil/trace.go:171","msg":"trace[1646847575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24013; }","duration":"208.396317ms","start":"2026-06-09T17:56:30.079843Z","end":"2026-06-09T17:56:30.28824Z","steps":["trace[1646847575] 'agreement among raft nodes before linearized reading' (duration: 208.272825ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:30.556015Z","caller":"traceutil/trace.go:171","msg":"trace[1474432376] linearizableReadLoop","detail":"{readStateIndex:24794; appliedIndex:24791; }","duration":"253.397324ms","start":"2026-06-09T17:56:30.302594Z","end":"2026-06-09T17:56:30.555991Z","steps":["trace[1474432376] 'read index received' (duration: 6.685907ms)","trace[1474432376] 'applied index is now lower than readState.Index' (duration: 246.710617ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:30.556131Z","caller":"traceutil/trace.go:171","msg":"trace[1509869515] transaction","detail":"{read_only:false; response_revision:24015; number_of_response:1; }","duration":"263.450826ms","start":"2026-06-09T17:56:30.292669Z","end":"2026-06-09T17:56:30.55612Z","steps":["trace[1509869515] 'process raft request' (duration: 258.126844ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:30.556175Z","caller":"traceutil/trace.go:171","msg":"trace[635671455] transaction","detail":"{read_only:false; response_revision:24016; number_of_response:1; }","duration":"261.624901ms","start":"2026-06-09T17:56:30.294517Z","end":"2026-06-09T17:56:30.556142Z","steps":["trace[635671455] 'process raft request' (duration: 261.412007ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:30.556295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.680659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:30.556335Z","caller":"traceutil/trace.go:171","msg":"trace[504621889] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24016; }","duration":"253.76613ms","start":"2026-06-09T17:56:30.302558Z","end":"2026-06-09T17:56:30.556324Z","steps":["trace[504621889] 'agreement among raft nodes before linearized reading' (duration: 253.685709ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:30.756399Z","caller":"traceutil/trace.go:171","msg":"trace[700397996] transaction","detail":"{read_only:false; response_revision:24019; number_of_response:1; }","duration":"380.357921ms","start":"2026-06-09T17:56:30.376019Z","end":"2026-06-09T17:56:30.756377Z","steps":["trace[700397996] 'process raft request' (duration: 380.317791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:30.756536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:30.375989Z","time spent":"380.478003ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:30.756902Z","caller":"traceutil/trace.go:171","msg":"trace[898093478] transaction","detail":"{read_only:false; response_revision:24017; number_of_response:1; }","duration":"409.532126ms","start":"2026-06-09T17:56:30.347358Z","end":"2026-06-09T17:56:30.75689Z","steps":["trace[898093478] 'process raft request' (duration: 408.799132ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:30.756964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:30.347338Z","time spent":"409.589488ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:30.757106Z","caller":"traceutil/trace.go:171","msg":"trace[907901442] transaction","detail":"{read_only:false; response_revision:24018; number_of_response:1; }","duration":"409.103318ms","start":"2026-06-09T17:56:30.347991Z","end":"2026-06-09T17:56:30.757094Z","steps":["trace[907901442] 'process raft request' (duration: 408.304293ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:30.757159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:30.347982Z","time spent":"409.145269ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:30.757326Z","caller":"traceutil/trace.go:171","msg":"trace[666079148] linearizableReadLoop","detail":"{readStateIndex:24797; appliedIndex:24797; }","duration":"201.206001ms","start":"2026-06-09T17:56:30.556091Z","end":"2026-06-09T17:56:30.757297Z","steps":["trace[666079148] 'read index received' (duration: 201.19561ms)","trace[666079148] 'applied index is now lower than readState.Index' (duration: 8.681µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:30.757466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.797715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:30.757489Z","caller":"traceutil/trace.go:171","msg":"trace[873469197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24019; }","duration":"374.877827ms","start":"2026-06-09T17:56:30.382604Z","end":"2026-06-09T17:56:30.757482Z","steps":["trace[873469197] 'agreement among raft nodes before linearized reading' (duration: 374.794536ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:30.757526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:30.382581Z","time spent":"374.939648ms","remote":"127.0.0.1:34004","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:56:30.984053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.73819ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:56:30.984248Z","caller":"traceutil/trace.go:171","msg":"trace[656087099] linearizableReadLoop","detail":"{readStateIndex:24800; appliedIndex:24797; }","duration":"226.858089ms","start":"2026-06-09T17:56:30.757374Z","end":"2026-06-09T17:56:30.984232Z","steps":["trace[656087099] 'read index received' (duration: 117.863724ms)","trace[656087099] 'applied index is now lower than readState.Index' (duration: 108.993395ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:30.984282Z","caller":"traceutil/trace.go:171","msg":"trace[1590825093] transaction","detail":"{read_only:false; response_revision:24022; number_of_response:1; }","duration":"334.885026ms","start":"2026-06-09T17:56:30.649366Z","end":"2026-06-09T17:56:30.984251Z","steps":["trace[1590825093] 'process raft request' (duration: 334.821035ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:30.98442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:30.649359Z","time spent":"334.985698ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:56:30.984461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.818395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-06-09T17:56:30.984489Z","caller":"traceutil/trace.go:171","msg":"trace[102169417] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24022; }","duration":"271.873296ms","start":"2026-06-09T17:56:30.712607Z","end":"2026-06-09T17:56:30.98448Z","steps":["trace[102169417] 'agreement among raft nodes before linearized reading' (duration: 271.711043ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:30.984577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.342479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:30.984613Z","caller":"traceutil/trace.go:171","msg":"trace[1343832178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24022; }","duration":"225.436491ms","start":"2026-06-09T17:56:30.759165Z","end":"2026-06-09T17:56:30.984602Z","steps":["trace[1343832178] 'agreement among raft nodes before linearized reading' (duration: 225.37967ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:30.98458Z","caller":"traceutil/trace.go:171","msg":"trace[669528806] transaction","detail":"{read_only:false; response_revision:24020; number_of_response:1; }","duration":"365.459067ms","start":"2026-06-09T17:56:30.619075Z","end":"2026-06-09T17:56:30.984534Z","steps":["trace[669528806] 'process raft request' (duration: 256.165066ms)","trace[669528806] 'compare' (duration: 108.538277ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:30.984629Z","caller":"traceutil/trace.go:171","msg":"trace[1434791354] transaction","detail":"{read_only:false; response_revision:24021; number_of_response:1; }","duration":"336.748861ms","start":"2026-06-09T17:56:30.647844Z","end":"2026-06-09T17:56:30.984593Z","steps":["trace[1434791354] 'process raft request' (duration: 336.293892ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:30.984635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.265301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:56:30.984719Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:30.619053Z","time spent":"365.59576ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:30.984729Z","caller":"traceutil/trace.go:171","msg":"trace[229734959] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/domains/; range_end:/registry/openstack.k-orc.cloud/domains0; response_count:0; response_revision:24022; }","duration":"327.395633ms","start":"2026-06-09T17:56:30.657312Z","end":"2026-06-09T17:56:30.984708Z","steps":["trace[229734959] 'agreement among raft nodes before linearized reading' (duration: 327.23281ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:30.984638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.892283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:56:30.98478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.671909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18571"} {"level":"warn","ts":"2026-06-09T17:56:30.984785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:30.657304Z","time spent":"327.470584ms","remote":"127.0.0.1:40662","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":27,"request content":"key:\"/registry/openstack.k-orc.cloud/domains/\" range_end:\"/registry/openstack.k-orc.cloud/domains0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:56:30.984816Z","caller":"traceutil/trace.go:171","msg":"trace[1956192208] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24022; }","duration":"422.132927ms","start":"2026-06-09T17:56:30.562668Z","end":"2026-06-09T17:56:30.984801Z","steps":["trace[1956192208] 'agreement among raft nodes before linearized reading' (duration: 421.870042ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:30.984818Z","caller":"traceutil/trace.go:171","msg":"trace[771513099] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24022; }","duration":"378.748291ms","start":"2026-06-09T17:56:30.606059Z","end":"2026-06-09T17:56:30.984807Z","steps":["trace[771513099] 'agreement among raft nodes before linearized reading' (duration: 378.589878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:30.984827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:30.647824Z","time spent":"336.883153ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:56:30.984849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:30.606037Z","time spent":"378.805231ms","remote":"127.0.0.1:34176","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18592,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-06-09T17:56:30.984861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:30.562651Z","time spent":"422.199379ms","remote":"127.0.0.1:34302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-06-09T17:56:31.234527Z","caller":"traceutil/trace.go:171","msg":"trace[534512130] transaction","detail":"{read_only:false; response_revision:24023; number_of_response:1; }","duration":"176.714164ms","start":"2026-06-09T17:56:31.057788Z","end":"2026-06-09T17:56:31.234502Z","steps":["trace[534512130] 'process raft request' (duration: 176.561291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:31.62918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.604172ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:56:31.629316Z","caller":"traceutil/trace.go:171","msg":"trace[876846603] linearizableReadLoop","detail":"{readStateIndex:24802; appliedIndex:24801; }","duration":"187.581792ms","start":"2026-06-09T17:56:31.441718Z","end":"2026-06-09T17:56:31.6293Z","steps":["trace[876846603] 'read index received' (duration: 77.972µs)","trace[876846603] 'applied index is now lower than readState.Index' (duration: 187.50259ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:31.630059Z","caller":"traceutil/trace.go:171","msg":"trace[2089552970] transaction","detail":"{read_only:false; response_revision:24024; number_of_response:1; }","duration":"234.713038ms","start":"2026-06-09T17:56:31.395294Z","end":"2026-06-09T17:56:31.630007Z","steps":["trace[2089552970] 'process raft request' (duration: 46.191889ms)","trace[2089552970] 'compare' (duration: 187.459839ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:31.630203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.459739ms","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:2083"} {"level":"info","ts":"2026-06-09T17:56:31.630283Z","caller":"traceutil/trace.go:171","msg":"trace[1489137200] 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:24024; }","duration":"188.595462ms","start":"2026-06-09T17:56:31.441668Z","end":"2026-06-09T17:56:31.630263Z","steps":["trace[1489137200] 'agreement among raft nodes before linearized reading' (duration: 188.337196ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:31.899289Z","caller":"traceutil/trace.go:171","msg":"trace[1797526962] transaction","detail":"{read_only:false; response_revision:24025; number_of_response:1; }","duration":"135.064432ms","start":"2026-06-09T17:56:31.764206Z","end":"2026-06-09T17:56:31.899271Z","steps":["trace[1797526962] 'process raft request' (duration: 134.645643ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:32.324719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.94057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:56:32.325161Z","caller":"traceutil/trace.go:171","msg":"trace[190539564] linearizableReadLoop","detail":"{readStateIndex:24804; appliedIndex:24803; }","duration":"208.593702ms","start":"2026-06-09T17:56:32.116554Z","end":"2026-06-09T17:56:32.325148Z","steps":["trace[190539564] 'read index received' (duration: 75.069879ms)","trace[190539564] 'applied index is now lower than readState.Index' (duration: 133.522613ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:32.325194Z","caller":"traceutil/trace.go:171","msg":"trace[1857763033] transaction","detail":"{read_only:false; response_revision:24026; number_of_response:1; }","duration":"280.693685ms","start":"2026-06-09T17:56:32.044471Z","end":"2026-06-09T17:56:32.325165Z","steps":["trace[1857763033] 'process raft request' (duration: 147.237454ms)","trace[1857763033] 'compare' (duration: 132.657235ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:32.325303Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.760004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-09T17:56:32.325328Z","caller":"traceutil/trace.go:171","msg":"trace[1198742487] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24026; }","duration":"208.820836ms","start":"2026-06-09T17:56:32.116501Z","end":"2026-06-09T17:56:32.325322Z","steps":["trace[1198742487] 'agreement among raft nodes before linearized reading' (duration: 208.708134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:32.328121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.898298ms","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-06-09T17:56:32.32818Z","caller":"traceutil/trace.go:171","msg":"trace[1486075668] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:24027; }","duration":"125.023091ms","start":"2026-06-09T17:56:32.203144Z","end":"2026-06-09T17:56:32.328167Z","steps":["trace[1486075668] 'agreement among raft nodes before linearized reading' (duration: 124.901249ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:32.329074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.234702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-09T17:56:32.329135Z","caller":"traceutil/trace.go:171","msg":"trace[890483903] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24027; }","duration":"200.334583ms","start":"2026-06-09T17:56:32.128785Z","end":"2026-06-09T17:56:32.329119Z","steps":["trace[890483903] 'agreement among raft nodes before linearized reading' (duration: 199.056499ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:32.694305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.50977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:56:32.694402Z","caller":"traceutil/trace.go:171","msg":"trace[995732257] linearizableReadLoop","detail":"{readStateIndex:24807; appliedIndex:24805; }","duration":"362.256686ms","start":"2026-06-09T17:56:32.332129Z","end":"2026-06-09T17:56:32.694385Z","steps":["trace[995732257] 'read index received' (duration: 7.423942ms)","trace[995732257] 'applied index is now lower than readState.Index' (duration: 354.831134ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:32.694487Z","caller":"traceutil/trace.go:171","msg":"trace[2001459855] transaction","detail":"{read_only:false; response_revision:24029; number_of_response:1; }","duration":"362.542892ms","start":"2026-06-09T17:56:32.331906Z","end":"2026-06-09T17:56:32.694449Z","steps":["trace[2001459855] 'process raft request' (duration: 206.799417ms)","trace[2001459855] 'compare' (duration: 155.389018ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:32.694554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.035137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:56:32.69458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.45275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-06-09T17:56:32.694597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:32.331885Z","time spent":"362.656124ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:32.694616Z","caller":"traceutil/trace.go:171","msg":"trace[809849833] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24029; }","duration":"362.503231ms","start":"2026-06-09T17:56:32.332102Z","end":"2026-06-09T17:56:32.694606Z","steps":["trace[809849833] 'agreement among raft nodes before linearized reading' (duration: 362.358948ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:32.694617Z","caller":"traceutil/trace.go:171","msg":"trace[758082609] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/keypairs/; range_end:/registry/openstack.k-orc.cloud/keypairs0; response_count:0; response_revision:24029; }","duration":"336.150059ms","start":"2026-06-09T17:56:32.35845Z","end":"2026-06-09T17:56:32.6946Z","steps":["trace[758082609] 'agreement among raft nodes before linearized reading' (duration: 336.040057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:32.694649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:32.332093Z","time spent":"362.547993ms","remote":"127.0.0.1:34190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-09T17:56:32.694775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.555993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-09T17:56:32.694656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:32.358431Z","time spent":"336.215632ms","remote":"127.0.0.1:40694","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/openstack.k-orc.cloud/keypairs/\" range_end:\"/registry/openstack.k-orc.cloud/keypairs0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:56:32.694815Z","caller":"traceutil/trace.go:171","msg":"trace[246360725] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24029; }","duration":"133.628664ms","start":"2026-06-09T17:56:32.561177Z","end":"2026-06-09T17:56:32.694805Z","steps":["trace[246360725] 'agreement among raft nodes before linearized reading' (duration: 133.500632ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:32.694949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.044914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-09T17:56:32.69498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.609672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-09T17:56:32.694986Z","caller":"traceutil/trace.go:171","msg":"trace[1053629355] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:24029; }","duration":"247.120566ms","start":"2026-06-09T17:56:32.447854Z","end":"2026-06-09T17:56:32.694975Z","steps":["trace[1053629355] 'agreement among raft nodes before linearized reading' (duration: 247.052664ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:32.695008Z","caller":"traceutil/trace.go:171","msg":"trace[347755104] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24029; }","duration":"134.673604ms","start":"2026-06-09T17:56:32.560326Z","end":"2026-06-09T17:56:32.695Z","steps":["trace[347755104] 'agreement among raft nodes before linearized reading' (duration: 134.589672ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:32.69501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.190283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:32.695079Z","caller":"traceutil/trace.go:171","msg":"trace[1073918096] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24029; }","duration":"314.298534ms","start":"2026-06-09T17:56:32.380764Z","end":"2026-06-09T17:56:32.695062Z","steps":["trace[1073918096] 'agreement among raft nodes before linearized reading' (duration: 314.157322ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:32.695118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:32.380746Z","time spent":"314.363906ms","remote":"127.0.0.1:34004","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-06-09T17:56:32.821578Z","caller":"traceutil/trace.go:171","msg":"trace[207340829] transaction","detail":"{read_only:false; response_revision:24030; number_of_response:1; }","duration":"123.247406ms","start":"2026-06-09T17:56:32.698305Z","end":"2026-06-09T17:56:32.821553Z","steps":["trace[207340829] 'process raft request' (duration: 122.38512ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:33.13064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.272563ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:56:33.131036Z","caller":"traceutil/trace.go:171","msg":"trace[1301409744] transaction","detail":"{read_only:false; response_revision:24031; number_of_response:1; }","duration":"429.987635ms","start":"2026-06-09T17:56:32.700981Z","end":"2026-06-09T17:56:33.130968Z","steps":["trace[1301409744] 'process raft request' (duration: 282.306364ms)","trace[1301409744] 'compare' (duration: 147.153501ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:33.13117Z","caller":"traceutil/trace.go:171","msg":"trace[1442924410] transaction","detail":"{read_only:false; response_revision:24032; number_of_response:1; }","duration":"362.986379ms","start":"2026-06-09T17:56:32.768149Z","end":"2026-06-09T17:56:33.131135Z","steps":["trace[1442924410] 'process raft request' (duration: 362.727034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:33.131222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:32.70096Z","time spent":"430.167469ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:56:33.131327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:32.768132Z","time spent":"363.108712ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:33.131346Z","caller":"traceutil/trace.go:171","msg":"trace[326151690] transaction","detail":"{read_only:false; response_revision:24035; number_of_response:1; }","duration":"141.250349ms","start":"2026-06-09T17:56:32.990077Z","end":"2026-06-09T17:56:33.131327Z","steps":["trace[326151690] 'process raft request' (duration: 141.205598ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:33.131348Z","caller":"traceutil/trace.go:171","msg":"trace[1533154743] transaction","detail":"{read_only:false; response_revision:24033; number_of_response:1; }","duration":"363.025431ms","start":"2026-06-09T17:56:32.768295Z","end":"2026-06-09T17:56:33.13132Z","steps":["trace[1533154743] 'process raft request' (duration: 362.683344ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:33.131512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:32.76829Z","time spent":"363.118322ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:33.131555Z","caller":"traceutil/trace.go:171","msg":"trace[534860765] transaction","detail":"{read_only:false; response_revision:24034; number_of_response:1; }","duration":"363.202314ms","start":"2026-06-09T17:56:32.768332Z","end":"2026-06-09T17:56:33.131535Z","steps":["trace[534860765] 'process raft request' (duration: 362.907808ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:33.131633Z","caller":"traceutil/trace.go:171","msg":"trace[1560739515] linearizableReadLoop","detail":"{readStateIndex:24812; appliedIndex:24807; }","duration":"321.921587ms","start":"2026-06-09T17:56:32.809699Z","end":"2026-06-09T17:56:33.13162Z","steps":["trace[1560739515] 'read index received' (duration: 11.004739ms)","trace[1560739515] 'applied index is now lower than readState.Index' (duration: 310.915168ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:33.131653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:32.768328Z","time spent":"363.272455ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:56:33.131746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.038991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:33.131782Z","caller":"traceutil/trace.go:171","msg":"trace[1639709859] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24035; }","duration":"322.108472ms","start":"2026-06-09T17:56:32.809665Z","end":"2026-06-09T17:56:33.131773Z","steps":["trace[1639709859] 'agreement among raft nodes before linearized reading' (duration: 322.032711ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:33.13182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:32.809638Z","time spent":"322.172474ms","remote":"127.0.0.1:34512","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":27,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-09T17:56:33.170223Z","caller":"traceutil/trace.go:171","msg":"trace[1202124845] transaction","detail":"{read_only:false; response_revision:24036; number_of_response:1; }","duration":"179.023098ms","start":"2026-06-09T17:56:32.991182Z","end":"2026-06-09T17:56:33.170205Z","steps":["trace[1202124845] 'process raft request' (duration: 178.818164ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:33.170367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.505884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"info","ts":"2026-06-09T17:56:33.170435Z","caller":"traceutil/trace.go:171","msg":"trace[822657209] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24036; }","duration":"172.594745ms","start":"2026-06-09T17:56:32.997824Z","end":"2026-06-09T17:56:33.170418Z","steps":["trace[822657209] 'agreement among raft nodes before linearized reading' (duration: 172.384111ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:33.467675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.859827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:56:33.46783Z","caller":"traceutil/trace.go:171","msg":"trace[2076093863] linearizableReadLoop","detail":"{readStateIndex:24815; appliedIndex:24814; }","duration":"297.646228ms","start":"2026-06-09T17:56:33.170167Z","end":"2026-06-09T17:56:33.467813Z","steps":["trace[2076093863] 'read index received' (duration: 110.706409ms)","trace[2076093863] 'applied index is now lower than readState.Index' (duration: 186.938429ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:33.467821Z","caller":"traceutil/trace.go:171","msg":"trace[612986138] transaction","detail":"{read_only:false; response_revision:24037; number_of_response:1; }","duration":"297.934791ms","start":"2026-06-09T17:56:33.169855Z","end":"2026-06-09T17:56:33.467789Z","steps":["trace[612986138] 'process raft request' (duration: 110.876701ms)","trace[612986138] 'compare' (duration: 186.681243ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:33.468073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.914748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:33.468108Z","caller":"traceutil/trace.go:171","msg":"trace[1689231037] transaction","detail":"{read_only:false; response_revision:24038; number_of_response:1; }","duration":"293.529739ms","start":"2026-06-09T17:56:33.174552Z","end":"2026-06-09T17:56:33.468082Z","steps":["trace[1689231037] 'process raft request' (duration: 293.225482ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:33.468147Z","caller":"traceutil/trace.go:171","msg":"trace[1645071345] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24038; }","duration":"333.02685ms","start":"2026-06-09T17:56:33.135106Z","end":"2026-06-09T17:56:33.468133Z","steps":["trace[1645071345] 'agreement among raft nodes before linearized reading' (duration: 332.868367ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:33.468137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.761562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-06-09T17:56:33.468196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:33.135092Z","time spent":"333.094942ms","remote":"127.0.0.1:34200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" "} {"level":"info","ts":"2026-06-09T17:56:33.468207Z","caller":"traceutil/trace.go:171","msg":"trace[375690866] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24038; }","duration":"221.872705ms","start":"2026-06-09T17:56:33.246316Z","end":"2026-06-09T17:56:33.468189Z","steps":["trace[375690866] 'agreement among raft nodes before linearized reading' (duration: 221.674381ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:33.745733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.42273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:56:33.745863Z","caller":"traceutil/trace.go:171","msg":"trace[145031596] transaction","detail":"{read_only:false; response_revision:24039; number_of_response:1; }","duration":"273.696611ms","start":"2026-06-09T17:56:33.472133Z","end":"2026-06-09T17:56:33.74583Z","steps":["trace[145031596] 'process raft request' (duration: 159.995465ms)","trace[145031596] 'compare' (duration: 113.071964ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:33.745873Z","caller":"traceutil/trace.go:171","msg":"trace[580549143] linearizableReadLoop","detail":"{readStateIndex:24817; appliedIndex:24816; }","duration":"272.957967ms","start":"2026-06-09T17:56:33.472898Z","end":"2026-06-09T17:56:33.745856Z","steps":["trace[580549143] 'read index received' (duration: 159.277352ms)","trace[580549143] 'applied index is now lower than readState.Index' (duration: 113.678915ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:33.746385Z","caller":"traceutil/trace.go:171","msg":"trace[1925643315] transaction","detail":"{read_only:false; response_revision:24040; number_of_response:1; }","duration":"110.538805ms","start":"2026-06-09T17:56:33.635827Z","end":"2026-06-09T17:56:33.746365Z","steps":["trace[1925643315] 'process raft request' (duration: 110.435243ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:33.746601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.693951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18571"} {"level":"info","ts":"2026-06-09T17:56:33.74667Z","caller":"traceutil/trace.go:171","msg":"trace[1989589965] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24040; }","duration":"273.775253ms","start":"2026-06-09T17:56:33.472873Z","end":"2026-06-09T17:56:33.746648Z","steps":["trace[1989589965] 'agreement among raft nodes before linearized reading' (duration: 273.493498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:34.044574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.611625ms","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-06-09T17:56:34.044654Z","caller":"traceutil/trace.go:171","msg":"trace[2009176018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24040; }","duration":"141.732447ms","start":"2026-06-09T17:56:33.902905Z","end":"2026-06-09T17:56:34.044637Z","steps":["trace[2009176018] 'range keys from in-memory index tree' (duration: 141.444772ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:34.185966Z","caller":"traceutil/trace.go:171","msg":"trace[1609715404] transaction","detail":"{read_only:false; response_revision:24041; number_of_response:1; }","duration":"137.151821ms","start":"2026-06-09T17:56:34.048792Z","end":"2026-06-09T17:56:34.185944Z","steps":["trace[1609715404] 'process raft request' (duration: 136.989688ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:34.529523Z","caller":"traceutil/trace.go:171","msg":"trace[475758039] transaction","detail":"{read_only:false; response_revision:24043; number_of_response:1; }","duration":"105.662761ms","start":"2026-06-09T17:56:34.423839Z","end":"2026-06-09T17:56:34.529502Z","steps":["trace[475758039] 'process raft request' (duration: 105.565309ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:34.529737Z","caller":"traceutil/trace.go:171","msg":"trace[1482253517] linearizableReadLoop","detail":"{readStateIndex:24821; appliedIndex:24820; }","duration":"109.033005ms","start":"2026-06-09T17:56:34.420696Z","end":"2026-06-09T17:56:34.529729Z","steps":["trace[1482253517] 'read index received' (duration: 80.218926ms)","trace[1482253517] 'applied index is now lower than readState.Index' (duration: 28.812779ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:34.67424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.879776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:34.674321Z","caller":"traceutil/trace.go:171","msg":"trace[1462757935] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24043; }","duration":"292.998837ms","start":"2026-06-09T17:56:34.3813Z","end":"2026-06-09T17:56:34.674299Z","steps":["trace[1462757935] 'agreement among raft nodes before linearized reading' (duration: 148.458496ms)","trace[1462757935] 'range keys from in-memory index tree' (duration: 144.432259ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:34.674684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.522882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1782"} {"level":"info","ts":"2026-06-09T17:56:34.674725Z","caller":"traceutil/trace.go:171","msg":"trace[1988029237] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:24043; }","duration":"278.601373ms","start":"2026-06-09T17:56:34.396112Z","end":"2026-06-09T17:56:34.674713Z","steps":["trace[1988029237] 'agreement among raft nodes before linearized reading' (duration: 133.685525ms)","trace[1988029237] 'range keys from in-memory index tree' (duration: 144.801196ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:34.675457Z","caller":"traceutil/trace.go:171","msg":"trace[256890082] transaction","detail":"{read_only:false; response_revision:24044; number_of_response:1; }","duration":"105.465178ms","start":"2026-06-09T17:56:34.569951Z","end":"2026-06-09T17:56:34.675417Z","steps":["trace[256890082] 'process raft request' (duration: 90.859659ms)","trace[256890082] 'compare' (duration: 14.432606ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:34.983532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.667376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:56:34.983635Z","caller":"traceutil/trace.go:171","msg":"trace[1701553973] linearizableReadLoop","detail":"{readStateIndex:24824; appliedIndex:24823; }","duration":"157.04522ms","start":"2026-06-09T17:56:34.826574Z","end":"2026-06-09T17:56:34.983619Z","steps":["trace[1701553973] 'read index received' (duration: 56.18643ms)","trace[1701553973] 'applied index is now lower than readState.Index' (duration: 100.857479ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:34.983785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.227892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-09T17:56:34.98382Z","caller":"traceutil/trace.go:171","msg":"trace[778263988] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24045; }","duration":"157.303684ms","start":"2026-06-09T17:56:34.826507Z","end":"2026-06-09T17:56:34.98381Z","steps":["trace[778263988] 'agreement among raft nodes before linearized reading' (duration: 157.157271ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:34.983912Z","caller":"traceutil/trace.go:171","msg":"trace[707620257] transaction","detail":"{read_only:false; response_revision:24045; number_of_response:1; }","duration":"281.895936ms","start":"2026-06-09T17:56:34.701982Z","end":"2026-06-09T17:56:34.983878Z","steps":["trace[707620257] 'process raft request' (duration: 180.742501ms)","trace[707620257] 'compare' (duration: 100.544763ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:35.42055Z","caller":"traceutil/trace.go:171","msg":"trace[1116848727] transaction","detail":"{read_only:false; response_revision:24048; number_of_response:1; }","duration":"283.09805ms","start":"2026-06-09T17:56:35.137425Z","end":"2026-06-09T17:56:35.420523Z","steps":["trace[1116848727] 'process raft request' (duration: 185.531032ms)","trace[1116848727] 'compare' (duration: 97.370663ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:35.420987Z","caller":"traceutil/trace.go:171","msg":"trace[389805037] transaction","detail":"{read_only:false; response_revision:24050; number_of_response:1; }","duration":"283.512648ms","start":"2026-06-09T17:56:35.137449Z","end":"2026-06-09T17:56:35.420962Z","steps":["trace[389805037] 'process raft request' (duration: 283.356255ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:35.421012Z","caller":"traceutil/trace.go:171","msg":"trace[1851119958] transaction","detail":"{read_only:false; response_revision:24049; number_of_response:1; }","duration":"283.547928ms","start":"2026-06-09T17:56:35.137427Z","end":"2026-06-09T17:56:35.420974Z","steps":["trace[1851119958] 'process raft request' (duration: 283.056869ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:35.445901Z","caller":"traceutil/trace.go:171","msg":"trace[981074554] transaction","detail":"{read_only:false; response_revision:24051; number_of_response:1; }","duration":"271.651981ms","start":"2026-06-09T17:56:35.174229Z","end":"2026-06-09T17:56:35.445881Z","steps":["trace[981074554] 'process raft request' (duration: 271.479748ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:35.445936Z","caller":"traceutil/trace.go:171","msg":"trace[1129010741] transaction","detail":"{read_only:false; response_revision:24052; number_of_response:1; }","duration":"247.086865ms","start":"2026-06-09T17:56:35.198825Z","end":"2026-06-09T17:56:35.445912Z","steps":["trace[1129010741] 'process raft request' (duration: 247.002413ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:40.3149Z","caller":"traceutil/trace.go:171","msg":"trace[1189611455] transaction","detail":"{read_only:false; response_revision:24083; number_of_response:1; }","duration":"105.956438ms","start":"2026-06-09T17:56:40.208907Z","end":"2026-06-09T17:56:40.314864Z","steps":["trace[1189611455] 'process raft request' (duration: 105.839826ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:40.784063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.876459ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:56:40.784309Z","caller":"traceutil/trace.go:171","msg":"trace[192930818] transaction","detail":"{read_only:false; response_revision:24084; number_of_response:1; }","duration":"342.646994ms","start":"2026-06-09T17:56:40.441645Z","end":"2026-06-09T17:56:40.784292Z","steps":["trace[192930818] 'process raft request' (duration: 176.449539ms)","trace[192930818] 'compare' (duration: 165.618474ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:56:40.784383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:40.44162Z","time spent":"342.726915ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:56:40.784556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.096974ms","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-06-09T17:56:40.784658Z","caller":"traceutil/trace.go:171","msg":"trace[847509099] linearizableReadLoop","detail":"{readStateIndex:24864; appliedIndex:24863; }","duration":"236.680666ms","start":"2026-06-09T17:56:40.547467Z","end":"2026-06-09T17:56:40.784147Z","steps":["trace[847509099] 'read index received' (duration: 70.562273ms)","trace[847509099] 'applied index is now lower than readState.Index' (duration: 166.116653ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:40.784718Z","caller":"traceutil/trace.go:171","msg":"trace[737774854] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24084; }","duration":"237.248186ms","start":"2026-06-09T17:56:40.547387Z","end":"2026-06-09T17:56:40.784635Z","steps":["trace[737774854] 'agreement among raft nodes before linearized reading' (duration: 236.995691ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:41.366859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.132984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:41.366946Z","caller":"traceutil/trace.go:171","msg":"trace[755657128] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24085; }","duration":"353.239356ms","start":"2026-06-09T17:56:41.013687Z","end":"2026-06-09T17:56:41.366926Z","steps":["trace[755657128] 'range keys from in-memory index tree' (duration: 353.004241ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:41.366932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.384789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-06-09T17:56:41.366991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:41.013656Z","time spent":"353.323227ms","remote":"127.0.0.1:40780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":152,"response count":0,"response size":27,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" "} {"level":"info","ts":"2026-06-09T17:56:41.367014Z","caller":"traceutil/trace.go:171","msg":"trace[410350600] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24085; }","duration":"362.506521ms","start":"2026-06-09T17:56:41.004486Z","end":"2026-06-09T17:56:41.366992Z","steps":["trace[410350600] 'range keys from in-memory index tree' (duration: 362.121225ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:41.367064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:41.004466Z","time spent":"362.586003ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-09T17:56:41.36761Z","caller":"traceutil/trace.go:171","msg":"trace[77665986] transaction","detail":"{read_only:false; response_revision:24086; number_of_response:1; }","duration":"158.921905ms","start":"2026-06-09T17:56:41.208664Z","end":"2026-06-09T17:56:41.367586Z","steps":["trace[77665986] 'process raft request' (duration: 156.981549ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:41.368572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.144168ms","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-06-09T17:56:41.368709Z","caller":"traceutil/trace.go:171","msg":"trace[42646631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24085; }","duration":"330.200848ms","start":"2026-06-09T17:56:41.038478Z","end":"2026-06-09T17:56:41.368679Z","steps":["trace[42646631] 'range keys from in-memory index tree' (duration: 327.821512ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:41.368782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:41.038463Z","time spent":"330.301199ms","remote":"127.0.0.1:34288","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-06-09T17:56:41.721528Z","caller":"traceutil/trace.go:171","msg":"trace[1837402021] linearizableReadLoop","detail":"{readStateIndex:24868; appliedIndex:24867; }","duration":"349.439594ms","start":"2026-06-09T17:56:41.372056Z","end":"2026-06-09T17:56:41.721496Z","steps":["trace[1837402021] 'read index received' (duration: 347.868784ms)","trace[1837402021] 'applied index is now lower than readState.Index' (duration: 1.56954ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:41.721636Z","caller":"traceutil/trace.go:171","msg":"trace[1389037971] transaction","detail":"{read_only:false; response_revision:24088; number_of_response:1; }","duration":"350.036165ms","start":"2026-06-09T17:56:41.371565Z","end":"2026-06-09T17:56:41.721601Z","steps":["trace[1389037971] 'process raft request' (duration: 348.344382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:41.721774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.693109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5845b134-31a8-4ec1-b011-4a1b44f91077\" ","response":"range_response_count:1 size:1322"} {"level":"warn","ts":"2026-06-09T17:56:41.72183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:41.371523Z","time spent":"350.195287ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:41.72186Z","caller":"traceutil/trace.go:171","msg":"trace[608207720] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5845b134-31a8-4ec1-b011-4a1b44f91077; range_end:; response_count:1; response_revision:24088; }","duration":"349.82231ms","start":"2026-06-09T17:56:41.372019Z","end":"2026-06-09T17:56:41.721842Z","steps":["trace[608207720] 'agreement among raft nodes before linearized reading' (duration: 349.571256ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:41.721911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:41.372001Z","time spent":"349.898203ms","remote":"127.0.0.1:34144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1343,"request content":"key:\"/registry/persistentvolumes/pvc-5845b134-31a8-4ec1-b011-4a1b44f91077\" "} {"level":"info","ts":"2026-06-09T17:56:41.723679Z","caller":"traceutil/trace.go:171","msg":"trace[268255054] transaction","detail":"{read_only:false; response_revision:24091; number_of_response:1; }","duration":"284.819803ms","start":"2026-06-09T17:56:41.438802Z","end":"2026-06-09T17:56:41.723621Z","steps":["trace[268255054] 'process raft request' (duration: 283.311744ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:41.724656Z","caller":"traceutil/trace.go:171","msg":"trace[889850529] transaction","detail":"{read_only:false; response_revision:24089; number_of_response:1; }","duration":"351.801729ms","start":"2026-06-09T17:56:41.372826Z","end":"2026-06-09T17:56:41.724627Z","steps":["trace[889850529] 'process raft request' (duration: 349.152068ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:41.724777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:41.372805Z","time spent":"351.88582ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:41.725374Z","caller":"traceutil/trace.go:171","msg":"trace[691516577] transaction","detail":"{read_only:false; response_revision:24090; number_of_response:1; }","duration":"286.883192ms","start":"2026-06-09T17:56:41.438463Z","end":"2026-06-09T17:56:41.725346Z","steps":["trace[691516577] 'process raft request' (duration: 283.60266ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:41.726838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.309862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:41.726929Z","caller":"traceutil/trace.go:171","msg":"trace[2030503889] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24093; }","duration":"349.455284ms","start":"2026-06-09T17:56:41.377438Z","end":"2026-06-09T17:56:41.726893Z","steps":["trace[2030503889] 'agreement among raft nodes before linearized reading' (duration: 348.958965ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:41.726985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:41.377425Z","time spent":"349.543375ms","remote":"127.0.0.1:34200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-06-09T17:56:41.7273Z","caller":"traceutil/trace.go:171","msg":"trace[742694692] transaction","detail":"{read_only:false; response_revision:24092; number_of_response:1; }","duration":"286.512935ms","start":"2026-06-09T17:56:41.440765Z","end":"2026-06-09T17:56:41.727277Z","steps":["trace[742694692] 'process raft request' (duration: 282.813365ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:41.727587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.230455ms","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-06-09T17:56:41.727651Z","caller":"traceutil/trace.go:171","msg":"trace[641964735] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24093; }","duration":"260.327147ms","start":"2026-06-09T17:56:41.467298Z","end":"2026-06-09T17:56:41.727625Z","steps":["trace[641964735] 'agreement among raft nodes before linearized reading' (duration: 260.197704ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:41.727694Z","caller":"traceutil/trace.go:171","msg":"trace[1895152960] transaction","detail":"{read_only:false; response_revision:24093; number_of_response:1; }","duration":"265.058418ms","start":"2026-06-09T17:56:41.462611Z","end":"2026-06-09T17:56:41.727669Z","steps":["trace[1895152960] 'process raft request' (duration: 263.709522ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:50.94686Z","caller":"traceutil/trace.go:171","msg":"trace[1596135527] linearizableReadLoop","detail":"{readStateIndex:24997; appliedIndex:24996; }","duration":"130.417554ms","start":"2026-06-09T17:56:50.816422Z","end":"2026-06-09T17:56:50.94684Z","steps":["trace[1596135527] 'read index received' (duration: 130.259911ms)","trace[1596135527] 'applied index is now lower than readState.Index' (duration: 156.733µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:50.946913Z","caller":"traceutil/trace.go:171","msg":"trace[1979139189] transaction","detail":"{read_only:false; response_revision:24215; number_of_response:1; }","duration":"130.912243ms","start":"2026-06-09T17:56:50.815975Z","end":"2026-06-09T17:56:50.946887Z","steps":["trace[1979139189] 'process raft request' (duration: 130.718569ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:50.947054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.607087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-057554d5-bed9-4791-901c-3e9cbf35e0fd\" ","response":"range_response_count:1 size:3644"} {"level":"info","ts":"2026-06-09T17:56:50.947082Z","caller":"traceutil/trace.go:171","msg":"trace[1526601321] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-057554d5-bed9-4791-901c-3e9cbf35e0fd; range_end:; response_count:1; response_revision:24215; }","duration":"130.683169ms","start":"2026-06-09T17:56:50.816389Z","end":"2026-06-09T17:56:50.947073Z","steps":["trace[1526601321] 'agreement among raft nodes before linearized reading' (duration: 130.556837ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:51.162081Z","caller":"traceutil/trace.go:171","msg":"trace[278562542] transaction","detail":"{read_only:false; response_revision:24216; number_of_response:1; }","duration":"210.840183ms","start":"2026-06-09T17:56:50.951218Z","end":"2026-06-09T17:56:51.162058Z","steps":["trace[278562542] 'process raft request' (duration: 116.10128ms)","trace[278562542] 'compare' (duration: 94.647412ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:51.197492Z","caller":"traceutil/trace.go:171","msg":"trace[1554474317] linearizableReadLoop","detail":"{readStateIndex:24999; appliedIndex:24997; }","duration":"198.899157ms","start":"2026-06-09T17:56:50.998561Z","end":"2026-06-09T17:56:51.19746Z","steps":["trace[1554474317] 'read index received' (duration: 68.852571ms)","trace[1554474317] 'applied index is now lower than readState.Index' (duration: 130.044986ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:51.197541Z","caller":"traceutil/trace.go:171","msg":"trace[239248154] transaction","detail":"{read_only:false; response_revision:24217; number_of_response:1; }","duration":"243.372773ms","start":"2026-06-09T17:56:50.954137Z","end":"2026-06-09T17:56:51.19751Z","steps":["trace[239248154] 'process raft request' (duration: 243.153729ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:51.19771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.357394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:56:51.197759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.171283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T17:56:51.197784Z","caller":"traceutil/trace.go:171","msg":"trace[102116312] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:24217; }","duration":"190.476157ms","start":"2026-06-09T17:56:51.007285Z","end":"2026-06-09T17:56:51.197762Z","steps":["trace[102116312] 'agreement among raft nodes before linearized reading' (duration: 190.347924ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:51.197834Z","caller":"traceutil/trace.go:171","msg":"trace[2145427291] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:24217; }","duration":"199.299786ms","start":"2026-06-09T17:56:50.998516Z","end":"2026-06-09T17:56:51.197816Z","steps":["trace[2145427291] 'agreement among raft nodes before linearized reading' (duration: 199.094601ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:51.994574Z","caller":"traceutil/trace.go:171","msg":"trace[1060463430] linearizableReadLoop","detail":"{readStateIndex:25002; appliedIndex:25001; }","duration":"224.570256ms","start":"2026-06-09T17:56:51.769982Z","end":"2026-06-09T17:56:51.994552Z","steps":["trace[1060463430] 'read index received' (duration: 224.464574ms)","trace[1060463430] 'applied index is now lower than readState.Index' (duration: 104.902µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:51.994663Z","caller":"traceutil/trace.go:171","msg":"trace[1288255602] transaction","detail":"{read_only:false; response_revision:24220; number_of_response:1; }","duration":"224.849501ms","start":"2026-06-09T17:56:51.769805Z","end":"2026-06-09T17:56:51.994655Z","steps":["trace[1288255602] 'process raft request' (duration: 224.600466ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:51.994799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.80577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:51.99484Z","caller":"traceutil/trace.go:171","msg":"trace[103481390] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24220; }","duration":"224.882162ms","start":"2026-06-09T17:56:51.769947Z","end":"2026-06-09T17:56:51.994829Z","steps":["trace[103481390] 'agreement among raft nodes before linearized reading' (duration: 224.809681ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:52.015756Z","caller":"traceutil/trace.go:171","msg":"trace[364232450] transaction","detail":"{read_only:false; response_revision:24222; number_of_response:1; }","duration":"242.173091ms","start":"2026-06-09T17:56:51.773563Z","end":"2026-06-09T17:56:52.015737Z","steps":["trace[364232450] 'process raft request' (duration: 242.08696ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:52.015804Z","caller":"traceutil/trace.go:171","msg":"trace[722214151] transaction","detail":"{read_only:false; response_revision:24221; number_of_response:1; }","duration":"244.154419ms","start":"2026-06-09T17:56:51.771613Z","end":"2026-06-09T17:56:52.015768Z","steps":["trace[722214151] 'process raft request' (duration: 243.921864ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:52.015984Z","caller":"traceutil/trace.go:171","msg":"trace[1746507814] transaction","detail":"{read_only:false; response_revision:24223; number_of_response:1; }","duration":"238.419509ms","start":"2026-06-09T17:56:51.777553Z","end":"2026-06-09T17:56:52.015972Z","steps":["trace[1746507814] 'process raft request' (duration: 238.146194ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:52.016198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.22512ms","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-06-09T17:56:52.016218Z","caller":"traceutil/trace.go:171","msg":"trace[795638612] transaction","detail":"{read_only:false; response_revision:24224; number_of_response:1; }","duration":"200.540528ms","start":"2026-06-09T17:56:51.815666Z","end":"2026-06-09T17:56:52.016207Z","steps":["trace[795638612] 'process raft request' (duration: 200.256813ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:52.016247Z","caller":"traceutil/trace.go:171","msg":"trace[1680104820] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24224; }","duration":"234.327032ms","start":"2026-06-09T17:56:51.781907Z","end":"2026-06-09T17:56:52.016234Z","steps":["trace[1680104820] 'agreement among raft nodes before linearized reading' (duration: 234.180309ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:52.016241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.872113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-09T17:56:52.016279Z","caller":"traceutil/trace.go:171","msg":"trace[139238052] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24224; }","duration":"232.942655ms","start":"2026-06-09T17:56:51.783326Z","end":"2026-06-09T17:56:52.016269Z","steps":["trace[139238052] 'agreement among raft nodes before linearized reading' (duration: 232.759702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:52.016372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.586652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:56:52.01645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.262807ms","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-06-09T17:56:52.01647Z","caller":"traceutil/trace.go:171","msg":"trace[1968378548] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:24224; }","duration":"227.731126ms","start":"2026-06-09T17:56:51.788718Z","end":"2026-06-09T17:56:52.016449Z","steps":["trace[1968378548] 'agreement among raft nodes before linearized reading' (duration: 227.531602ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:52.016498Z","caller":"traceutil/trace.go:171","msg":"trace[2034069203] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24224; }","duration":"174.291938ms","start":"2026-06-09T17:56:51.842178Z","end":"2026-06-09T17:56:52.01647Z","steps":["trace[2034069203] 'agreement among raft nodes before linearized reading' (duration: 174.220427ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:52.016559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.895335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-09T17:56:52.016614Z","caller":"traceutil/trace.go:171","msg":"trace[1085676215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24224; }","duration":"231.970957ms","start":"2026-06-09T17:56:51.784633Z","end":"2026-06-09T17:56:52.016604Z","steps":["trace[1085676215] 'agreement among raft nodes before linearized reading' (duration: 231.834794ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:52.016706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.012364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-057554d5-bed9-4791-901c-3e9cbf35e0fd\" ","response":"range_response_count:1 size:3761"} {"level":"info","ts":"2026-06-09T17:56:52.016745Z","caller":"traceutil/trace.go:171","msg":"trace[642296140] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-057554d5-bed9-4791-901c-3e9cbf35e0fd; range_end:; response_count:1; response_revision:24224; }","duration":"204.076636ms","start":"2026-06-09T17:56:51.812659Z","end":"2026-06-09T17:56:52.016735Z","steps":["trace[642296140] 'agreement among raft nodes before linearized reading' (duration: 203.995784ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:52.016859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.005799ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3236"} {"level":"info","ts":"2026-06-09T17:56:52.017343Z","caller":"traceutil/trace.go:171","msg":"trace[21177690] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24224; }","duration":"219.54328ms","start":"2026-06-09T17:56:51.797779Z","end":"2026-06-09T17:56:52.017322Z","steps":["trace[21177690] 'agreement among raft nodes before linearized reading' (duration: 218.299547ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:52.645536Z","caller":"traceutil/trace.go:171","msg":"trace[1668018081] transaction","detail":"{read_only:false; response_revision:24226; number_of_response:1; }","duration":"624.4879ms","start":"2026-06-09T17:56:52.021013Z","end":"2026-06-09T17:56:52.645501Z","steps":["trace[1668018081] 'process raft request' (duration: 624.234335ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:52.645554Z","caller":"traceutil/trace.go:171","msg":"trace[731368034] transaction","detail":"{read_only:false; response_revision:24227; number_of_response:1; }","duration":"623.396019ms","start":"2026-06-09T17:56:52.022131Z","end":"2026-06-09T17:56:52.645527Z","steps":["trace[731368034] 'process raft request' (duration: 623.294617ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:52.645701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:52.020992Z","time spent":"624.620932ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:56:52.645747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:52.022112Z","time spent":"623.534731ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:52.646301Z","caller":"traceutil/trace.go:171","msg":"trace[303003536] linearizableReadLoop","detail":"{readStateIndex:25010; appliedIndex:25006; }","duration":"622.764768ms","start":"2026-06-09T17:56:52.023522Z","end":"2026-06-09T17:56:52.646287Z","steps":["trace[303003536] 'read index received' (duration: 27.536404ms)","trace[303003536] 'applied index is now lower than readState.Index' (duration: 595.227334ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:52.646474Z","caller":"traceutil/trace.go:171","msg":"trace[564632056] transaction","detail":"{read_only:false; response_revision:24228; number_of_response:1; }","duration":"624.099203ms","start":"2026-06-09T17:56:52.022333Z","end":"2026-06-09T17:56:52.646432Z","steps":["trace[564632056] 'process raft request' (duration: 623.136264ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:52.64667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:52.022321Z","time spent":"624.248415ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:56:52.646708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.145025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-06-09T17:56:52.646815Z","caller":"traceutil/trace.go:171","msg":"trace[1111396745] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24228; }","duration":"623.283667ms","start":"2026-06-09T17:56:52.023506Z","end":"2026-06-09T17:56:52.646789Z","steps":["trace[1111396745] 'agreement among raft nodes before linearized reading' (duration: 622.939111ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:52.646892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:52.023499Z","time spent":"623.377649ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1176,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"info","ts":"2026-06-09T17:56:52.675634Z","caller":"traceutil/trace.go:171","msg":"trace[1176347536] transaction","detail":"{read_only:false; response_revision:24229; number_of_response:1; }","duration":"320.36707ms","start":"2026-06-09T17:56:52.355232Z","end":"2026-06-09T17:56:52.675599Z","steps":["trace[1176347536] 'process raft request' (duration: 319.971342ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:52.675749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.61918ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:56:52.675797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:52.355207Z","time spent":"320.508012ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:56:52.675823Z","caller":"traceutil/trace.go:171","msg":"trace[863913703] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24229; }","duration":"294.697951ms","start":"2026-06-09T17:56:52.381104Z","end":"2026-06-09T17:56:52.675802Z","steps":["trace[863913703] 'agreement among raft nodes before linearized reading' (duration: 294.572409ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:52.675927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.066928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:56:52.675994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.11726ms","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-06-09T17:56:52.676048Z","caller":"traceutil/trace.go:171","msg":"trace[660702342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24229; }","duration":"304.217852ms","start":"2026-06-09T17:56:52.371816Z","end":"2026-06-09T17:56:52.676034Z","steps":["trace[660702342] 'agreement among raft nodes before linearized reading' (duration: 304.055909ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:52.676086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:52.371796Z","time spent":"304.281512ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T17:56:52.676096Z","caller":"traceutil/trace.go:171","msg":"trace[2031142656] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:24229; }","duration":"545.255441ms","start":"2026-06-09T17:56:52.130764Z","end":"2026-06-09T17:56:52.676019Z","steps":["trace[2031142656] 'agreement among raft nodes before linearized reading' (duration: 544.876514ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:52.676183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.997033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:56:52.676239Z","caller":"traceutil/trace.go:171","msg":"trace[2074501441] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24229; }","duration":"652.094005ms","start":"2026-06-09T17:56:52.02413Z","end":"2026-06-09T17:56:52.676224Z","steps":["trace[2074501441] 'agreement among raft nodes before linearized reading' (duration: 651.990273ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:52.676274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:52.02411Z","time spent":"652.156026ms","remote":"127.0.0.1:34302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"warn","ts":"2026-06-09T17:56:52.676388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.660691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T17:56:52.676438Z","caller":"traceutil/trace.go:171","msg":"trace[558424432] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:24229; }","duration":"595.738692ms","start":"2026-06-09T17:56:52.080687Z","end":"2026-06-09T17:56:52.676425Z","steps":["trace[558424432] 'agreement among raft nodes before linearized reading' (duration: 595.64642ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:52.676353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:52.130737Z","time spent":"545.548456ms","remote":"127.0.0.1:34844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:56:52.676457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.917337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T17:56:52.676581Z","caller":"traceutil/trace.go:171","msg":"trace[1458477321] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:24229; }","duration":"553.696591ms","start":"2026-06-09T17:56:52.12286Z","end":"2026-06-09T17:56:52.676556Z","steps":["trace[1458477321] 'agreement among raft nodes before linearized reading' (duration: 552.826375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:52.676477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:52.080673Z","time spent":"595.795973ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":19,"response size":29,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:56:52.676656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:52.122843Z","time spent":"553.786053ms","remote":"127.0.0.1:34250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":78,"response size":29,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:56:54.198469Z","caller":"traceutil/trace.go:171","msg":"trace[435517031] transaction","detail":"{read_only:false; response_revision:24239; number_of_response:1; }","duration":"176.572952ms","start":"2026-06-09T17:56:54.021865Z","end":"2026-06-09T17:56:54.198438Z","steps":["trace[435517031] 'process raft request' (duration: 126.640622ms)","trace[435517031] 'compare' (duration: 49.690966ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:54.198538Z","caller":"traceutil/trace.go:171","msg":"trace[1381706138] linearizableReadLoop","detail":"{readStateIndex:25024; appliedIndex:25019; }","duration":"173.172818ms","start":"2026-06-09T17:56:54.025342Z","end":"2026-06-09T17:56:54.198515Z","steps":["trace[1381706138] 'read index received' (duration: 41.620403ms)","trace[1381706138] 'applied index is now lower than readState.Index' (duration: 131.550835ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:54.198602Z","caller":"traceutil/trace.go:171","msg":"trace[411116189] transaction","detail":"{read_only:false; response_revision:24242; number_of_response:1; }","duration":"175.549433ms","start":"2026-06-09T17:56:54.023029Z","end":"2026-06-09T17:56:54.198578Z","steps":["trace[411116189] 'process raft request' (duration: 175.438461ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:54.1986Z","caller":"traceutil/trace.go:171","msg":"trace[1115242050] transaction","detail":"{read_only:false; response_revision:24241; number_of_response:1; }","duration":"176.42473ms","start":"2026-06-09T17:56:54.022138Z","end":"2026-06-09T17:56:54.198563Z","steps":["trace[1115242050] 'process raft request' (duration: 176.275127ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:54.198703Z","caller":"traceutil/trace.go:171","msg":"trace[1395936736] transaction","detail":"{read_only:false; response_revision:24240; number_of_response:1; }","duration":"176.750995ms","start":"2026-06-09T17:56:54.021927Z","end":"2026-06-09T17:56:54.198678Z","steps":["trace[1395936736] 'process raft request' (duration: 176.419399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:54.198837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.452653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-057554d5-bed9-4791-901c-3e9cbf35e0fd\" ","response":"range_response_count:1 size:3947"} {"level":"info","ts":"2026-06-09T17:56:54.198927Z","caller":"traceutil/trace.go:171","msg":"trace[2028640972] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-057554d5-bed9-4791-901c-3e9cbf35e0fd; range_end:; response_count:1; response_revision:24242; }","duration":"173.605105ms","start":"2026-06-09T17:56:54.025301Z","end":"2026-06-09T17:56:54.198906Z","steps":["trace[2028640972] 'agreement among raft nodes before linearized reading' (duration: 173.31245ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:54.200617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.451424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-09T17:56:54.200663Z","caller":"traceutil/trace.go:171","msg":"trace[1774528468] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24243; }","duration":"161.556276ms","start":"2026-06-09T17:56:54.039095Z","end":"2026-06-09T17:56:54.200651Z","steps":["trace[1774528468] 'agreement among raft nodes before linearized reading' (duration: 161.397723ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:54.200965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.372407ms","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-06-09T17:56:54.200993Z","caller":"traceutil/trace.go:171","msg":"trace[705333592] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24243; }","duration":"146.424189ms","start":"2026-06-09T17:56:54.054562Z","end":"2026-06-09T17:56:54.200986Z","steps":["trace[705333592] 'agreement among raft nodes before linearized reading' (duration: 146.356128ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:57.184826Z","caller":"traceutil/trace.go:171","msg":"trace[445645897] transaction","detail":"{read_only:false; response_revision:24292; number_of_response:1; }","duration":"233.852533ms","start":"2026-06-09T17:56:56.950932Z","end":"2026-06-09T17:56:57.184785Z","steps":["trace[445645897] 'process raft request' (duration: 189.03661ms)","trace[445645897] 'compare' (duration: 44.451367ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:57.184867Z","caller":"traceutil/trace.go:171","msg":"trace[888389518] transaction","detail":"{read_only:false; response_revision:24293; number_of_response:1; }","duration":"233.802352ms","start":"2026-06-09T17:56:56.951049Z","end":"2026-06-09T17:56:57.184851Z","steps":["trace[888389518] 'process raft request' (duration: 233.552797ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:57.185065Z","caller":"traceutil/trace.go:171","msg":"trace[1189678910] transaction","detail":"{read_only:false; response_revision:24294; number_of_response:1; }","duration":"233.495806ms","start":"2026-06-09T17:56:56.951542Z","end":"2026-06-09T17:56:57.185038Z","steps":["trace[1189678910] 'process raft request' (duration: 233.259452ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:57.452107Z","caller":"traceutil/trace.go:171","msg":"trace[319208617] transaction","detail":"{read_only:false; response_revision:24295; number_of_response:1; }","duration":"488.201035ms","start":"2026-06-09T17:56:56.963856Z","end":"2026-06-09T17:56:57.452057Z","steps":["trace[319208617] 'process raft request' (duration: 422.005065ms)","trace[319208617] 'compare' (duration: 65.951815ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:57.45217Z","caller":"traceutil/trace.go:171","msg":"trace[626349218] transaction","detail":"{read_only:false; response_revision:24296; number_of_response:1; }","duration":"387.229053ms","start":"2026-06-09T17:56:57.064915Z","end":"2026-06-09T17:56:57.452144Z","steps":["trace[626349218] 'process raft request' (duration: 387.039929ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:57.452302Z","caller":"traceutil/trace.go:171","msg":"trace[1795347351] linearizableReadLoop","detail":"{readStateIndex:25078; appliedIndex:25074; }","duration":"471.66037ms","start":"2026-06-09T17:56:56.98063Z","end":"2026-06-09T17:56:57.45229Z","steps":["trace[1795347351] 'read index received' (duration: 159.341414ms)","trace[1795347351] 'applied index is now lower than readState.Index' (duration: 312.317656ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:56:57.452329Z","caller":"traceutil/trace.go:171","msg":"trace[1447915584] transaction","detail":"{read_only:false; response_revision:24297; number_of_response:1; }","duration":"257.539183ms","start":"2026-06-09T17:56:57.194749Z","end":"2026-06-09T17:56:57.452288Z","steps":["trace[1447915584] 'process raft request' (duration: 257.326969ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:57.452427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:57.064898Z","time spent":"387.339135ms","remote":"127.0.0.1:54494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:56:57.452497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.446865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:561"} {"level":"warn","ts":"2026-06-09T17:56:57.45252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.882884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-06-09T17:56:57.452575Z","caller":"traceutil/trace.go:171","msg":"trace[467567312] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24297; }","duration":"400.499916ms","start":"2026-06-09T17:56:57.052034Z","end":"2026-06-09T17:56:57.452534Z","steps":["trace[467567312] 'agreement among raft nodes before linearized reading' (duration: 400.349573ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:57.452583Z","caller":"traceutil/trace.go:171","msg":"trace[1659456556] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24297; }","duration":"471.997666ms","start":"2026-06-09T17:56:56.980568Z","end":"2026-06-09T17:56:57.452566Z","steps":["trace[1659456556] 'agreement among raft nodes before linearized reading' (duration: 471.787502ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:56:57.452645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:56.980544Z","time spent":"472.094368ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1881,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" "} {"level":"warn","ts":"2026-06-09T17:56:57.452647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:57.051979Z","time spent":"400.655529ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-06-09T17:56:57.452342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:56:56.963827Z","time spent":"488.387809ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:56:57.452871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.082778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:56:57.45291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.089644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-09T17:56:57.452958Z","caller":"traceutil/trace.go:171","msg":"trace[1891190413] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24297; }","duration":"268.173905ms","start":"2026-06-09T17:56:57.184769Z","end":"2026-06-09T17:56:57.452943Z","steps":["trace[1891190413] 'agreement among raft nodes before linearized reading' (duration: 268.013652ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:57.452962Z","caller":"traceutil/trace.go:171","msg":"trace[957595379] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:24297; }","duration":"159.22303ms","start":"2026-06-09T17:56:57.293717Z","end":"2026-06-09T17:56:57.45294Z","steps":["trace[957595379] 'agreement among raft nodes before linearized reading' (duration: 159.063637ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:57.580595Z","caller":"traceutil/trace.go:171","msg":"trace[552470940] transaction","detail":"{read_only:false; response_revision:24298; number_of_response:1; }","duration":"123.39644ms","start":"2026-06-09T17:56:57.457176Z","end":"2026-06-09T17:56:57.580572Z","steps":["trace[552470940] 'process raft request' (duration: 123.236837ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:57.582897Z","caller":"traceutil/trace.go:171","msg":"trace[711155769] transaction","detail":"{read_only:false; response_revision:24299; number_of_response:1; }","duration":"124.697965ms","start":"2026-06-09T17:56:57.458184Z","end":"2026-06-09T17:56:57.582882Z","steps":["trace[711155769] 'process raft request' (duration: 124.44734ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:56:57.583161Z","caller":"traceutil/trace.go:171","msg":"trace[248494185] transaction","detail":"{read_only:false; response_revision:24300; number_of_response:1; }","duration":"122.791838ms","start":"2026-06-09T17:56:57.460358Z","end":"2026-06-09T17:56:57.58315Z","steps":["trace[248494185] 'process raft request' (duration: 122.37754ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:22.833674Z","caller":"traceutil/trace.go:171","msg":"trace[1158161823] transaction","detail":"{read_only:false; response_revision:24517; number_of_response:1; }","duration":"289.479512ms","start":"2026-06-09T17:57:22.544174Z","end":"2026-06-09T17:57:22.833653Z","steps":["trace[1158161823] 'process raft request' (duration: 289.34303ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:22.993941Z","caller":"traceutil/trace.go:171","msg":"trace[1495311164] linearizableReadLoop","detail":"{readStateIndex:25307; appliedIndex:25305; }","duration":"244.638669ms","start":"2026-06-09T17:57:22.749269Z","end":"2026-06-09T17:57:22.993907Z","steps":["trace[1495311164] 'read index received' (duration: 84.242945ms)","trace[1495311164] 'applied index is now lower than readState.Index' (duration: 160.394494ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:22.994022Z","caller":"traceutil/trace.go:171","msg":"trace[1512249552] transaction","detail":"{read_only:false; response_revision:24518; number_of_response:1; }","duration":"277.130357ms","start":"2026-06-09T17:57:22.716836Z","end":"2026-06-09T17:57:22.993967Z","steps":["trace[1512249552] 'process raft request' (duration: 198.658132ms)","trace[1512249552] 'compare' (duration: 78.176049ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:22.994118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.598338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:22.994164Z","caller":"traceutil/trace.go:171","msg":"trace[1439622500] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24518; }","duration":"212.6902ms","start":"2026-06-09T17:57:22.781461Z","end":"2026-06-09T17:57:22.994151Z","steps":["trace[1439622500] 'agreement among raft nodes before linearized reading' (duration: 212.593398ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:22.9942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.979228ms","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-06-09T17:57:22.99424Z","caller":"traceutil/trace.go:171","msg":"trace[1720689622] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24518; }","duration":"190.043559ms","start":"2026-06-09T17:57:22.804187Z","end":"2026-06-09T17:57:22.994231Z","steps":["trace[1720689622] 'agreement among raft nodes before linearized reading' (duration: 189.916326ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:22.994293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.34826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-09T17:57:22.994358Z","caller":"traceutil/trace.go:171","msg":"trace[566682774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24518; }","duration":"195.427682ms","start":"2026-06-09T17:57:22.798911Z","end":"2026-06-09T17:57:22.994338Z","steps":["trace[566682774] 'agreement among raft nodes before linearized reading' (duration: 195.286489ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:22.994383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.991097ms","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":"warn","ts":"2026-06-09T17:57:22.994402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.125308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3235"} {"level":"info","ts":"2026-06-09T17:57:22.994417Z","caller":"traceutil/trace.go:171","msg":"trace[1484640679] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24518; }","duration":"201.084269ms","start":"2026-06-09T17:57:22.793325Z","end":"2026-06-09T17:57:22.994409Z","steps":["trace[1484640679] 'agreement among raft nodes before linearized reading' (duration: 201.000807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:22.994281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.316018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-09T17:57:22.994446Z","caller":"traceutil/trace.go:171","msg":"trace[7059511] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:24518; }","duration":"245.191999ms","start":"2026-06-09T17:57:22.74924Z","end":"2026-06-09T17:57:22.994432Z","steps":["trace[7059511] 'agreement among raft nodes before linearized reading' (duration: 245.042977ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:22.994488Z","caller":"traceutil/trace.go:171","msg":"trace[1284983772] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24518; }","duration":"197.555832ms","start":"2026-06-09T17:57:22.796915Z","end":"2026-06-09T17:57:22.994471Z","steps":["trace[1284983772] 'agreement among raft nodes before linearized reading' (duration: 197.208436ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:23.248491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.8994ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:57:23.248639Z","caller":"traceutil/trace.go:171","msg":"trace[1149128964] transaction","detail":"{read_only:false; response_revision:24519; number_of_response:1; }","duration":"251.139772ms","start":"2026-06-09T17:57:22.997469Z","end":"2026-06-09T17:57:23.248608Z","steps":["trace[1149128964] 'process raft request' (duration: 97.023088ms)","trace[1149128964] 'compare' (duration: 153.690636ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:23.327957Z","caller":"traceutil/trace.go:171","msg":"trace[1713070500] linearizableReadLoop","detail":"{readStateIndex:25311; appliedIndex:25307; }","duration":"327.452015ms","start":"2026-06-09T17:57:23.000482Z","end":"2026-06-09T17:57:23.327934Z","steps":["trace[1713070500] 'read index received' (duration: 93.99194ms)","trace[1713070500] 'applied index is now lower than readState.Index' (duration: 233.459195ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:23.32825Z","caller":"traceutil/trace.go:171","msg":"trace[1318019149] transaction","detail":"{read_only:false; response_revision:24520; number_of_response:1; }","duration":"329.426262ms","start":"2026-06-09T17:57:22.998812Z","end":"2026-06-09T17:57:23.328238Z","steps":["trace[1318019149] 'process raft request' (duration: 328.861601ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:23.328354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:22.998805Z","time spent":"329.478912ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:23.328526Z","caller":"traceutil/trace.go:171","msg":"trace[201663801] transaction","detail":"{read_only:false; response_revision:24521; number_of_response:1; }","duration":"329.547244ms","start":"2026-06-09T17:57:22.998967Z","end":"2026-06-09T17:57:23.328514Z","steps":["trace[201663801] 'process raft request' (duration: 328.878971ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:23.328587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:22.998958Z","time spent":"329.597155ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:23.32871Z","caller":"traceutil/trace.go:171","msg":"trace[967866385] transaction","detail":"{read_only:false; response_revision:24522; number_of_response:1; }","duration":"329.604055ms","start":"2026-06-09T17:57:22.999096Z","end":"2026-06-09T17:57:23.3287Z","steps":["trace[967866385] 'process raft request' (duration: 328.79682ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:23.328763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:22.999057Z","time spent":"329.679907ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:57:23.328982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.528015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1155"} {"level":"info","ts":"2026-06-09T17:57:23.329019Z","caller":"traceutil/trace.go:171","msg":"trace[1103512664] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:24522; }","duration":"328.582456ms","start":"2026-06-09T17:57:23.000427Z","end":"2026-06-09T17:57:23.32901Z","steps":["trace[1103512664] 'agreement among raft nodes before linearized reading' (duration: 328.455914ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:23.329052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:23.000413Z","time spent":"328.632587ms","remote":"127.0.0.1:34090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":1176,"request content":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" "} {"level":"warn","ts":"2026-06-09T17:57:23.329217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.76632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:23.329249Z","caller":"traceutil/trace.go:171","msg":"trace[1487503596] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24522; }","duration":"328.82659ms","start":"2026-06-09T17:57:23.000412Z","end":"2026-06-09T17:57:23.329239Z","steps":["trace[1487503596] 'agreement among raft nodes before linearized reading' (duration: 328.775189ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:23.329275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:23.00038Z","time spent":"328.888883ms","remote":"127.0.0.1:34200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-06-09T17:57:23.5432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.738878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:23.5433Z","caller":"traceutil/trace.go:171","msg":"trace[1942787836] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24522; }","duration":"162.84867ms","start":"2026-06-09T17:57:23.380428Z","end":"2026-06-09T17:57:23.543277Z","steps":["trace[1942787836] 'range keys from in-memory index tree' (duration: 162.659487ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:23.543352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.423657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-manila-server\" ","response":"range_response_count:1 size:9545"} {"level":"info","ts":"2026-06-09T17:57:23.543421Z","caller":"traceutil/trace.go:171","msg":"trace[1871503463] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-manila-server; range_end:; response_count:1; response_revision:24522; }","duration":"188.544049ms","start":"2026-06-09T17:57:23.354861Z","end":"2026-06-09T17:57:23.543405Z","steps":["trace[1871503463] 'range keys from in-memory index tree' (duration: 187.936458ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:23.78057Z","caller":"traceutil/trace.go:171","msg":"trace[1222921780] transaction","detail":"{read_only:false; response_revision:24524; number_of_response:1; }","duration":"103.341897ms","start":"2026-06-09T17:57:23.677128Z","end":"2026-06-09T17:57:23.78047Z","steps":["trace[1222921780] 'process raft request' (duration: 54.260302ms)","trace[1222921780] 'compare' (duration: 48.942503ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:24.448615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.779312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-06-09T17:57:24.448708Z","caller":"traceutil/trace.go:171","msg":"trace[1451599085] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:24526; }","duration":"117.934136ms","start":"2026-06-09T17:57:24.330755Z","end":"2026-06-09T17:57:24.448689Z","steps":["trace[1451599085] 'count revisions from in-memory index tree' (duration: 117.685971ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:24.530387Z","caller":"traceutil/trace.go:171","msg":"trace[1593102500] transaction","detail":"{read_only:false; response_revision:24528; number_of_response:1; }","duration":"159.545927ms","start":"2026-06-09T17:57:24.370819Z","end":"2026-06-09T17:57:24.530365Z","steps":["trace[1593102500] 'process raft request' (duration: 159.399615ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:24.596007Z","caller":"traceutil/trace.go:171","msg":"trace[1444494081] transaction","detail":"{read_only:false; response_revision:24529; number_of_response:1; }","duration":"224.552565ms","start":"2026-06-09T17:57:24.371426Z","end":"2026-06-09T17:57:24.595979Z","steps":["trace[1444494081] 'process raft request' (duration: 223.23573ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:24.596133Z","caller":"traceutil/trace.go:171","msg":"trace[833675981] transaction","detail":"{read_only:false; response_revision:24530; number_of_response:1; }","duration":"224.619027ms","start":"2026-06-09T17:57:24.371492Z","end":"2026-06-09T17:57:24.596111Z","steps":["trace[833675981] 'process raft request' (duration: 224.428823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:24.767735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.675319ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-06-09T17:57:24.767826Z","caller":"traceutil/trace.go:171","msg":"trace[1372717827] linearizableReadLoop","detail":"{readStateIndex:25319; appliedIndex:25317; }","duration":"383.585144ms","start":"2026-06-09T17:57:24.384225Z","end":"2026-06-09T17:57:24.76781Z","steps":["trace[1372717827] 'read index received' (duration: 146.200194ms)","trace[1372717827] 'applied index is now lower than readState.Index' (duration: 237.38378ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:24.767993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.757787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" ","response":"range_response_count:1 size:1634"} {"level":"info","ts":"2026-06-09T17:57:24.768047Z","caller":"traceutil/trace.go:171","msg":"trace[1062007299] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0; range_end:; response_count:1; response_revision:24530; }","duration":"383.844789ms","start":"2026-06-09T17:57:24.384189Z","end":"2026-06-09T17:57:24.768034Z","steps":["trace[1062007299] 'agreement among raft nodes before linearized reading' (duration: 383.722587ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:24.768071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:24.384181Z","time spent":"383.885659ms","remote":"127.0.0.1:34148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1655,"request content":"key:\"/registry/persistentvolumeclaims/openstack/valkey-data-valkey-node-0\" "} {"level":"info","ts":"2026-06-09T17:57:24.78404Z","caller":"traceutil/trace.go:171","msg":"trace[157330054] transaction","detail":"{read_only:false; response_revision:24531; number_of_response:1; }","duration":"111.093145ms","start":"2026-06-09T17:57:24.672925Z","end":"2026-06-09T17:57:24.784018Z","steps":["trace[157330054] 'process raft request' (duration: 110.79929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:24.784278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.863585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/trunks/\" range_end:\"/registry/openstack.k-orc.cloud/trunks0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:24.78434Z","caller":"traceutil/trace.go:171","msg":"trace[1483794503] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/trunks/; range_end:/registry/openstack.k-orc.cloud/trunks0; response_count:0; response_revision:24531; }","duration":"211.974776ms","start":"2026-06-09T17:57:24.572346Z","end":"2026-06-09T17:57:24.78432Z","steps":["trace[1483794503] 'agreement among raft nodes before linearized reading' (duration: 211.884244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:24.784557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.612006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T17:57:24.784601Z","caller":"traceutil/trace.go:171","msg":"trace[1212174336] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:24531; }","duration":"214.700228ms","start":"2026-06-09T17:57:24.56989Z","end":"2026-06-09T17:57:24.78459Z","steps":["trace[1212174336] 'agreement among raft nodes before linearized reading' (duration: 214.633057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:24.784825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.978002ms","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-06-09T17:57:24.784861Z","caller":"traceutil/trace.go:171","msg":"trace[510686597] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24531; }","duration":"372.033914ms","start":"2026-06-09T17:57:24.412818Z","end":"2026-06-09T17:57:24.784852Z","steps":["trace[510686597] 'agreement among raft nodes before linearized reading' (duration: 371.916832ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:24.784888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:24.412807Z","time spent":"372.073235ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T17:57:25.117567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.09184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:57:25.117661Z","caller":"traceutil/trace.go:171","msg":"trace[1790646884] linearizableReadLoop","detail":"{readStateIndex:25322; appliedIndex:25321; }","duration":"300.869128ms","start":"2026-06-09T17:57:24.816779Z","end":"2026-06-09T17:57:25.117648Z","steps":["trace[1790646884] 'read index received' (duration: 100.546504ms)","trace[1790646884] 'applied index is now lower than readState.Index' (duration: 200.321764ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:25.117793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.013021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"info","ts":"2026-06-09T17:57:25.117814Z","caller":"traceutil/trace.go:171","msg":"trace[1026549188] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24532; }","duration":"301.057912ms","start":"2026-06-09T17:57:24.816746Z","end":"2026-06-09T17:57:25.117804Z","steps":["trace[1026549188] 'agreement among raft nodes before linearized reading' (duration: 300.93592ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:25.11784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:24.816733Z","time spent":"301.099344ms","remote":"127.0.0.1:34190","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-06-09T17:57:25.118049Z","caller":"traceutil/trace.go:171","msg":"trace[94880985] transaction","detail":"{read_only:false; response_revision:24532; number_of_response:1; }","duration":"329.297901ms","start":"2026-06-09T17:57:24.788741Z","end":"2026-06-09T17:57:25.118039Z","steps":["trace[94880985] 'process raft request' (duration: 128.65397ms)","trace[94880985] 'compare' (duration: 199.993799ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:25.118088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:24.788728Z","time spent":"329.338841ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:25.474037Z","caller":"traceutil/trace.go:171","msg":"trace[942774521] linearizableReadLoop","detail":"{readStateIndex:25323; appliedIndex:25322; }","duration":"141.160517ms","start":"2026-06-09T17:57:25.332849Z","end":"2026-06-09T17:57:25.474009Z","steps":["trace[942774521] 'read index received' (duration: 140.882292ms)","trace[942774521] 'applied index is now lower than readState.Index' (duration: 277.365µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:25.474255Z","caller":"traceutil/trace.go:171","msg":"trace[1687871465] transaction","detail":"{read_only:false; response_revision:24533; number_of_response:1; }","duration":"201.308073ms","start":"2026-06-09T17:57:25.272904Z","end":"2026-06-09T17:57:25.474212Z","steps":["trace[1687871465] 'process raft request' (duration: 200.929676ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:25.474395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.232238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-06-09T17:57:25.474438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.80313ms","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-06-09T17:57:25.474458Z","caller":"traceutil/trace.go:171","msg":"trace[1431215386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24533; }","duration":"141.31403ms","start":"2026-06-09T17:57:25.333127Z","end":"2026-06-09T17:57:25.474441Z","steps":["trace[1431215386] 'agreement among raft nodes before linearized reading' (duration: 141.177688ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:25.474473Z","caller":"traceutil/trace.go:171","msg":"trace[393043126] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24533; }","duration":"140.849942ms","start":"2026-06-09T17:57:25.333609Z","end":"2026-06-09T17:57:25.474459Z","steps":["trace[393043126] 'agreement among raft nodes before linearized reading' (duration: 140.76315ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:25.474335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.464453ms","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-06-09T17:57:25.474634Z","caller":"traceutil/trace.go:171","msg":"trace[1386366129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24533; }","duration":"141.780339ms","start":"2026-06-09T17:57:25.332802Z","end":"2026-06-09T17:57:25.474582Z","steps":["trace[1386366129] 'agreement among raft nodes before linearized reading' (duration: 141.398412ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:25.677364Z","caller":"traceutil/trace.go:171","msg":"trace[342860291] transaction","detail":"{read_only:false; response_revision:24536; number_of_response:1; }","duration":"196.285917ms","start":"2026-06-09T17:57:25.481055Z","end":"2026-06-09T17:57:25.677341Z","steps":["trace[342860291] 'process raft request' (duration: 196.232536ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:25.677565Z","caller":"traceutil/trace.go:171","msg":"trace[731677789] transaction","detail":"{read_only:false; response_revision:24535; number_of_response:1; }","duration":"197.200645ms","start":"2026-06-09T17:57:25.480349Z","end":"2026-06-09T17:57:25.67755Z","steps":["trace[731677789] 'process raft request' (duration: 131.531104ms)","trace[731677789] 'compare' (duration: 65.07702ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:25.71824Z","caller":"traceutil/trace.go:171","msg":"trace[16855803] transaction","detail":"{read_only:false; response_revision:24537; number_of_response:1; }","duration":"109.641198ms","start":"2026-06-09T17:57:25.608577Z","end":"2026-06-09T17:57:25.718218Z","steps":["trace[16855803] 'process raft request' (duration: 109.518815ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:26.034052Z","caller":"traceutil/trace.go:171","msg":"trace[638897491] linearizableReadLoop","detail":"{readStateIndex:25328; appliedIndex:25327; }","duration":"172.840691ms","start":"2026-06-09T17:57:25.861187Z","end":"2026-06-09T17:57:26.034028Z","steps":["trace[638897491] 'read index received' (duration: 172.646367ms)","trace[638897491] 'applied index is now lower than readState.Index' (duration: 190.474µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:26.034153Z","caller":"traceutil/trace.go:171","msg":"trace[868929448] transaction","detail":"{read_only:false; response_revision:24538; number_of_response:1; }","duration":"248.816978ms","start":"2026-06-09T17:57:25.785303Z","end":"2026-06-09T17:57:26.03412Z","steps":["trace[868929448] 'process raft request' (duration: 248.560313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:26.034242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.039715ms","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-06-09T17:57:26.034289Z","caller":"traceutil/trace.go:171","msg":"trace[806461545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24538; }","duration":"173.132927ms","start":"2026-06-09T17:57:25.861145Z","end":"2026-06-09T17:57:26.034278Z","steps":["trace[806461545] 'agreement among raft nodes before linearized reading' (duration: 172.976284ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:26.156297Z","caller":"traceutil/trace.go:171","msg":"trace[354706722] transaction","detail":"{read_only:false; response_revision:24539; number_of_response:1; }","duration":"118.363944ms","start":"2026-06-09T17:57:26.037902Z","end":"2026-06-09T17:57:26.156266Z","steps":["trace[354706722] 'process raft request' (duration: 34.575749ms)","trace[354706722] 'compare' (duration: 83.644413ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:26.867802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.900206ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:57:26.868022Z","caller":"traceutil/trace.go:171","msg":"trace[246036386] transaction","detail":"{read_only:false; response_revision:24544; number_of_response:1; }","duration":"266.604606ms","start":"2026-06-09T17:57:26.601376Z","end":"2026-06-09T17:57:26.86798Z","steps":["trace[246036386] 'process raft request' (duration: 149.379074ms)","trace[246036386] 'compare' (duration: 116.625291ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:26.867951Z","caller":"traceutil/trace.go:171","msg":"trace[151835256] linearizableReadLoop","detail":"{readStateIndex:25334; appliedIndex:25333; }","duration":"160.515897ms","start":"2026-06-09T17:57:26.707408Z","end":"2026-06-09T17:57:26.867924Z","steps":["trace[151835256] 'read index received' (duration: 43.187913ms)","trace[151835256] 'applied index is now lower than readState.Index' (duration: 117.326384ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:26.868388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.954115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:57:26.868498Z","caller":"traceutil/trace.go:171","msg":"trace[1712140813] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:24544; }","duration":"161.111018ms","start":"2026-06-09T17:57:26.707361Z","end":"2026-06-09T17:57:26.868472Z","steps":["trace[1712140813] 'agreement among raft nodes before linearized reading' (duration: 160.959496ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:27.842427Z","caller":"traceutil/trace.go:171","msg":"trace[1014643014] linearizableReadLoop","detail":"{readStateIndex:25339; appliedIndex:25338; }","duration":"160.776962ms","start":"2026-06-09T17:57:27.681625Z","end":"2026-06-09T17:57:27.842402Z","steps":["trace[1014643014] 'read index received' (duration: 160.704451ms)","trace[1014643014] 'applied index is now lower than readState.Index' (duration: 71.431µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:57:27.842552Z","caller":"traceutil/trace.go:171","msg":"trace[1795050837] transaction","detail":"{read_only:false; response_revision:24549; number_of_response:1; }","duration":"239.944839ms","start":"2026-06-09T17:57:27.602596Z","end":"2026-06-09T17:57:27.842541Z","steps":["trace[1795050837] 'process raft request' (duration: 239.647453ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:27.84279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.144899ms","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-06-09T17:57:27.842846Z","caller":"traceutil/trace.go:171","msg":"trace[1897047871] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24549; }","duration":"161.22447ms","start":"2026-06-09T17:57:27.681596Z","end":"2026-06-09T17:57:27.842821Z","steps":["trace[1897047871] 'agreement among raft nodes before linearized reading' (duration: 161.066317ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:57:27.843256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.344723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-09T17:57:27.84333Z","caller":"traceutil/trace.go:171","msg":"trace[1240248832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24550; }","duration":"160.440634ms","start":"2026-06-09T17:57:27.68287Z","end":"2026-06-09T17:57:27.843311Z","steps":["trace[1240248832] 'agreement among raft nodes before linearized reading' (duration: 160.23867ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:27.843418Z","caller":"traceutil/trace.go:171","msg":"trace[108048578] transaction","detail":"{read_only:false; response_revision:24550; number_of_response:1; }","duration":"120.250479ms","start":"2026-06-09T17:57:27.723125Z","end":"2026-06-09T17:57:27.843376Z","steps":["trace[108048578] 'process raft request' (duration: 119.848112ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:29.83944Z","caller":"traceutil/trace.go:171","msg":"trace[1775110798] transaction","detail":"{read_only:false; response_revision:24565; number_of_response:1; }","duration":"123.732106ms","start":"2026-06-09T17:57:29.71568Z","end":"2026-06-09T17:57:29.839412Z","steps":["trace[1775110798] 'process raft request' (duration: 123.346929ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:40.85156Z","caller":"traceutil/trace.go:171","msg":"trace[141938352] transaction","detail":"{read_only:false; response_revision:24656; number_of_response:1; }","duration":"209.0731ms","start":"2026-06-09T17:57:40.642463Z","end":"2026-06-09T17:57:40.851536Z","steps":["trace[141938352] 'process raft request' (duration: 208.901437ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:41.050988Z","caller":"traceutil/trace.go:171","msg":"trace[1925221900] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"137.247643ms","start":"2026-06-09T17:57:40.913711Z","end":"2026-06-09T17:57:41.050959Z","steps":["trace[1925221900] 'process raft request' (duration: 136.957457ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:57:41.051016Z","caller":"traceutil/trace.go:171","msg":"trace[726494210] transaction","detail":"{read_only:false; response_revision:24657; number_of_response:1; }","duration":"407.754464ms","start":"2026-06-09T17:57:40.643222Z","end":"2026-06-09T17:57:41.050977Z","steps":["trace[726494210] 'process raft request' (duration: 330.009864ms)","trace[726494210] 'compare' (duration: 77.27188ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:57:41.051375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:57:40.6432Z","time spent":"408.07819ms","remote":"127.0.0.1:34288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:57:51.344885Z","caller":"traceutil/trace.go:171","msg":"trace[251706935] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"251.588381ms","start":"2026-06-09T17:57:51.093241Z","end":"2026-06-09T17:57:51.34483Z","steps":["trace[251706935] 'process raft request' (duration: 209.595261ms)","trace[251706935] 'compare' (duration: 41.691194ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T18:00:08.518517Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006973.wal"} {"level":"info","ts":"2026-06-09T18:01:05.976639Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23716} {"level":"info","ts":"2026-06-09T18:01:06.105847Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23716,"took":"121.265848ms","hash":2009109916,"current-db-size-bytes":59637760,"current-db-size":"60 MB","current-db-size-in-use-bytes":38748160,"current-db-size-in-use":"39 MB"} {"level":"info","ts":"2026-06-09T18:01:06.1059Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2009109916,"revision":23716,"compact-revision":20481} {"level":"info","ts":"2026-06-09T18:05:57.978511Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T18:05:57.984888Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-09T18:05:57.98497Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-09T18:06:05.98349Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26766} {"level":"info","ts":"2026-06-09T18:06:06.127234Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26766,"took":"139.503904ms","hash":1622664450,"current-db-size-bytes":59637760,"current-db-size":"60 MB","current-db-size-in-use-bytes":32407552,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-09T18:06:06.127369Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1622664450,"revision":26766,"compact-revision":23716} {"level":"info","ts":"2026-06-09T18:11:06.002353Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":29154} {"level":"info","ts":"2026-06-09T18:11:06.198593Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":29154,"took":"192.010898ms","hash":1712815931,"current-db-size-bytes":59637760,"current-db-size":"60 MB","current-db-size-in-use-bytes":30167040,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-09T18:11:06.198681Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1712815931,"revision":29154,"compact-revision":26766} {"level":"info","ts":"2026-06-09T18:16:06.008739Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31375} {"level":"info","ts":"2026-06-09T18:16:06.131647Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31375,"took":"119.805603ms","hash":1354053883,"current-db-size-bytes":59637760,"current-db-size":"60 MB","current-db-size-in-use-bytes":31264768,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-06-09T18:16:06.131703Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1354053883,"revision":31375,"compact-revision":29154} {"level":"info","ts":"2026-06-09T18:21:06.015796Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33709} {"level":"info","ts":"2026-06-09T18:21:06.165556Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33709,"took":"143.389152ms","hash":352587410,"current-db-size-bytes":60682240,"current-db-size":"61 MB","current-db-size-in-use-bytes":33038336,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-09T18:21:06.165673Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":352587410,"revision":33709,"compact-revision":31375} {"level":"info","ts":"2026-06-09T18:26:06.022228Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35937} {"level":"info","ts":"2026-06-09T18:26:06.158728Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35937,"took":"131.482516ms","hash":2381486539,"current-db-size-bytes":61104128,"current-db-size":"61 MB","current-db-size-in-use-bytes":31666176,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-09T18:26:06.158816Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2381486539,"revision":35937,"compact-revision":33709} {"level":"info","ts":"2026-06-09T18:27:26.501874Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"aab6040abcf11","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T18:27:26.50743Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-09T18:27:26.507558Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}