{"level":"warn","ts":"2026-05-06T05:03:02.672985Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T05:03:02.673143Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.73: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.19.213.73:2380","--initial-cluster=instance=https://199.19.213.73:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.73:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.73: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-05-06T05:03:02.673235Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-06T05:03:02.67327Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.73:2380"]} {"level":"info","ts":"2026-05-06T05:03:02.673313Z","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-05-06T05:03:02.674083Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"]} {"level":"info","ts":"2026-05-06T05:03:02.6742Z","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.19.213.73:2380"],"listen-peer-urls":["https://199.19.213.73:2380"],"advertise-client-urls":["https://199.19.213.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.73: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-05-06T05:03:02.679683Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.204623ms"} {"level":"info","ts":"2026-05-06T05:03:02.686376Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"34a8f480fa9ab6cb","cluster-id":"4820536600e0938e"} {"level":"info","ts":"2026-05-06T05:03:02.686483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=()"} {"level":"info","ts":"2026-05-06T05:03:02.68651Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became follower at term 0"} {"level":"info","ts":"2026-05-06T05:03:02.686524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 34a8f480fa9ab6cb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-06T05:03:02.68654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became follower at term 1"} {"level":"info","ts":"2026-05-06T05:03:02.686587Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=(3794551520857077451)"} {"level":"warn","ts":"2026-05-06T05:03:02.691826Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-06T05:03:02.693614Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-06T05:03:02.695618Z","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-05-06T05:03:02.698029Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"34a8f480fa9ab6cb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-06T05:03:02.698251Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"34a8f480fa9ab6cb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-06T05:03:02.698378Z","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-05-06T05:03:02.698443Z","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-05-06T05:03:02.698462Z","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-05-06T05:03:02.701353Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb switched to configuration voters=(3794551520857077451)"} {"level":"info","ts":"2026-05-06T05:03:02.701545Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4820536600e0938e","local-member-id":"34a8f480fa9ab6cb","added-peer-id":"34a8f480fa9ab6cb","added-peer-peer-urls":["https://199.19.213.73:2380"]} {"level":"info","ts":"2026-05-06T05:03:02.703446Z","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-05-06T05:03:02.703594Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.73:2380"} {"level":"info","ts":"2026-05-06T05:03:02.703637Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.73:2380"} {"level":"info","ts":"2026-05-06T05:03:02.703775Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"34a8f480fa9ab6cb","initial-advertise-peer-urls":["https://199.19.213.73:2380"],"listen-peer-urls":["https://199.19.213.73:2380"],"advertise-client-urls":["https://199.19.213.73:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.73:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-06T05:03:02.703837Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-06T05:03:03.18824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb is starting a new election at term 1"} {"level":"info","ts":"2026-05-06T05:03:03.188363Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became pre-candidate at term 1"} {"level":"info","ts":"2026-05-06T05:03:03.188409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb received MsgPreVoteResp from 34a8f480fa9ab6cb at term 1"} {"level":"info","ts":"2026-05-06T05:03:03.188434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became candidate at term 2"} {"level":"info","ts":"2026-05-06T05:03:03.188446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb received MsgVoteResp from 34a8f480fa9ab6cb at term 2"} {"level":"info","ts":"2026-05-06T05:03:03.188466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"34a8f480fa9ab6cb became leader at term 2"} {"level":"info","ts":"2026-05-06T05:03:03.188488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 34a8f480fa9ab6cb elected leader 34a8f480fa9ab6cb at term 2"} {"level":"info","ts":"2026-05-06T05:03:03.196252Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:03:03.204447Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"34a8f480fa9ab6cb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.73:2379]}","request-path":"/0/members/34a8f480fa9ab6cb/attributes","cluster-id":"4820536600e0938e","publish-timeout":"7s"} {"level":"info","ts":"2026-05-06T05:03:03.204686Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T05:03:03.208188Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-06T05:03:03.212187Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4820536600e0938e","local-member-id":"34a8f480fa9ab6cb","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:03:03.212515Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:03:03.212562Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-06T05:03:03.212754Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-06T05:03:03.212822Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-06T05:03:03.213737Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-06T05:03:03.217139Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.73:2379"} {"level":"info","ts":"2026-05-06T05:03:13.830482Z","caller":"traceutil/trace.go:171","msg":"trace[1491726106] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"130.391634ms","start":"2026-05-06T05:03:13.700041Z","end":"2026-05-06T05:03:13.830433Z","steps":["trace[1491726106] 'process raft request' (duration: 130.156798ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:03:14.216092Z","caller":"traceutil/trace.go:171","msg":"trace[1503830770] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"126.841033ms","start":"2026-05-06T05:03:14.089214Z","end":"2026-05-06T05:03:14.216055Z","steps":["trace[1503830770] 'read index received' (duration: 81.475547ms)","trace[1503830770] 'applied index is now lower than readState.Index' (duration: 45.364106ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:03:14.21773Z","caller":"traceutil/trace.go:171","msg":"trace[859870999] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"375.175164ms","start":"2026-05-06T05:03:13.840959Z","end":"2026-05-06T05:03:14.216134Z","steps":["trace[859870999] 'process raft request' (duration: 329.821748ms)","trace[859870999] 'compare' (duration: 45.085739ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:03:14.218463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.213263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-06T05:03:14.218889Z","caller":"traceutil/trace.go:171","msg":"trace[772641368] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:394; }","duration":"129.667075ms","start":"2026-05-06T05:03:14.089162Z","end":"2026-05-06T05:03:14.218829Z","steps":["trace[772641368] 'agreement among raft nodes before linearized reading' (duration: 127.029577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:03:14.221287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:03:13.840924Z","time spent":"377.074353ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:03:14.495997Z","caller":"traceutil/trace.go:171","msg":"trace[526646777] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"219.460645ms","start":"2026-05-06T05:03:14.276505Z","end":"2026-05-06T05:03:14.495965Z","steps":["trace[526646777] 'process raft request' (duration: 216.602822ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:03:34.416892Z","caller":"traceutil/trace.go:171","msg":"trace[1981565269] transaction","detail":"{read_only:false; number_of_response:1; response_revision:522; }","duration":"104.595996ms","start":"2026-05-06T05:03:34.312258Z","end":"2026-05-06T05:03:34.416854Z","steps":["trace[1981565269] 'process raft request' (duration: 104.36054ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:03:34.416968Z","caller":"traceutil/trace.go:171","msg":"trace[1043693446] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"104.585725ms","start":"2026-05-06T05:03:34.312343Z","end":"2026-05-06T05:03:34.416929Z","steps":["trace[1043693446] 'process raft request' (duration: 104.38972ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:03:34.417056Z","caller":"traceutil/trace.go:171","msg":"trace[422539768] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"104.644396ms","start":"2026-05-06T05:03:34.312403Z","end":"2026-05-06T05:03:34.417047Z","steps":["trace[422539768] 'process raft request' (duration: 104.420821ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:03:34.417485Z","caller":"traceutil/trace.go:171","msg":"trace[948814252] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"103.871377ms","start":"2026-05-06T05:03:34.313562Z","end":"2026-05-06T05:03:34.417433Z","steps":["trace[948814252] 'process raft request' (duration: 103.316193ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:03:34.417749Z","caller":"traceutil/trace.go:171","msg":"trace[1089746195] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"102.986855ms","start":"2026-05-06T05:03:34.314451Z","end":"2026-05-06T05:03:34.417438Z","steps":["trace[1089746195] 'process raft request' (duration: 102.486333ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:03:34.417864Z","caller":"traceutil/trace.go:171","msg":"trace[2043918632] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"100.527483ms","start":"2026-05-06T05:03:34.31732Z","end":"2026-05-06T05:03:34.417847Z","steps":["trace[2043918632] 'process raft request' (duration: 99.707412ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:03:40.562469Z","caller":"traceutil/trace.go:171","msg":"trace[1072584573] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"102.136983ms","start":"2026-05-06T05:03:40.460303Z","end":"2026-05-06T05:03:40.56244Z","steps":["trace[1072584573] 'process raft request' (duration: 101.889157ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:03:41.023916Z","caller":"traceutil/trace.go:171","msg":"trace[1293752531] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"105.095896ms","start":"2026-05-06T05:03:40.918773Z","end":"2026-05-06T05:03:41.023869Z","steps":["trace[1293752531] 'process raft request' (duration: 30.247936ms)","trace[1293752531] 'compare' (duration: 74.664045ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:03:41.181064Z","caller":"traceutil/trace.go:171","msg":"trace[954961356] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"131.576618ms","start":"2026-05-06T05:03:41.049448Z","end":"2026-05-06T05:03:41.181025Z","steps":["trace[954961356] 'process raft request' (duration: 130.880341ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:03:41.692341Z","caller":"traceutil/trace.go:171","msg":"trace[1869819112] linearizableReadLoop","detail":"{readStateIndex:646; appliedIndex:645; }","duration":"382.142361ms","start":"2026-05-06T05:03:41.310167Z","end":"2026-05-06T05:03:41.692309Z","steps":["trace[1869819112] 'read index received' (duration: 381.832943ms)","trace[1869819112] 'applied index is now lower than readState.Index' (duration: 308.408µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:03:41.692417Z","caller":"traceutil/trace.go:171","msg":"trace[57431212] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"446.975103ms","start":"2026-05-06T05:03:41.245404Z","end":"2026-05-06T05:03:41.692379Z","steps":["trace[57431212] 'process raft request' (duration: 446.706466ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:03:41.69259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:03:41.245375Z","time spent":"447.134346ms","remote":"127.0.0.1:44092","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-06T05:03:41.692781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.597863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-06T05:03:41.692835Z","caller":"traceutil/trace.go:171","msg":"trace[1548549990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"382.702065ms","start":"2026-05-06T05:03:41.310118Z","end":"2026-05-06T05:03:41.69282Z","steps":["trace[1548549990] 'agreement among raft nodes before linearized reading' (duration: 382.295355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:03:41.693091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:03:41.310097Z","time spent":"382.974172ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-06T05:05:06.382675Z","caller":"traceutil/trace.go:171","msg":"trace[1457582984] linearizableReadLoop","detail":"{readStateIndex:1887; appliedIndex:1886; }","duration":"117.223425ms","start":"2026-05-06T05:05:06.265415Z","end":"2026-05-06T05:05:06.382639Z","steps":["trace[1457582984] 'read index received' (duration: 116.863786ms)","trace[1457582984] 'applied index is now lower than readState.Index' (duration: 357.599µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:05:06.382739Z","caller":"traceutil/trace.go:171","msg":"trace[982232109] transaction","detail":"{read_only:false; response_revision:1824; number_of_response:1; }","duration":"172.654038ms","start":"2026-05-06T05:05:06.210043Z","end":"2026-05-06T05:05:06.382697Z","steps":["trace[982232109] 'process raft request' (duration: 172.386202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:06.382943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.511511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-baea46bd-2a69-44f8-a068-a5e55704ad88\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-05-06T05:05:06.383009Z","caller":"traceutil/trace.go:171","msg":"trace[2100759088] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-baea46bd-2a69-44f8-a068-a5e55704ad88; range_end:; response_count:1; response_revision:1824; }","duration":"117.646445ms","start":"2026-05-06T05:05:06.265347Z","end":"2026-05-06T05:05:06.382993Z","steps":["trace[2100759088] 'agreement among raft nodes before linearized reading' (duration: 117.435849ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:06.590104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.824821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:05:06.590247Z","caller":"traceutil/trace.go:171","msg":"trace[526119763] transaction","detail":"{read_only:false; response_revision:1826; number_of_response:1; }","duration":"198.797666ms","start":"2026-05-06T05:05:06.391429Z","end":"2026-05-06T05:05:06.590226Z","steps":["trace[526119763] 'process raft request' (duration: 67.587885ms)","trace[526119763] 'compare' (duration: 130.666638ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:05:06.909161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.321245ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-05-06T05:05:06.909416Z","caller":"traceutil/trace.go:171","msg":"trace[830780564] linearizableReadLoop","detail":"{readStateIndex:1891; appliedIndex:1889; }","duration":"196.313784ms","start":"2026-05-06T05:05:06.713082Z","end":"2026-05-06T05:05:06.909396Z","steps":["trace[830780564] 'read index received' (duration: 186.175583ms)","trace[830780564] 'applied index is now lower than readState.Index' (duration: 10.136931ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:05:06.909627Z","caller":"traceutil/trace.go:171","msg":"trace[1535669487] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"218.911824ms","start":"2026-05-06T05:05:06.690703Z","end":"2026-05-06T05:05:06.909615Z","steps":["trace[1535669487] 'process raft request' (duration: 218.564665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:06.909797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.219773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:05:06.909904Z","caller":"traceutil/trace.go:171","msg":"trace[1733067997] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1827; }","duration":"169.374157ms","start":"2026-05-06T05:05:06.740501Z","end":"2026-05-06T05:05:06.909875Z","steps":["trace[1733067997] 'agreement among raft nodes before linearized reading' (duration: 169.201743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:05:06.910011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.873226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-05-06T05:05:06.91005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.94659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-06T05:05:06.9101Z","caller":"traceutil/trace.go:171","msg":"trace[881538900] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1827; }","duration":"113.02411ms","start":"2026-05-06T05:05:06.797056Z","end":"2026-05-06T05:05:06.910081Z","steps":["trace[881538900] 'agreement among raft nodes before linearized reading' (duration: 112.752863ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:06.910123Z","caller":"traceutil/trace.go:171","msg":"trace[2005652937] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1827; }","duration":"197.058793ms","start":"2026-05-06T05:05:06.713044Z","end":"2026-05-06T05:05:06.910103Z","steps":["trace[2005652937] 'agreement among raft nodes before linearized reading' (duration: 196.858868ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:05:50.811734Z","caller":"traceutil/trace.go:171","msg":"trace[782371616] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"143.58629ms","start":"2026-05-06T05:05:50.668112Z","end":"2026-05-06T05:05:50.811698Z","steps":["trace[782371616] 'process raft request' (duration: 128.882284ms)","trace[782371616] 'compare' (duration: 14.409609ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:07:19.777386Z","caller":"traceutil/trace.go:171","msg":"trace[638335000] transaction","detail":"{read_only:false; response_revision:2797; number_of_response:1; }","duration":"230.39266ms","start":"2026-05-06T05:07:19.546958Z","end":"2026-05-06T05:07:19.77735Z","steps":["trace[638335000] 'process raft request' (duration: 230.101914ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:07:19.912793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.703979ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:07:19.913147Z","caller":"traceutil/trace.go:171","msg":"trace[1429003397] transaction","detail":"{read_only:false; response_revision:2798; number_of_response:1; }","duration":"130.313366ms","start":"2026-05-06T05:07:19.782639Z","end":"2026-05-06T05:07:19.912952Z","steps":["trace[1429003397] 'process raft request' (duration: 27.371831ms)","trace[1429003397] 'compare' (duration: 102.573575ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:11:29.680101Z","caller":"traceutil/trace.go:171","msg":"trace[1904017499] transaction","detail":"{read_only:false; response_revision:5333; number_of_response:1; }","duration":"136.210026ms","start":"2026-05-06T05:11:29.543813Z","end":"2026-05-06T05:11:29.680023Z","steps":["trace[1904017499] 'process raft request' (duration: 73.212997ms)","trace[1904017499] 'compare' (duration: 62.771834ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:11:29.714601Z","caller":"traceutil/trace.go:171","msg":"trace[632471587] linearizableReadLoop","detail":"{readStateIndex:5522; appliedIndex:5520; }","duration":"124.673647ms","start":"2026-05-06T05:11:29.589903Z","end":"2026-05-06T05:11:29.714576Z","steps":["trace[632471587] 'read index received' (duration: 27.19356ms)","trace[632471587] 'applied index is now lower than readState.Index' (duration: 97.478997ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:11:29.714772Z","caller":"traceutil/trace.go:171","msg":"trace[1175303348] transaction","detail":"{read_only:false; response_revision:5334; number_of_response:1; }","duration":"170.063349ms","start":"2026-05-06T05:11:29.544648Z","end":"2026-05-06T05:11:29.714712Z","steps":["trace[1175303348] 'process raft request' (duration: 169.785292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:11:29.714853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.937393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-77866bda-cbe3-4780-abe9-540cdbf9e59b\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-05-06T05:11:29.714895Z","caller":"traceutil/trace.go:171","msg":"trace[1861856155] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-77866bda-cbe3-4780-abe9-540cdbf9e59b; range_end:; response_count:1; response_revision:5334; }","duration":"125.053206ms","start":"2026-05-06T05:11:29.589829Z","end":"2026-05-06T05:11:29.714882Z","steps":["trace[1861856155] 'agreement among raft nodes before linearized reading' (duration: 124.887452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:11:29.715163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.082844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-xs98z\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-05-06T05:11:29.715246Z","caller":"traceutil/trace.go:171","msg":"trace[2060546608] range","detail":"{range_begin:/registry/pods/openstack/keepalived-xs98z; range_end:; response_count:1; response_revision:5334; }","duration":"115.238657ms","start":"2026-05-06T05:11:29.599987Z","end":"2026-05-06T05:11:29.715225Z","steps":["trace[2060546608] 'agreement among raft nodes before linearized reading' (duration: 115.033362ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:11:29.983032Z","caller":"traceutil/trace.go:171","msg":"trace[292799563] transaction","detail":"{read_only:false; response_revision:5335; number_of_response:1; }","duration":"120.65989ms","start":"2026-05-06T05:11:29.862351Z","end":"2026-05-06T05:11:29.983011Z","steps":["trace[292799563] 'process raft request' (duration: 120.530836ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:11:40.583243Z","caller":"traceutil/trace.go:171","msg":"trace[1377679215] transaction","detail":"{read_only:false; response_revision:5484; number_of_response:1; }","duration":"118.860706ms","start":"2026-05-06T05:11:40.46434Z","end":"2026-05-06T05:11:40.5832Z","steps":["trace[1377679215] 'process raft request' (duration: 37.010549ms)","trace[1377679215] 'compare' (duration: 81.56619ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:11:40.606026Z","caller":"traceutil/trace.go:171","msg":"trace[1305697886] transaction","detail":"{read_only:false; response_revision:5485; number_of_response:1; }","duration":"141.494494ms","start":"2026-05-06T05:11:40.464515Z","end":"2026-05-06T05:11:40.60601Z","steps":["trace[1305697886] 'process raft request' (duration: 141.31494ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:11:40.60638Z","caller":"traceutil/trace.go:171","msg":"trace[977166272] transaction","detail":"{read_only:false; response_revision:5486; number_of_response:1; }","duration":"141.816193ms","start":"2026-05-06T05:11:40.464538Z","end":"2026-05-06T05:11:40.606354Z","steps":["trace[977166272] 'process raft request' (duration: 141.411173ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:11:40.606411Z","caller":"traceutil/trace.go:171","msg":"trace[1226613696] linearizableReadLoop","detail":"{readStateIndex:5678; appliedIndex:5674; }","duration":"133.570013ms","start":"2026-05-06T05:11:40.472816Z","end":"2026-05-06T05:11:40.606386Z","steps":["trace[1226613696] 'read index received' (duration: 28.547583ms)","trace[1226613696] 'applied index is now lower than readState.Index' (duration: 105.02134ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:11:40.606481Z","caller":"traceutil/trace.go:171","msg":"trace[860270778] transaction","detail":"{read_only:false; response_revision:5487; number_of_response:1; }","duration":"141.844794ms","start":"2026-05-06T05:11:40.464621Z","end":"2026-05-06T05:11:40.606466Z","steps":["trace[860270778] 'process raft request' (duration: 141.635299ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:11:40.606612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.773568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-06T05:11:40.606659Z","caller":"traceutil/trace.go:171","msg":"trace[1168479688] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5487; }","duration":"133.859189ms","start":"2026-05-06T05:11:40.472787Z","end":"2026-05-06T05:11:40.606646Z","steps":["trace[1168479688] 'agreement among raft nodes before linearized reading' (duration: 133.694505ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:13:03.246612Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3036} {"level":"info","ts":"2026-05-06T05:13:03.329562Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3036,"took":"81.187875ms","hash":3431156293,"current-db-size-bytes":58359808,"current-db-size":"58 MB","current-db-size-in-use-bytes":31969280,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-06T05:13:03.329631Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3431156293,"revision":3036,"compact-revision":-1} {"level":"info","ts":"2026-05-06T05:17:19.081247Z","caller":"traceutil/trace.go:171","msg":"trace[798300151] linearizableReadLoop","detail":"{readStateIndex:8350; appliedIndex:8349; }","duration":"160.437358ms","start":"2026-05-06T05:17:18.920777Z","end":"2026-05-06T05:17:19.081214Z","steps":["trace[798300151] 'read index received' (duration: 160.272204ms)","trace[798300151] 'applied index is now lower than readState.Index' (duration: 163.584µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:17:19.081315Z","caller":"traceutil/trace.go:171","msg":"trace[1225342114] transaction","detail":"{read_only:false; response_revision:8078; number_of_response:1; }","duration":"236.724471ms","start":"2026-05-06T05:17:18.844552Z","end":"2026-05-06T05:17:19.081277Z","steps":["trace[1225342114] 'process raft request' (duration: 236.447354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:17:19.081547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.711534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:17:19.081614Z","caller":"traceutil/trace.go:171","msg":"trace[1934952653] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:8078; }","duration":"160.872248ms","start":"2026-05-06T05:17:18.920727Z","end":"2026-05-06T05:17:19.081599Z","steps":["trace[1934952653] 'agreement among raft nodes before linearized reading' (duration: 160.626812ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:17:19.211351Z","caller":"traceutil/trace.go:171","msg":"trace[217780646] transaction","detail":"{read_only:false; response_revision:8079; number_of_response:1; }","duration":"252.676187ms","start":"2026-05-06T05:17:18.958641Z","end":"2026-05-06T05:17:19.211318Z","steps":["trace[217780646] 'process raft request' (duration: 239.708714ms)","trace[217780646] 'compare' (duration: 12.768988ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:17:19.211813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.826904ms","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-05-06T05:17:19.211928Z","caller":"traceutil/trace.go:171","msg":"trace[238697495] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8079; }","duration":"239.015318ms","start":"2026-05-06T05:17:18.972885Z","end":"2026-05-06T05:17:19.2119Z","steps":["trace[238697495] 'agreement among raft nodes before linearized reading' (duration: 238.66874ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:17:19.212102Z","caller":"traceutil/trace.go:171","msg":"trace[1315653966] linearizableReadLoop","detail":"{readStateIndex:8351; appliedIndex:8350; }","duration":"129.899539ms","start":"2026-05-06T05:17:19.081346Z","end":"2026-05-06T05:17:19.211245Z","steps":["trace[1315653966] 'read index received' (duration: 116.919706ms)","trace[1315653966] 'applied index is now lower than readState.Index' (duration: 12.978833ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:17:30.84669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.485453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:17:30.84681Z","caller":"traceutil/trace.go:171","msg":"trace[1763271562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8138; }","duration":"106.690788ms","start":"2026-05-06T05:17:30.740093Z","end":"2026-05-06T05:17:30.846783Z","steps":["trace[1763271562] 'range keys from in-memory index tree' (duration: 106.31699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:17:30.846866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.547438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-05-06T05:17:30.846969Z","caller":"traceutil/trace.go:171","msg":"trace[1062579863] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:8138; }","duration":"325.660032ms","start":"2026-05-06T05:17:30.52128Z","end":"2026-05-06T05:17:30.84694Z","steps":["trace[1062579863] 'range keys from in-memory index tree' (duration: 325.359014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:17:30.846952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.2885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-06T05:17:30.84702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:17:30.521262Z","time spent":"325.749323ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-05-06T05:17:30.847067Z","caller":"traceutil/trace.go:171","msg":"trace[2757632] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:8138; }","duration":"202.438953ms","start":"2026-05-06T05:17:30.644603Z","end":"2026-05-06T05:17:30.847042Z","steps":["trace[2757632] 'count revisions from in-memory index tree' (duration: 202.104185ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:18:03.252716Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6170} {"level":"info","ts":"2026-05-06T05:18:03.345728Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6170,"took":"90.815074ms","hash":369336631,"current-db-size-bytes":58359808,"current-db-size":"58 MB","current-db-size-in-use-bytes":23056384,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-05-06T05:18:03.345796Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":369336631,"revision":6170,"compact-revision":3036} {"level":"info","ts":"2026-05-06T05:18:11.539282Z","caller":"traceutil/trace.go:171","msg":"trace[595375766] transaction","detail":"{read_only:false; response_revision:8448; number_of_response:1; }","duration":"149.446679ms","start":"2026-05-06T05:18:11.389799Z","end":"2026-05-06T05:18:11.539246Z","steps":["trace[595375766] 'process raft request' (duration: 149.264995ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:18:11.700594Z","caller":"traceutil/trace.go:171","msg":"trace[1903510865] transaction","detail":"{read_only:false; response_revision:8449; number_of_response:1; }","duration":"213.091647ms","start":"2026-05-06T05:18:11.487448Z","end":"2026-05-06T05:18:11.70054Z","steps":["trace[1903510865] 'process raft request' (duration: 166.581474ms)","trace[1903510865] 'compare' (duration: 46.318379ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:18:11.700746Z","caller":"traceutil/trace.go:171","msg":"trace[1053440225] transaction","detail":"{read_only:false; response_revision:8450; number_of_response:1; }","duration":"151.184651ms","start":"2026-05-06T05:18:11.549534Z","end":"2026-05-06T05:18:11.700719Z","steps":["trace[1053440225] 'process raft request' (duration: 150.975796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:18:11.70087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.488385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-mf8sh\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-05-06T05:18:11.700611Z","caller":"traceutil/trace.go:171","msg":"trace[120017931] linearizableReadLoop","detail":"{readStateIndex:8738; appliedIndex:8737; }","duration":"157.218218ms","start":"2026-05-06T05:18:11.543355Z","end":"2026-05-06T05:18:11.700573Z","steps":["trace[120017931] 'read index received' (duration: 110.865259ms)","trace[120017931] 'applied index is now lower than readState.Index' (duration: 46.348319ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:18:11.700926Z","caller":"traceutil/trace.go:171","msg":"trace[1431511198] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-mf8sh; range_end:; response_count:1; response_revision:8450; }","duration":"157.590967ms","start":"2026-05-06T05:18:11.543324Z","end":"2026-05-06T05:18:11.700915Z","steps":["trace[1431511198] 'agreement among raft nodes before linearized reading' (duration: 157.34551ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:18:11.701194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.103521ms","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-05-06T05:18:11.701186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.121446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-mf8sh\" ","response":"range_response_count:1 size:4644"} {"level":"info","ts":"2026-05-06T05:18:11.701239Z","caller":"traceutil/trace.go:171","msg":"trace[1049109847] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8450; }","duration":"108.187053ms","start":"2026-05-06T05:18:11.59304Z","end":"2026-05-06T05:18:11.701227Z","steps":["trace[1049109847] 'agreement among raft nodes before linearized reading' (duration: 108.04347ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:18:11.701255Z","caller":"traceutil/trace.go:171","msg":"trace[1493610558] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-mf8sh; range_end:; response_count:1; response_revision:8450; }","duration":"152.248159ms","start":"2026-05-06T05:18:11.548987Z","end":"2026-05-06T05:18:11.701235Z","steps":["trace[1493610558] 'agreement among raft nodes before linearized reading' (duration: 152.028813ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:18:11.701454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.266104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-06T05:18:11.701522Z","caller":"traceutil/trace.go:171","msg":"trace[1119571633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8450; }","duration":"110.386247ms","start":"2026-05-06T05:18:11.591121Z","end":"2026-05-06T05:18:11.701508Z","steps":["trace[1119571633] 'agreement among raft nodes before linearized reading' (duration: 110.06789ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:18:12.538314Z","caller":"traceutil/trace.go:171","msg":"trace[725757987] transaction","detail":"{read_only:false; response_revision:8457; number_of_response:1; }","duration":"256.935735ms","start":"2026-05-06T05:18:12.281358Z","end":"2026-05-06T05:18:12.538294Z","steps":["trace[725757987] 'process raft request' (duration: 256.811952ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:18:12.583296Z","caller":"traceutil/trace.go:171","msg":"trace[178185840] transaction","detail":"{read_only:false; response_revision:8458; number_of_response:1; }","duration":"207.512324ms","start":"2026-05-06T05:18:12.375757Z","end":"2026-05-06T05:18:12.583269Z","steps":["trace[178185840] 'process raft request' (duration: 207.259757ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:18:12.583564Z","caller":"traceutil/trace.go:171","msg":"trace[1781576793] transaction","detail":"{read_only:false; response_revision:8459; number_of_response:1; }","duration":"113.619535ms","start":"2026-05-06T05:18:12.469913Z","end":"2026-05-06T05:18:12.583532Z","steps":["trace[1781576793] 'process raft request' (duration: 113.297847ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:18:13.126146Z","caller":"traceutil/trace.go:171","msg":"trace[668333675] linearizableReadLoop","detail":"{readStateIndex:8753; appliedIndex:8752; }","duration":"127.481508ms","start":"2026-05-06T05:18:12.998637Z","end":"2026-05-06T05:18:13.126118Z","steps":["trace[668333675] 'read index received' (duration: 127.153301ms)","trace[668333675] 'applied index is now lower than readState.Index' (duration: 326.897µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:18:13.126291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.634603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:18:13.126328Z","caller":"traceutil/trace.go:171","msg":"trace[667459384] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:8463; }","duration":"127.727865ms","start":"2026-05-06T05:18:12.998587Z","end":"2026-05-06T05:18:13.126315Z","steps":["trace[667459384] 'agreement among raft nodes before linearized reading' (duration: 127.628393ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:18:13.126401Z","caller":"traceutil/trace.go:171","msg":"trace[774591536] transaction","detail":"{read_only:false; response_revision:8463; number_of_response:1; }","duration":"141.808845ms","start":"2026-05-06T05:18:12.984534Z","end":"2026-05-06T05:18:13.126343Z","steps":["trace[774591536] 'process raft request' (duration: 141.403075ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:19:29.375193Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024fa.wal"} {"level":"info","ts":"2026-05-06T05:20:30.214213Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"34a8f480fa9ab6cb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-06T05:20:30.221813Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-05-06T05:20:30.221908Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-05-06T05:23:03.268893Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8365} {"level":"info","ts":"2026-05-06T05:23:03.350494Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8365,"took":"79.116821ms","hash":1221001786,"current-db-size-bytes":58359808,"current-db-size":"58 MB","current-db-size-in-use-bytes":26476544,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-05-06T05:23:03.350573Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1221001786,"revision":8365,"compact-revision":6170} {"level":"info","ts":"2026-05-06T05:28:03.275485Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":11037} {"level":"info","ts":"2026-05-06T05:28:03.354332Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":11037,"took":"75.795679ms","hash":4041326818,"current-db-size-bytes":58359808,"current-db-size":"58 MB","current-db-size-in-use-bytes":27881472,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-05-06T05:28:03.354407Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4041326818,"revision":11037,"compact-revision":8365} {"level":"info","ts":"2026-05-06T05:29:22.163617Z","caller":"traceutil/trace.go:171","msg":"trace[1691416667] linearizableReadLoop","detail":"{readStateIndex:14053; appliedIndex:14052; }","duration":"103.824012ms","start":"2026-05-06T05:29:22.05977Z","end":"2026-05-06T05:29:22.163594Z","steps":["trace[1691416667] 'read index received' (duration: 103.686099ms)","trace[1691416667] 'applied index is now lower than readState.Index' (duration: 136.723µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:29:22.163733Z","caller":"traceutil/trace.go:171","msg":"trace[1652729222] transaction","detail":"{read_only:false; response_revision:13602; number_of_response:1; }","duration":"107.06141ms","start":"2026-05-06T05:29:22.05663Z","end":"2026-05-06T05:29:22.163691Z","steps":["trace[1652729222] 'process raft request' (duration: 106.822014ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:29:22.163898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.107069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-06T05:29:22.163983Z","caller":"traceutil/trace.go:171","msg":"trace[1690093347] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:13602; }","duration":"104.243402ms","start":"2026-05-06T05:29:22.059719Z","end":"2026-05-06T05:29:22.163962Z","steps":["trace[1690093347] 'agreement among raft nodes before linearized reading' (duration: 103.989776ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:29:23.044248Z","caller":"traceutil/trace.go:171","msg":"trace[1702894159] transaction","detail":"{read_only:false; response_revision:13605; number_of_response:1; }","duration":"151.178412ms","start":"2026-05-06T05:29:22.89304Z","end":"2026-05-06T05:29:23.044219Z","steps":["trace[1702894159] 'process raft request' (duration: 150.945247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:29:32.0282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.724263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-06T05:29:32.028545Z","caller":"traceutil/trace.go:171","msg":"trace[1508593545] linearizableReadLoop","detail":"{readStateIndex:14220; appliedIndex:14219; }","duration":"288.415979ms","start":"2026-05-06T05:29:31.740092Z","end":"2026-05-06T05:29:32.028508Z","steps":["trace[1508593545] 'read index received' (duration: 100.186464ms)","trace[1508593545] 'applied index is now lower than readState.Index' (duration: 188.221655ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:29:32.028629Z","caller":"traceutil/trace.go:171","msg":"trace[1353551269] transaction","detail":"{read_only:false; response_revision:13765; number_of_response:1; }","duration":"400.151731ms","start":"2026-05-06T05:29:31.628419Z","end":"2026-05-06T05:29:32.028571Z","steps":["trace[1353551269] 'process raft request' (duration: 211.879575ms)","trace[1353551269] 'compare' (duration: 187.464137ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:29:32.029054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.926861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T05:29:32.029127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:29:31.628387Z","time spent":"400.603442ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:29:32.029155Z","caller":"traceutil/trace.go:171","msg":"trace[375207841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13765; }","duration":"289.140186ms","start":"2026-05-06T05:29:31.739982Z","end":"2026-05-06T05:29:32.029122Z","steps":["trace[375207841] 'agreement among raft nodes before linearized reading' (duration: 288.942711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:29:32.029129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.311413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-06T05:29:32.02916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.794955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-06T05:29:32.029241Z","caller":"traceutil/trace.go:171","msg":"trace[1739998701] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:13765; }","duration":"136.446417ms","start":"2026-05-06T05:29:31.892768Z","end":"2026-05-06T05:29:32.029214Z","steps":["trace[1739998701] 'agreement among raft nodes before linearized reading' (duration: 136.255402ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:29:32.029285Z","caller":"traceutil/trace.go:171","msg":"trace[2086712395] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:13765; }","duration":"187.99216ms","start":"2026-05-06T05:29:31.841257Z","end":"2026-05-06T05:29:32.02925Z","steps":["trace[2086712395] 'agreement among raft nodes before linearized reading' (duration: 187.767264ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:29:32.18031Z","caller":"traceutil/trace.go:171","msg":"trace[1929676732] transaction","detail":"{read_only:false; response_revision:13766; number_of_response:1; }","duration":"112.683345ms","start":"2026-05-06T05:29:32.067544Z","end":"2026-05-06T05:29:32.180227Z","steps":["trace[1929676732] 'process raft request' (duration: 95.06109ms)","trace[1929676732] 'compare' (duration: 17.331708ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:31:11.829231Z","caller":"traceutil/trace.go:171","msg":"trace[234515093] linearizableReadLoop","detail":"{readStateIndex:15336; appliedIndex:15335; }","duration":"231.802586ms","start":"2026-05-06T05:31:11.597402Z","end":"2026-05-06T05:31:11.829204Z","steps":["trace[234515093] 'read index received' (duration: 163.993932ms)","trace[234515093] 'applied index is now lower than readState.Index' (duration: 67.807303ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:31:11.829347Z","caller":"traceutil/trace.go:171","msg":"trace[1803342844] transaction","detail":"{read_only:false; response_revision:14846; number_of_response:1; }","duration":"260.547828ms","start":"2026-05-06T05:31:11.568786Z","end":"2026-05-06T05:31:11.829333Z","steps":["trace[1803342844] 'process raft request' (duration: 192.57085ms)","trace[1803342844] 'compare' (duration: 67.720462ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:31:11.829513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.102782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5523"} {"level":"info","ts":"2026-05-06T05:31:11.829588Z","caller":"traceutil/trace.go:171","msg":"trace[493355339] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:14846; }","duration":"232.232085ms","start":"2026-05-06T05:31:11.597341Z","end":"2026-05-06T05:31:11.829573Z","steps":["trace[493355339] 'agreement among raft nodes before linearized reading' (duration: 232.036681ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:31:11.895141Z","caller":"traceutil/trace.go:171","msg":"trace[666556074] transaction","detail":"{read_only:false; response_revision:14847; number_of_response:1; }","duration":"215.284837ms","start":"2026-05-06T05:31:11.679816Z","end":"2026-05-06T05:31:11.895101Z","steps":["trace[666556074] 'process raft request' (duration: 215.161824ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:31:11.983318Z","caller":"traceutil/trace.go:171","msg":"trace[634950995] linearizableReadLoop","detail":"{readStateIndex:15338; appliedIndex:15336; }","duration":"153.98785ms","start":"2026-05-06T05:31:11.829307Z","end":"2026-05-06T05:31:11.983295Z","steps":["trace[634950995] 'read index received' (duration: 65.664952ms)","trace[634950995] 'applied index is now lower than readState.Index' (duration: 88.321788ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:31:11.983506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.307982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pm4pl\" ","response":"range_response_count:1 size:22121"} {"level":"info","ts":"2026-05-06T05:31:11.983575Z","caller":"traceutil/trace.go:171","msg":"trace[386279146] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pm4pl; range_end:; response_count:1; response_revision:14847; }","duration":"370.411914ms","start":"2026-05-06T05:31:11.613146Z","end":"2026-05-06T05:31:11.983558Z","steps":["trace[386279146] 'agreement among raft nodes before linearized reading' (duration: 370.189469ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:31:11.983614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:31:11.613134Z","time spent":"370.473365ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22143,"request content":"key:\"/registry/pods/openstack/nova-compute-default-pm4pl\" "} {"level":"warn","ts":"2026-05-06T05:31:11.983611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.344681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-compute-default-pm4pl\" ","response":"range_response_count:1 size:22121"} {"level":"info","ts":"2026-05-06T05:31:11.983689Z","caller":"traceutil/trace.go:171","msg":"trace[1343646360] range","detail":"{range_begin:/registry/pods/openstack/nova-compute-default-pm4pl; range_end:; response_count:1; response_revision:14847; }","duration":"372.475484ms","start":"2026-05-06T05:31:11.611195Z","end":"2026-05-06T05:31:11.98367Z","steps":["trace[1343646360] 'agreement among raft nodes before linearized reading' (duration: 372.210957ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:31:11.983735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:31:11.611164Z","time spent":"372.561956ms","remote":"127.0.0.1:44036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":22143,"request content":"key:\"/registry/pods/openstack/nova-compute-default-pm4pl\" "} {"level":"info","ts":"2026-05-06T05:33:03.28165Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":13093} {"level":"info","ts":"2026-05-06T05:33:03.362685Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":13093,"took":"78.267015ms","hash":3905742768,"current-db-size-bytes":58359808,"current-db-size":"58 MB","current-db-size-in-use-bytes":31305728,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-06T05:33:03.36276Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3905742768,"revision":13093,"compact-revision":11037} {"level":"info","ts":"2026-05-06T05:36:52.436566Z","caller":"traceutil/trace.go:171","msg":"trace[941197777] linearizableReadLoop","detail":"{readStateIndex:17999; appliedIndex:17998; }","duration":"153.648881ms","start":"2026-05-06T05:36:52.282884Z","end":"2026-05-06T05:36:52.436533Z","steps":["trace[941197777] 'read index received' (duration: 142.052031ms)","trace[941197777] 'applied index is now lower than readState.Index' (duration: 11.59577ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:36:52.43688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.830695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/rsakeys/\" range_end:\"/registry/secretgen.k14s.io/rsakeys0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:36:52.436924Z","caller":"traceutil/trace.go:171","msg":"trace[1442584119] range","detail":"{range_begin:/registry/secretgen.k14s.io/rsakeys/; range_end:/registry/secretgen.k14s.io/rsakeys0; response_count:0; response_revision:17423; }","duration":"154.097771ms","start":"2026-05-06T05:36:52.282814Z","end":"2026-05-06T05:36:52.436912Z","steps":["trace[1442584119] 'agreement among raft nodes before linearized reading' (duration: 153.844495ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:36:59.873555Z","caller":"traceutil/trace.go:171","msg":"trace[874966061] transaction","detail":"{read_only:false; response_revision:17649; number_of_response:1; }","duration":"131.934018ms","start":"2026-05-06T05:36:59.741591Z","end":"2026-05-06T05:36:59.873525Z","steps":["trace[874966061] 'process raft request' (duration: 128.881194ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:38:03.298982Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15645} {"level":"info","ts":"2026-05-06T05:38:03.409305Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15645,"took":"105.990903ms","hash":313137276,"current-db-size-bytes":58359808,"current-db-size":"58 MB","current-db-size-in-use-bytes":34136064,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-05-06T05:38:03.409385Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":313137276,"revision":15645,"compact-revision":13093} {"level":"info","ts":"2026-05-06T05:40:37.070959Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"34a8f480fa9ab6cb","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-06T05:40:37.077276Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-05-06T05:40:37.077444Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"warn","ts":"2026-05-06T05:41:49.793002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.621844ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:41:49.793173Z","caller":"traceutil/trace.go:171","msg":"trace[1025413629] linearizableReadLoop","detail":"{readStateIndex:20394; appliedIndex:20391; }","duration":"340.537366ms","start":"2026-05-06T05:41:49.452615Z","end":"2026-05-06T05:41:49.793152Z","steps":["trace[1025413629] 'read index received' (duration: 47.225935ms)","trace[1025413629] 'applied index is now lower than readState.Index' (duration: 293.310231ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:41:49.793533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.896684ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-06T05:41:49.793504Z","caller":"traceutil/trace.go:171","msg":"trace[1740836546] transaction","detail":"{read_only:false; response_revision:19740; number_of_response:1; }","duration":"375.23155ms","start":"2026-05-06T05:41:49.418229Z","end":"2026-05-06T05:41:49.793461Z","steps":["trace[1740836546] 'process raft request' (duration: 270.078013ms)","trace[1740836546] 'compare' (duration: 104.370839ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:41:49.793576Z","caller":"traceutil/trace.go:171","msg":"trace[732868977] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:19741; }","duration":"189.968776ms","start":"2026-05-06T05:41:49.603596Z","end":"2026-05-06T05:41:49.793565Z","steps":["trace[732868977] 'agreement among raft nodes before linearized reading' (duration: 189.883174ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:41:49.793551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.915094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-06T05:41:49.793541Z","caller":"traceutil/trace.go:171","msg":"trace[1884248372] transaction","detail":"{read_only:false; response_revision:19741; number_of_response:1; }","duration":"374.897922ms","start":"2026-05-06T05:41:49.41859Z","end":"2026-05-06T05:41:49.793488Z","steps":["trace[1884248372] 'process raft request' (duration: 374.507822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:41:49.793916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:41:49.418568Z","time spent":"375.22922ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:41:49.793648Z","caller":"traceutil/trace.go:171","msg":"trace[476219189] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:19741; }","duration":"341.055478ms","start":"2026-05-06T05:41:49.452568Z","end":"2026-05-06T05:41:49.793624Z","steps":["trace[476219189] 'agreement among raft nodes before linearized reading' (duration: 340.807282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:41:49.794232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:41:49.452549Z","time spent":"341.663803ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-06T05:41:49.795448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:41:49.418203Z","time spent":"375.391864ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:41:50.237524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.502556ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:41:50.237606Z","caller":"traceutil/trace.go:171","msg":"trace[1918296397] transaction","detail":"{read_only:false; response_revision:19744; number_of_response:1; }","duration":"202.101788ms","start":"2026-05-06T05:41:50.035491Z","end":"2026-05-06T05:41:50.237593Z","steps":["trace[1918296397] 'process raft request' (duration: 74.468819ms)","trace[1918296397] 'compare' (duration: 127.418454ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:41:50.525962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.694857ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:41:50.526072Z","caller":"traceutil/trace.go:171","msg":"trace[14868388] transaction","detail":"{read_only:false; response_revision:19745; number_of_response:1; }","duration":"237.276824ms","start":"2026-05-06T05:41:50.288775Z","end":"2026-05-06T05:41:50.526052Z","steps":["trace[14868388] 'process raft request' (duration: 90.405713ms)","trace[14868388] 'compare' (duration: 146.39845ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:43:03.305874Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18184} {"level":"info","ts":"2026-05-06T05:43:03.418873Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18184,"took":"109.006941ms","hash":2284157606,"current-db-size-bytes":58359808,"current-db-size":"58 MB","current-db-size-in-use-bytes":29483008,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-06T05:43:03.418922Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2284157606,"revision":18184,"compact-revision":15645} {"level":"info","ts":"2026-05-06T05:45:25.680457Z","caller":"traceutil/trace.go:171","msg":"trace[1108767782] transaction","detail":"{read_only:false; response_revision:22100; number_of_response:1; }","duration":"368.195461ms","start":"2026-05-06T05:45:25.312229Z","end":"2026-05-06T05:45:25.680425Z","steps":["trace[1108767782] 'process raft request' (duration: 367.995866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:45:25.680672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:45:25.312195Z","time spent":"368.374995ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:45:25.860836Z","caller":"traceutil/trace.go:171","msg":"trace[1892950068] transaction","detail":"{read_only:false; response_revision:22102; number_of_response:1; }","duration":"449.035144ms","start":"2026-05-06T05:45:25.411789Z","end":"2026-05-06T05:45:25.860824Z","steps":["trace[1892950068] 'process raft request' (duration: 448.781948ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:45:25.860983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:45:25.411774Z","time spent":"449.131326ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:45:25.86111Z","caller":"traceutil/trace.go:171","msg":"trace[1441049836] linearizableReadLoop","detail":"{readStateIndex:22826; appliedIndex:22823; }","duration":"119.580504ms","start":"2026-05-06T05:45:25.74152Z","end":"2026-05-06T05:45:25.861101Z","steps":["trace[1441049836] 'read index received' (duration: 28.405082ms)","trace[1441049836] 'applied index is now lower than readState.Index' (duration: 91.174172ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:45:25.861094Z","caller":"traceutil/trace.go:171","msg":"trace[1430030164] transaction","detail":"{read_only:false; response_revision:22103; number_of_response:1; }","duration":"345.26632ms","start":"2026-05-06T05:45:25.515768Z","end":"2026-05-06T05:45:25.861034Z","steps":["trace[1430030164] 'process raft request' (duration: 345.008244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:45:25.861217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.786329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:45:25.861246Z","caller":"traceutil/trace.go:171","msg":"trace[1690800415] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22104; }","duration":"119.856561ms","start":"2026-05-06T05:45:25.741381Z","end":"2026-05-06T05:45:25.861237Z","steps":["trace[1690800415] 'agreement among raft nodes before linearized reading' (duration: 119.778759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:45:25.861245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:45:25.51571Z","time spent":"345.455384ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:45:25.860667Z","caller":"traceutil/trace.go:171","msg":"trace[597218970] transaction","detail":"{read_only:false; response_revision:22101; number_of_response:1; }","duration":"500.243445ms","start":"2026-05-06T05:45:25.360393Z","end":"2026-05-06T05:45:25.860636Z","steps":["trace[597218970] 'process raft request' (duration: 409.617276ms)","trace[597218970] 'compare' (duration: 90.365102ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:45:25.861649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:45:25.360366Z","time spent":"501.235089ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:48:03.312577Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20417} {"level":"info","ts":"2026-05-06T05:48:03.427625Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20417,"took":"110.182858ms","hash":1334415010,"current-db-size-bytes":58359808,"current-db-size":"58 MB","current-db-size-in-use-bytes":42487808,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-05-06T05:48:03.427707Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1334415010,"revision":20417,"compact-revision":18184} {"level":"info","ts":"2026-05-06T05:48:42.133115Z","caller":"traceutil/trace.go:171","msg":"trace[1000312857] transaction","detail":"{read_only:false; response_revision:24064; number_of_response:1; }","duration":"145.422576ms","start":"2026-05-06T05:48:41.987659Z","end":"2026-05-06T05:48:42.133082Z","steps":["trace[1000312857] 'process raft request' (duration: 145.216461ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:42.146944Z","caller":"traceutil/trace.go:171","msg":"trace[1492460147] linearizableReadLoop","detail":"{readStateIndex:24841; appliedIndex:24839; }","duration":"158.514341ms","start":"2026-05-06T05:48:41.988401Z","end":"2026-05-06T05:48:42.146915Z","steps":["trace[1492460147] 'read index received' (duration: 144.402012ms)","trace[1492460147] 'applied index is now lower than readState.Index' (duration: 14.111589ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:42.147224Z","caller":"traceutil/trace.go:171","msg":"trace[1148913302] transaction","detail":"{read_only:false; response_revision:24065; number_of_response:1; }","duration":"159.060964ms","start":"2026-05-06T05:48:41.988154Z","end":"2026-05-06T05:48:42.147215Z","steps":["trace[1148913302] 'process raft request' (duration: 158.631804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:42.147484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.065304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-06T05:48:42.147525Z","caller":"traceutil/trace.go:171","msg":"trace[955760190] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24065; }","duration":"159.128015ms","start":"2026-05-06T05:48:41.988384Z","end":"2026-05-06T05:48:42.147512Z","steps":["trace[955760190] 'agreement among raft nodes before linearized reading' (duration: 159.012452ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:42.14765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.621172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:48:42.147686Z","caller":"traceutil/trace.go:171","msg":"trace[1464645946] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:24065; }","duration":"143.651183ms","start":"2026-05-06T05:48:42.004019Z","end":"2026-05-06T05:48:42.14767Z","steps":["trace[1464645946] 'agreement among raft nodes before linearized reading' (duration: 143.597921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:42.41353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.078283ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:48:42.413722Z","caller":"traceutil/trace.go:171","msg":"trace[1876125883] transaction","detail":"{read_only:false; response_revision:24066; number_of_response:1; }","duration":"261.957937ms","start":"2026-05-06T05:48:42.151741Z","end":"2026-05-06T05:48:42.413699Z","steps":["trace[1876125883] 'process raft request' (duration: 127.611467ms)","trace[1876125883] 'compare' (duration: 133.845518ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:48:42.554236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.561599ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-06T05:48:42.555063Z","caller":"traceutil/trace.go:171","msg":"trace[91536311] linearizableReadLoop","detail":"{readStateIndex:24843; appliedIndex:24841; }","duration":"289.446018ms","start":"2026-05-06T05:48:42.265589Z","end":"2026-05-06T05:48:42.555035Z","steps":["trace[91536311] 'read index received' (duration: 13.741701ms)","trace[91536311] 'applied index is now lower than readState.Index' (duration: 275.702797ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:42.555162Z","caller":"traceutil/trace.go:171","msg":"trace[36000833] transaction","detail":"{read_only:false; response_revision:24067; number_of_response:1; }","duration":"287.702276ms","start":"2026-05-06T05:48:42.26745Z","end":"2026-05-06T05:48:42.555152Z","steps":["trace[36000833] 'process raft request' (duration: 286.896746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:42.698226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.499007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-06T05:48:42.698473Z","caller":"traceutil/trace.go:171","msg":"trace[340798570] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:24067; }","duration":"432.905897ms","start":"2026-05-06T05:48:42.265536Z","end":"2026-05-06T05:48:42.698442Z","steps":["trace[340798570] 'agreement among raft nodes before linearized reading' (duration: 289.594572ms)","trace[340798570] 'count revisions from in-memory index tree' (duration: 142.914555ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:48:42.698564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:42.265512Z","time spent":"433.03401ms","remote":"127.0.0.1:44306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-05-06T05:48:42.738209Z","caller":"traceutil/trace.go:171","msg":"trace[1342606071] transaction","detail":"{read_only:false; response_revision:24069; number_of_response:1; }","duration":"105.65385ms","start":"2026-05-06T05:48:42.632534Z","end":"2026-05-06T05:48:42.738188Z","steps":["trace[1342606071] 'process raft request' (duration: 105.384514ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:42.738264Z","caller":"traceutil/trace.go:171","msg":"trace[1683683314] transaction","detail":"{read_only:false; response_revision:24070; number_of_response:1; }","duration":"105.563968ms","start":"2026-05-06T05:48:42.63266Z","end":"2026-05-06T05:48:42.738224Z","steps":["trace[1683683314] 'process raft request' (duration: 105.437415ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:46.940558Z","caller":"traceutil/trace.go:171","msg":"trace[1664663064] linearizableReadLoop","detail":"{readStateIndex:24883; appliedIndex:24881; }","duration":"187.503017ms","start":"2026-05-06T05:48:46.753026Z","end":"2026-05-06T05:48:46.940529Z","steps":["trace[1664663064] 'read index received' (duration: 13.067654ms)","trace[1664663064] 'applied index is now lower than readState.Index' (duration: 174.433993ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:46.940624Z","caller":"traceutil/trace.go:171","msg":"trace[911165201] transaction","detail":"{read_only:false; response_revision:24106; number_of_response:1; }","duration":"188.344587ms","start":"2026-05-06T05:48:46.752248Z","end":"2026-05-06T05:48:46.940592Z","steps":["trace[911165201] 'process raft request' (duration: 135.063907ms)","trace[911165201] 'compare' (duration: 52.967293ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:48:46.940764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.711533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:48:46.941649Z","caller":"traceutil/trace.go:171","msg":"trace[2090700829] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24106; }","duration":"188.577294ms","start":"2026-05-06T05:48:46.752992Z","end":"2026-05-06T05:48:46.94157Z","steps":["trace[2090700829] 'agreement among raft nodes before linearized reading' (duration: 187.705252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:46.941717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.866291ms","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-05-06T05:48:46.941762Z","caller":"traceutil/trace.go:171","msg":"trace[1401650258] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24106; }","duration":"183.920332ms","start":"2026-05-06T05:48:46.757829Z","end":"2026-05-06T05:48:46.94175Z","steps":["trace[1401650258] 'agreement among raft nodes before linearized reading' (duration: 183.761368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:46.941897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.627032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.73\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-06T05:48:46.941979Z","caller":"traceutil/trace.go:171","msg":"trace[641658285] range","detail":"{range_begin:/registry/masterleases/199.19.213.73; range_end:; response_count:1; response_revision:24106; }","duration":"108.917868ms","start":"2026-05-06T05:48:46.833048Z","end":"2026-05-06T05:48:46.941965Z","steps":["trace[641658285] 'agreement among raft nodes before linearized reading' (duration: 108.547599ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:47.19498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.317128ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-05-06T05:48:47.195548Z","caller":"traceutil/trace.go:171","msg":"trace[1564456674] transaction","detail":"{read_only:false; response_revision:24107; number_of_response:1; }","duration":"249.797695ms","start":"2026-05-06T05:48:46.945731Z","end":"2026-05-06T05:48:47.195528Z","steps":["trace[1564456674] 'process raft request' (duration: 249.337544ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:47.398939Z","caller":"traceutil/trace.go:171","msg":"trace[1697377794] transaction","detail":"{read_only:false; response_revision:24108; number_of_response:1; }","duration":"202.258722ms","start":"2026-05-06T05:48:47.196643Z","end":"2026-05-06T05:48:47.398902Z","steps":["trace[1697377794] 'process raft request' (duration: 144.823201ms)","trace[1697377794] 'compare' (duration: 57.197615ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:47.39894Z","caller":"traceutil/trace.go:171","msg":"trace[543627828] linearizableReadLoop","detail":"{readStateIndex:24887; appliedIndex:24885; }","duration":"191.667238ms","start":"2026-05-06T05:48:47.207257Z","end":"2026-05-06T05:48:47.398924Z","steps":["trace[543627828] 'read index received' (duration: 134.215547ms)","trace[543627828] 'applied index is now lower than readState.Index' (duration: 57.450881ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:48:47.399137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.866383ms","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-05-06T05:48:47.399182Z","caller":"traceutil/trace.go:171","msg":"trace[866847834] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24110; }","duration":"191.960155ms","start":"2026-05-06T05:48:47.207213Z","end":"2026-05-06T05:48:47.399173Z","steps":["trace[866847834] 'agreement among raft nodes before linearized reading' (duration: 191.770621ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:47.399247Z","caller":"traceutil/trace.go:171","msg":"trace[2061708071] transaction","detail":"{read_only:false; response_revision:24110; number_of_response:1; }","duration":"152.62469ms","start":"2026-05-06T05:48:47.246598Z","end":"2026-05-06T05:48:47.399223Z","steps":["trace[2061708071] 'process raft request' (duration: 152.283401ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:47.39958Z","caller":"traceutil/trace.go:171","msg":"trace[2108248935] transaction","detail":"{read_only:false; response_revision:24109; number_of_response:1; }","duration":"199.310181ms","start":"2026-05-06T05:48:47.200147Z","end":"2026-05-06T05:48:47.399457Z","steps":["trace[2108248935] 'process raft request' (duration: 198.661656ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:47.671221Z","caller":"traceutil/trace.go:171","msg":"trace[1275837501] transaction","detail":"{read_only:false; response_revision:24112; number_of_response:1; }","duration":"165.125559ms","start":"2026-05-06T05:48:47.506066Z","end":"2026-05-06T05:48:47.671192Z","steps":["trace[1275837501] 'process raft request' (duration: 101.000218ms)","trace[1275837501] 'compare' (duration: 63.928027ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:47.81808Z","caller":"traceutil/trace.go:171","msg":"trace[309611224] linearizableReadLoop","detail":"{readStateIndex:24891; appliedIndex:24890; }","duration":"141.244915ms","start":"2026-05-06T05:48:47.67681Z","end":"2026-05-06T05:48:47.818055Z","steps":["trace[309611224] 'read index received' (duration: 104.446831ms)","trace[309611224] 'applied index is now lower than readState.Index' (duration: 36.797164ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:47.818111Z","caller":"traceutil/trace.go:171","msg":"trace[1785676539] transaction","detail":"{read_only:false; response_revision:24113; number_of_response:1; }","duration":"272.761406ms","start":"2026-05-06T05:48:47.545316Z","end":"2026-05-06T05:48:47.818077Z","steps":["trace[1785676539] 'process raft request' (duration: 235.86286ms)","trace[1785676539] 'compare' (duration: 36.767133ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:48:47.818509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.683736ms","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-05-06T05:48:47.818562Z","caller":"traceutil/trace.go:171","msg":"trace[1791792922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24113; }","duration":"141.786229ms","start":"2026-05-06T05:48:47.676764Z","end":"2026-05-06T05:48:47.81855Z","steps":["trace[1791792922] 'agreement among raft nodes before linearized reading' (duration: 141.431159ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:48.112313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.182941ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:48:48.112403Z","caller":"traceutil/trace.go:171","msg":"trace[1150127190] linearizableReadLoop","detail":"{readStateIndex:24892; appliedIndex:24891; }","duration":"288.881474ms","start":"2026-05-06T05:48:47.823506Z","end":"2026-05-06T05:48:48.112388Z","steps":["trace[1150127190] 'read index received' (duration: 113.466328ms)","trace[1150127190] 'applied index is now lower than readState.Index' (duration: 175.412456ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:48.112559Z","caller":"traceutil/trace.go:171","msg":"trace[1741500717] transaction","detail":"{read_only:false; response_revision:24114; number_of_response:1; }","duration":"289.118041ms","start":"2026-05-06T05:48:47.823431Z","end":"2026-05-06T05:48:48.112549Z","steps":["trace[1741500717] 'process raft request' (duration: 113.633682ms)","trace[1741500717] 'compare' (duration: 175.041767ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:48:48.112942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.250076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-05-06T05:48:48.112963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.414748ms","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-05-06T05:48:48.113061Z","caller":"traceutil/trace.go:171","msg":"trace[508668603] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24114; }","duration":"203.42074ms","start":"2026-05-06T05:48:47.909609Z","end":"2026-05-06T05:48:48.11303Z","steps":["trace[508668603] 'agreement among raft nodes before linearized reading' (duration: 203.075972ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:48.113096Z","caller":"traceutil/trace.go:171","msg":"trace[1212152786] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24114; }","duration":"289.606442ms","start":"2026-05-06T05:48:47.823462Z","end":"2026-05-06T05:48:48.113069Z","steps":["trace[1212152786] 'agreement among raft nodes before linearized reading' (duration: 289.355506ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:48.444899Z","caller":"traceutil/trace.go:171","msg":"trace[68790417] linearizableReadLoop","detail":"{readStateIndex:24896; appliedIndex:24893; }","duration":"181.609726ms","start":"2026-05-06T05:48:48.263259Z","end":"2026-05-06T05:48:48.444868Z","steps":["trace[68790417] 'read index received' (duration: 16.463006ms)","trace[68790417] 'applied index is now lower than readState.Index' (duration: 165.14531ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:48.444905Z","caller":"traceutil/trace.go:171","msg":"trace[1220537884] transaction","detail":"{read_only:false; response_revision:24117; number_of_response:1; }","duration":"182.291132ms","start":"2026-05-06T05:48:48.262574Z","end":"2026-05-06T05:48:48.444865Z","steps":["trace[1220537884] 'process raft request' (duration: 181.799891ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:48.44515Z","caller":"traceutil/trace.go:171","msg":"trace[890100549] transaction","detail":"{read_only:false; response_revision:24118; number_of_response:1; }","duration":"182.139209ms","start":"2026-05-06T05:48:48.262887Z","end":"2026-05-06T05:48:48.445026Z","steps":["trace[890100549] 'process raft request' (duration: 181.920794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:48.445352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.078617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-06T05:48:48.44544Z","caller":"traceutil/trace.go:171","msg":"trace[1868029568] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24118; }","duration":"182.195731ms","start":"2026-05-06T05:48:48.263231Z","end":"2026-05-06T05:48:48.445426Z","steps":["trace[1868029568] 'agreement among raft nodes before linearized reading' (duration: 181.856663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:48.735694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.194066ms","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-05-06T05:48:48.735832Z","caller":"traceutil/trace.go:171","msg":"trace[1813632529] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:24119; }","duration":"196.345689ms","start":"2026-05-06T05:48:48.539466Z","end":"2026-05-06T05:48:48.735812Z","steps":["trace[1813632529] 'range keys from in-memory index tree' (duration: 196.179625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:48.735992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.335058ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:48:48.736202Z","caller":"traceutil/trace.go:171","msg":"trace[2020619852] transaction","detail":"{read_only:false; response_revision:24120; number_of_response:1; }","duration":"277.144502ms","start":"2026-05-06T05:48:48.459031Z","end":"2026-05-06T05:48:48.736175Z","steps":["trace[2020619852] 'process raft request' (duration: 110.469626ms)","trace[2020619852] 'compare' (duration: 166.075762ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:48.782049Z","caller":"traceutil/trace.go:171","msg":"trace[488604630] transaction","detail":"{read_only:false; response_revision:24121; number_of_response:1; }","duration":"319.657034ms","start":"2026-05-06T05:48:48.462359Z","end":"2026-05-06T05:48:48.782016Z","steps":["trace[488604630] 'process raft request' (duration: 319.221103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:48.782324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:48.462334Z","time spent":"319.87256ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:48:48.782329Z","caller":"traceutil/trace.go:171","msg":"trace[1645855231] transaction","detail":"{read_only:false; response_revision:24122; number_of_response:1; }","duration":"318.846784ms","start":"2026-05-06T05:48:48.463459Z","end":"2026-05-06T05:48:48.782306Z","steps":["trace[1645855231] 'process raft request' (duration: 318.367072ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:48.782209Z","caller":"traceutil/trace.go:171","msg":"trace[447694388] linearizableReadLoop","detail":"{readStateIndex:24900; appliedIndex:24897; }","duration":"315.031113ms","start":"2026-05-06T05:48:48.467143Z","end":"2026-05-06T05:48:48.782174Z","steps":["trace[447694388] 'read index received' (duration: 102.3487ms)","trace[447694388] 'applied index is now lower than readState.Index' (duration: 212.681043ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:48:48.782542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:48.463449Z","time spent":"319.033489ms","remote":"127.0.0.1:53694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2569,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:48:48.78272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.548425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-05-06T05:48:48.783047Z","caller":"traceutil/trace.go:171","msg":"trace[18044211] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24122; }","duration":"315.943955ms","start":"2026-05-06T05:48:48.467086Z","end":"2026-05-06T05:48:48.78303Z","steps":["trace[18044211] 'agreement among raft nodes before linearized reading' (duration: 315.30833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:48.783099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:48.467069Z","time spent":"316.017087ms","remote":"127.0.0.1:39814","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5706,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-05-06T05:48:49.003521Z","caller":"traceutil/trace.go:171","msg":"trace[795569020] linearizableReadLoop","detail":"{readStateIndex:24903; appliedIndex:24900; }","duration":"220.939181ms","start":"2026-05-06T05:48:48.782552Z","end":"2026-05-06T05:48:49.003491Z","steps":["trace[795569020] 'read index received' (duration: 220.51212ms)","trace[795569020] 'applied index is now lower than readState.Index' (duration: 425.901µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:49.003538Z","caller":"traceutil/trace.go:171","msg":"trace[823491811] transaction","detail":"{read_only:false; response_revision:24123; number_of_response:1; }","duration":"428.540882ms","start":"2026-05-06T05:48:48.574965Z","end":"2026-05-06T05:48:49.003506Z","steps":["trace[823491811] 'process raft request' (duration: 428.056349ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:49.003678Z","caller":"traceutil/trace.go:171","msg":"trace[2101961499] transaction","detail":"{read_only:false; response_revision:24124; number_of_response:1; }","duration":"246.511236ms","start":"2026-05-06T05:48:48.757137Z","end":"2026-05-06T05:48:49.003648Z","steps":["trace[2101961499] 'process raft request' (duration: 246.24213ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:49.003736Z","caller":"traceutil/trace.go:171","msg":"trace[172783724] transaction","detail":"{read_only:false; response_revision:24125; number_of_response:1; }","duration":"232.51176ms","start":"2026-05-06T05:48:48.771201Z","end":"2026-05-06T05:48:49.003712Z","steps":["trace[172783724] 'process raft request' (duration: 232.241943ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:49.003758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.169483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/container-infra-registry.18ace3e1d44fe504\" ","response":"range_response_count:1 size:722"} {"level":"info","ts":"2026-05-06T05:48:49.003825Z","caller":"traceutil/trace.go:171","msg":"trace[1181308904] range","detail":"{range_begin:/registry/events/openstack/container-infra-registry.18ace3e1d44fe504; range_end:; response_count:1; response_revision:24125; }","duration":"262.285066ms","start":"2026-05-06T05:48:48.741515Z","end":"2026-05-06T05:48:49.0038Z","steps":["trace[1181308904] 'agreement among raft nodes before linearized reading' (duration: 262.088961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:49.003953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:48.574919Z","time spent":"428.844949ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:48:49.157101Z","caller":"traceutil/trace.go:171","msg":"trace[1074826683] transaction","detail":"{read_only:false; response_revision:24127; number_of_response:1; }","duration":"210.09759ms","start":"2026-05-06T05:48:48.946975Z","end":"2026-05-06T05:48:49.157073Z","steps":["trace[1074826683] 'process raft request' (duration: 209.951297ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:49.157165Z","caller":"traceutil/trace.go:171","msg":"trace[713554372] transaction","detail":"{read_only:false; response_revision:24126; number_of_response:1; }","duration":"366.333426ms","start":"2026-05-06T05:48:48.790793Z","end":"2026-05-06T05:48:49.157126Z","steps":["trace[713554372] 'process raft request' (duration: 347.113505ms)","trace[713554372] 'compare' (duration: 18.605476ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:48:49.157375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:48.790773Z","time spent":"366.485499ms","remote":"127.0.0.1:53694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3421,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:48:49.157384Z","caller":"traceutil/trace.go:171","msg":"trace[1434163339] transaction","detail":"{read_only:false; response_revision:24128; number_of_response:1; }","duration":"150.804015ms","start":"2026-05-06T05:48:49.006561Z","end":"2026-05-06T05:48:49.157365Z","steps":["trace[1434163339] 'process raft request' (duration: 150.454026ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:49.445296Z","caller":"traceutil/trace.go:171","msg":"trace[881741631] linearizableReadLoop","detail":"{readStateIndex:24907; appliedIndex:24906; }","duration":"245.87345ms","start":"2026-05-06T05:48:49.199397Z","end":"2026-05-06T05:48:49.44527Z","steps":["trace[881741631] 'read index received' (duration: 245.85387ms)","trace[881741631] 'applied index is now lower than readState.Index' (duration: 18.44µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:49.445356Z","caller":"traceutil/trace.go:171","msg":"trace[681415502] transaction","detail":"{read_only:false; response_revision:24129; number_of_response:1; }","duration":"280.696597ms","start":"2026-05-06T05:48:49.16462Z","end":"2026-05-06T05:48:49.445316Z","steps":["trace[681415502] 'process raft request' (duration: 280.498853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:49.445609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.204088ms","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-05-06T05:48:49.445698Z","caller":"traceutil/trace.go:171","msg":"trace[281383849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24129; }","duration":"246.329372ms","start":"2026-05-06T05:48:49.199329Z","end":"2026-05-06T05:48:49.445658Z","steps":["trace[281383849] 'agreement among raft nodes before linearized reading' (duration: 246.082726ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:49.663852Z","caller":"traceutil/trace.go:171","msg":"trace[763058134] transaction","detail":"{read_only:false; response_revision:24130; number_of_response:1; }","duration":"279.862817ms","start":"2026-05-06T05:48:49.383939Z","end":"2026-05-06T05:48:49.663802Z","steps":["trace[763058134] 'process raft request' (duration: 279.53547ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:49.664049Z","caller":"traceutil/trace.go:171","msg":"trace[1904635974] transaction","detail":"{read_only:false; response_revision:24131; number_of_response:1; }","duration":"259.856706ms","start":"2026-05-06T05:48:49.404145Z","end":"2026-05-06T05:48:49.664001Z","steps":["trace[1904635974] 'process raft request' (duration: 259.521438ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:49.664245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.177201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:48:49.664316Z","caller":"traceutil/trace.go:171","msg":"trace[2080620005] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0; response_count:0; response_revision:24131; }","duration":"398.314324ms","start":"2026-05-06T05:48:49.265985Z","end":"2026-05-06T05:48:49.664299Z","steps":["trace[2080620005] 'agreement among raft nodes before linearized reading' (duration: 398.11994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:49.664356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:49.265941Z","time spent":"398.404416ms","remote":"127.0.0.1:41642","response type":"/etcdserverpb.KV/Range","request count":0,"request size":140,"response count":0,"response size":28,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackfloatingippools0\" count_only:true "} {"level":"info","ts":"2026-05-06T05:48:49.663964Z","caller":"traceutil/trace.go:171","msg":"trace[586371840] linearizableReadLoop","detail":"{readStateIndex:24909; appliedIndex:24907; }","duration":"218.559864ms","start":"2026-05-06T05:48:49.445379Z","end":"2026-05-06T05:48:49.663939Z","steps":["trace[586371840] 'read index received' (duration: 218.055192ms)","trace[586371840] 'applied index is now lower than readState.Index' (duration: 503.722µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:48:49.664523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.708443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:48:49.664585Z","caller":"traceutil/trace.go:171","msg":"trace[1791642746] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:24131; }","duration":"410.828816ms","start":"2026-05-06T05:48:49.253741Z","end":"2026-05-06T05:48:49.66457Z","steps":["trace[1791642746] 'agreement among raft nodes before linearized reading' (duration: 410.717513ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:49.664776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:49.253719Z","time spent":"410.958429ms","remote":"127.0.0.1:41230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":28,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"info","ts":"2026-05-06T05:48:49.97133Z","caller":"traceutil/trace.go:171","msg":"trace[2094150606] linearizableReadLoop","detail":"{readStateIndex:24911; appliedIndex:24909; }","duration":"306.902618ms","start":"2026-05-06T05:48:49.664403Z","end":"2026-05-06T05:48:49.971306Z","steps":["trace[2094150606] 'read index received' (duration: 269.027878ms)","trace[2094150606] 'applied index is now lower than readState.Index' (duration: 37.87351ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:49.971466Z","caller":"traceutil/trace.go:171","msg":"trace[112334316] transaction","detail":"{read_only:false; response_revision:24133; number_of_response:1; }","duration":"517.550362ms","start":"2026-05-06T05:48:49.45388Z","end":"2026-05-06T05:48:49.97143Z","steps":["trace[112334316] 'process raft request' (duration: 517.357417ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:49.971415Z","caller":"traceutil/trace.go:171","msg":"trace[1266106095] transaction","detail":"{read_only:false; response_revision:24132; number_of_response:1; }","duration":"521.845815ms","start":"2026-05-06T05:48:49.449538Z","end":"2026-05-06T05:48:49.971384Z","steps":["trace[1266106095] 'process raft request' (duration: 483.881232ms)","trace[1266106095] 'compare' (duration: 37.676866ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:48:49.971547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.220068ms","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-05-06T05:48:49.971589Z","caller":"traceutil/trace.go:171","msg":"trace[433542570] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24133; }","duration":"491.31544ms","start":"2026-05-06T05:48:49.480262Z","end":"2026-05-06T05:48:49.971577Z","steps":["trace[433542570] 'agreement among raft nodes before linearized reading' (duration: 491.163377ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:49.971629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:49.480233Z","time spent":"491.387612ms","remote":"127.0.0.1:44118","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":"warn","ts":"2026-05-06T05:48:49.971622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:49.453851Z","time spent":"517.691674ms","remote":"127.0.0.1:53694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3859,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:48:49.971806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:49.449521Z","time spent":"522.178752ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:48:50.056463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.662003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:48:50.056549Z","caller":"traceutil/trace.go:171","msg":"trace[693023841] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24135; }","duration":"316.786575ms","start":"2026-05-06T05:48:49.739742Z","end":"2026-05-06T05:48:50.056528Z","steps":["trace[693023841] 'agreement among raft nodes before linearized reading' (duration: 316.628711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:50.056589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:49.739727Z","time spent":"316.854877ms","remote":"127.0.0.1:57618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T05:48:50.056929Z","caller":"traceutil/trace.go:171","msg":"trace[801232348] transaction","detail":"{read_only:false; response_revision:24134; number_of_response:1; }","duration":"381.06649ms","start":"2026-05-06T05:48:49.675848Z","end":"2026-05-06T05:48:50.056915Z","steps":["trace[801232348] 'process raft request' (duration: 380.321011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:50.057025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:49.675824Z","time spent":"381.142242ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:48:50.057178Z","caller":"traceutil/trace.go:171","msg":"trace[1920952719] transaction","detail":"{read_only:false; response_revision:24135; number_of_response:1; }","duration":"234.111318ms","start":"2026-05-06T05:48:49.823056Z","end":"2026-05-06T05:48:50.057168Z","steps":["trace[1920952719] 'process raft request' (duration: 233.262977ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:50.4112Z","caller":"traceutil/trace.go:171","msg":"trace[276170387] transaction","detail":"{read_only:false; response_revision:24136; number_of_response:1; }","duration":"434.534346ms","start":"2026-05-06T05:48:49.976626Z","end":"2026-05-06T05:48:50.411161Z","steps":["trace[276170387] 'process raft request' (duration: 434.314781ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:50.411335Z","caller":"traceutil/trace.go:171","msg":"trace[1032601693] linearizableReadLoop","detail":"{readStateIndex:24915; appliedIndex:24914; }","duration":"352.021912ms","start":"2026-05-06T05:48:50.059276Z","end":"2026-05-06T05:48:50.411297Z","steps":["trace[1032601693] 'read index received' (duration: 351.783277ms)","trace[1032601693] 'applied index is now lower than readState.Index' (duration: 235.965µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:48:50.411507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.208397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:48:50.41162Z","caller":"traceutil/trace.go:171","msg":"trace[184544853] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24137; }","duration":"352.381151ms","start":"2026-05-06T05:48:50.059216Z","end":"2026-05-06T05:48:50.411597Z","steps":["trace[184544853] 'agreement among raft nodes before linearized reading' (duration: 352.229678ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:50.411681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:50.0592Z","time spent":"352.472853ms","remote":"127.0.0.1:57624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:48:50.411397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:49.976603Z","time spent":"434.701229ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:48:50.411448Z","caller":"traceutil/trace.go:171","msg":"trace[1830039994] transaction","detail":"{read_only:false; response_revision:24137; number_of_response:1; }","duration":"432.080446ms","start":"2026-05-06T05:48:49.979304Z","end":"2026-05-06T05:48:50.411385Z","steps":["trace[1830039994] 'process raft request' (duration: 431.770149ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:50.412158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:49.979283Z","time spent":"432.63727ms","remote":"127.0.0.1:53694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:48:50.488993Z","caller":"traceutil/trace.go:171","msg":"trace[1493679027] transaction","detail":"{read_only:false; response_revision:24138; number_of_response:1; }","duration":"425.15381ms","start":"2026-05-06T05:48:50.063806Z","end":"2026-05-06T05:48:50.48896Z","steps":["trace[1493679027] 'process raft request' (duration: 375.436095ms)","trace[1493679027] 'compare' (duration: 49.570082ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:48:50.489131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.789662ms","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-05-06T05:48:50.489229Z","caller":"traceutil/trace.go:171","msg":"trace[751495980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24138; }","duration":"372.973685ms","start":"2026-05-06T05:48:50.11624Z","end":"2026-05-06T05:48:50.489214Z","steps":["trace[751495980] 'agreement among raft nodes before linearized reading' (duration: 372.695879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:50.489311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:50.116225Z","time spent":"373.040358ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T05:48:50.489151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:50.063791Z","time spent":"425.274553ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":626,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:48:50.489325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.178639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-05-06T05:48:50.489302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.926266ms","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-05-06T05:48:50.489414Z","caller":"traceutil/trace.go:171","msg":"trace[640397436] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24138; }","duration":"229.303293ms","start":"2026-05-06T05:48:50.26009Z","end":"2026-05-06T05:48:50.489393Z","steps":["trace[640397436] 'agreement among raft nodes before linearized reading' (duration: 229.033036ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:50.489469Z","caller":"traceutil/trace.go:171","msg":"trace[1562966049] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24138; }","duration":"205.151651ms","start":"2026-05-06T05:48:50.284295Z","end":"2026-05-06T05:48:50.489447Z","steps":["trace[1562966049] 'agreement among raft nodes before linearized reading' (duration: 204.812223ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:50.640297Z","caller":"traceutil/trace.go:171","msg":"trace[206647021] transaction","detail":"{read_only:false; response_revision:24139; number_of_response:1; }","duration":"219.890607ms","start":"2026-05-06T05:48:50.420371Z","end":"2026-05-06T05:48:50.640261Z","steps":["trace[206647021] 'process raft request' (duration: 201.588706ms)","trace[206647021] 'compare' (duration: 18.055735ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:50.640576Z","caller":"traceutil/trace.go:171","msg":"trace[1354976071] transaction","detail":"{read_only:false; response_revision:24140; number_of_response:1; }","duration":"146.326097ms","start":"2026-05-06T05:48:50.494221Z","end":"2026-05-06T05:48:50.640547Z","steps":["trace[1354976071] 'process raft request' (duration: 145.972109ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:50.641076Z","caller":"traceutil/trace.go:171","msg":"trace[1895775918] transaction","detail":"{read_only:false; response_revision:24141; number_of_response:1; }","duration":"146.515661ms","start":"2026-05-06T05:48:50.494548Z","end":"2026-05-06T05:48:50.641063Z","steps":["trace[1895775918] 'process raft request' (duration: 145.906077ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:50.641133Z","caller":"traceutil/trace.go:171","msg":"trace[629422791] transaction","detail":"{read_only:false; response_revision:24142; number_of_response:1; }","duration":"146.46647ms","start":"2026-05-06T05:48:50.494636Z","end":"2026-05-06T05:48:50.641102Z","steps":["trace[629422791] 'process raft request' (duration: 146.305787ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:50.963814Z","caller":"traceutil/trace.go:171","msg":"trace[1300560276] linearizableReadLoop","detail":"{readStateIndex:24921; appliedIndex:24920; }","duration":"178.181693ms","start":"2026-05-06T05:48:50.785611Z","end":"2026-05-06T05:48:50.963793Z","steps":["trace[1300560276] 'read index received' (duration: 123.792626ms)","trace[1300560276] 'applied index is now lower than readState.Index' (duration: 54.385257ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:50.963869Z","caller":"traceutil/trace.go:171","msg":"trace[1224253764] transaction","detail":"{read_only:false; response_revision:24143; number_of_response:1; }","duration":"316.917808ms","start":"2026-05-06T05:48:50.646911Z","end":"2026-05-06T05:48:50.963829Z","steps":["trace[1224253764] 'process raft request' (duration: 262.48371ms)","trace[1224253764] 'compare' (duration: 54.283134ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:48:50.964038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.392528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-05-06T05:48:50.964012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:50.646892Z","time spent":"317.046021ms","remote":"127.0.0.1:53694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:48:50.9641Z","caller":"traceutil/trace.go:171","msg":"trace[1356122506] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24143; }","duration":"178.50482ms","start":"2026-05-06T05:48:50.78558Z","end":"2026-05-06T05:48:50.964085Z","steps":["trace[1356122506] 'agreement among raft nodes before linearized reading' (duration: 178.315985ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:51.280737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.86233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:48:51.280987Z","caller":"traceutil/trace.go:171","msg":"trace[115140643] transaction","detail":"{read_only:false; response_revision:24145; number_of_response:1; }","duration":"311.711204ms","start":"2026-05-06T05:48:50.969244Z","end":"2026-05-06T05:48:51.280956Z","steps":["trace[115140643] 'process raft request' (duration: 144.530195ms)","trace[115140643] 'compare' (duration: 166.717067ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:51.281054Z","caller":"traceutil/trace.go:171","msg":"trace[313529615] transaction","detail":"{read_only:false; response_revision:24148; number_of_response:1; }","duration":"270.623456ms","start":"2026-05-06T05:48:51.010412Z","end":"2026-05-06T05:48:51.281035Z","steps":["trace[313529615] 'process raft request' (duration: 270.553364ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:51.28114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:50.969237Z","time spent":"311.820196ms","remote":"127.0.0.1:53694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2537,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:48:51.281108Z","caller":"traceutil/trace.go:171","msg":"trace[862614148] transaction","detail":"{read_only:false; response_revision:24147; number_of_response:1; }","duration":"270.925283ms","start":"2026-05-06T05:48:51.010142Z","end":"2026-05-06T05:48:51.281067Z","steps":["trace[862614148] 'process raft request' (duration: 270.770839ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:51.281567Z","caller":"traceutil/trace.go:171","msg":"trace[323227586] transaction","detail":"{read_only:false; response_revision:24146; number_of_response:1; }","duration":"271.437275ms","start":"2026-05-06T05:48:51.01007Z","end":"2026-05-06T05:48:51.281507Z","steps":["trace[323227586] 'process raft request' (duration: 270.778559ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:51.413161Z","caller":"traceutil/trace.go:171","msg":"trace[2012955569] transaction","detail":"{read_only:false; response_revision:24150; number_of_response:1; }","duration":"166.940303ms","start":"2026-05-06T05:48:51.246192Z","end":"2026-05-06T05:48:51.413132Z","steps":["trace[2012955569] 'process raft request' (duration: 166.879102ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:48:51.413197Z","caller":"traceutil/trace.go:171","msg":"trace[1986395322] transaction","detail":"{read_only:false; response_revision:24149; number_of_response:1; }","duration":"249.918998ms","start":"2026-05-06T05:48:51.163244Z","end":"2026-05-06T05:48:51.413163Z","steps":["trace[1986395322] 'process raft request' (duration: 229.727203ms)","trace[1986395322] 'compare' (duration: 19.951149ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:51.434346Z","caller":"traceutil/trace.go:171","msg":"trace[484755677] linearizableReadLoop","detail":"{readStateIndex:24929; appliedIndex:24926; }","duration":"106.830138ms","start":"2026-05-06T05:48:51.327491Z","end":"2026-05-06T05:48:51.434322Z","steps":["trace[484755677] 'read index received' (duration: 65.553627ms)","trace[484755677] 'applied index is now lower than readState.Index' (duration: 41.274651ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:48:51.434472Z","caller":"traceutil/trace.go:171","msg":"trace[1658591599] transaction","detail":"{read_only:false; response_revision:24151; number_of_response:1; }","duration":"146.492551ms","start":"2026-05-06T05:48:51.287935Z","end":"2026-05-06T05:48:51.434427Z","steps":["trace[1658591599] 'process raft request' (duration: 146.207345ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:51.434569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.062314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/endpoints/\" range_end:\"/registry/openstack.k-orc.cloud/endpoints0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:48:51.434649Z","caller":"traceutil/trace.go:171","msg":"trace[141606083] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/endpoints/; range_end:/registry/openstack.k-orc.cloud/endpoints0; response_count:0; response_revision:24151; }","duration":"107.211237ms","start":"2026-05-06T05:48:51.327413Z","end":"2026-05-06T05:48:51.434624Z","steps":["trace[141606083] 'agreement among raft nodes before linearized reading' (duration: 106.997842ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:51.768917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.195217ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:48:51.769022Z","caller":"traceutil/trace.go:171","msg":"trace[1574620058] transaction","detail":"{read_only:false; response_revision:24152; number_of_response:1; }","duration":"327.479433ms","start":"2026-05-06T05:48:51.441521Z","end":"2026-05-06T05:48:51.769Z","steps":["trace[1574620058] 'process raft request' (duration: 141.116712ms)","trace[1574620058] 'compare' (duration: 186.080674ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:48:51.769076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:48:51.441497Z","time spent":"327.555604ms","remote":"127.0.0.1:53694","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2561,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:48:51.777403Z","caller":"traceutil/trace.go:171","msg":"trace[1491498835] transaction","detail":"{read_only:false; response_revision:24153; number_of_response:1; }","duration":"108.098859ms","start":"2026-05-06T05:48:51.66928Z","end":"2026-05-06T05:48:51.777379Z","steps":["trace[1491498835] 'process raft request' (duration: 107.612428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:48:51.867568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.001887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/volumetypes/\" range_end:\"/registry/openstack.k-orc.cloud/volumetypes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:48:51.86765Z","caller":"traceutil/trace.go:171","msg":"trace[385973974] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/volumetypes/; range_end:/registry/openstack.k-orc.cloud/volumetypes0; response_count:0; response_revision:24154; }","duration":"118.15129ms","start":"2026-05-06T05:48:51.749478Z","end":"2026-05-06T05:48:51.86763Z","steps":["trace[385973974] 'agreement among raft nodes before linearized reading' (duration: 117.990636ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:03.305529Z","caller":"traceutil/trace.go:171","msg":"trace[367293731] transaction","detail":"{read_only:false; response_revision:24303; number_of_response:1; }","duration":"260.596454ms","start":"2026-05-06T05:49:03.044906Z","end":"2026-05-06T05:49:03.305502Z","steps":["trace[367293731] 'process raft request' (duration: 260.43701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:03.611036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.254783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:03.611464Z","caller":"traceutil/trace.go:171","msg":"trace[1895407535] transaction","detail":"{read_only:false; response_revision:24305; number_of_response:1; }","duration":"284.878719ms","start":"2026-05-06T05:49:03.326551Z","end":"2026-05-06T05:49:03.611429Z","steps":["trace[1895407535] 'process raft request' (duration: 141.110503ms)","trace[1895407535] 'compare' (duration: 142.833653ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:03.611616Z","caller":"traceutil/trace.go:171","msg":"trace[1181296893] linearizableReadLoop","detail":"{readStateIndex:25087; appliedIndex:25085; }","duration":"172.613239ms","start":"2026-05-06T05:49:03.438723Z","end":"2026-05-06T05:49:03.611336Z","steps":["trace[1181296893] 'read index received' (duration: 29.035428ms)","trace[1181296893] 'applied index is now lower than readState.Index' (duration: 143.576271ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:03.611655Z","caller":"traceutil/trace.go:171","msg":"trace[868632707] transaction","detail":"{read_only:false; response_revision:24306; number_of_response:1; }","duration":"281.444215ms","start":"2026-05-06T05:49:03.330175Z","end":"2026-05-06T05:49:03.611619Z","steps":["trace[868632707] 'process raft request' (duration: 280.971344ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:03.61182Z","caller":"traceutil/trace.go:171","msg":"trace[779804141] transaction","detail":"{read_only:false; response_revision:24307; number_of_response:1; }","duration":"150.851186ms","start":"2026-05-06T05:49:03.460893Z","end":"2026-05-06T05:49:03.611744Z","steps":["trace[779804141] 'process raft request' (duration: 150.379105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:03.611936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.187284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4a899c0-67da-49cb-b082-85970fe3f6d4\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-05-06T05:49:03.612003Z","caller":"traceutil/trace.go:171","msg":"trace[1064434609] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a4a899c0-67da-49cb-b082-85970fe3f6d4; range_end:; response_count:1; response_revision:24307; }","duration":"173.324337ms","start":"2026-05-06T05:49:03.438662Z","end":"2026-05-06T05:49:03.611987Z","steps":["trace[1064434609] 'agreement among raft nodes before linearized reading' (duration: 172.987789ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:03.832865Z","caller":"traceutil/trace.go:171","msg":"trace[361499526] linearizableReadLoop","detail":"{readStateIndex:25089; appliedIndex:25088; }","duration":"154.286199ms","start":"2026-05-06T05:49:03.678543Z","end":"2026-05-06T05:49:03.832829Z","steps":["trace[361499526] 'read index received' (duration: 105.053876ms)","trace[361499526] 'applied index is now lower than readState.Index' (duration: 49.230873ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:03.83301Z","caller":"traceutil/trace.go:171","msg":"trace[383392771] transaction","detail":"{read_only:false; response_revision:24308; number_of_response:1; }","duration":"213.909542ms","start":"2026-05-06T05:49:03.619086Z","end":"2026-05-06T05:49:03.832996Z","steps":["trace[383392771] 'process raft request' (duration: 164.531555ms)","trace[383392771] 'compare' (duration: 49.042739ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:03.833232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.649937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:03.833342Z","caller":"traceutil/trace.go:171","msg":"trace[34690216] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:24308; }","duration":"154.78154ms","start":"2026-05-06T05:49:03.678537Z","end":"2026-05-06T05:49:03.833318Z","steps":["trace[34690216] 'agreement among raft nodes before linearized reading' (duration: 154.591306ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:04.22684Z","caller":"traceutil/trace.go:171","msg":"trace[1019499489] linearizableReadLoop","detail":"{readStateIndex:25090; appliedIndex:25089; }","duration":"393.855188ms","start":"2026-05-06T05:49:03.832956Z","end":"2026-05-06T05:49:04.226811Z","steps":["trace[1019499489] 'read index received' (duration: 393.661953ms)","trace[1019499489] 'applied index is now lower than readState.Index' (duration: 192.165µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:04.226948Z","caller":"traceutil/trace.go:171","msg":"trace[1296967461] transaction","detail":"{read_only:false; response_revision:24309; number_of_response:1; }","duration":"401.896861ms","start":"2026-05-06T05:49:03.825007Z","end":"2026-05-06T05:49:04.226904Z","steps":["trace[1296967461] 'process raft request' (duration: 401.593414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:04.227012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"486.391323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:04.227078Z","caller":"traceutil/trace.go:171","msg":"trace[683269205] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24309; }","duration":"486.529256ms","start":"2026-05-06T05:49:03.740536Z","end":"2026-05-06T05:49:04.227066Z","steps":["trace[683269205] 'agreement among raft nodes before linearized reading' (duration: 486.412983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:04.227116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:03.824974Z","time spent":"402.057245ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:04.227119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:03.740505Z","time spent":"486.606808ms","remote":"127.0.0.1:57618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T05:49:04.244004Z","caller":"traceutil/trace.go:171","msg":"trace[1599286957] transaction","detail":"{read_only:false; response_revision:24310; number_of_response:1; }","duration":"131.799648ms","start":"2026-05-06T05:49:04.112174Z","end":"2026-05-06T05:49:04.243973Z","steps":["trace[1599286957] 'process raft request' (duration: 131.583083ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:04.244382Z","caller":"traceutil/trace.go:171","msg":"trace[1296195708] transaction","detail":"{read_only:false; response_revision:24311; number_of_response:1; }","duration":"128.493758ms","start":"2026-05-06T05:49:04.115869Z","end":"2026-05-06T05:49:04.244363Z","steps":["trace[1296195708] 'process raft request' (duration: 128.053018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:04.244368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.374394ms","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-05-06T05:49:04.24445Z","caller":"traceutil/trace.go:171","msg":"trace[1669757700] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24311; }","duration":"197.513107ms","start":"2026-05-06T05:49:04.046922Z","end":"2026-05-06T05:49:04.244435Z","steps":["trace[1669757700] 'agreement among raft nodes before linearized reading' (duration: 197.314822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:04.24443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.443428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4a899c0-67da-49cb-b082-85970fe3f6d4\" ","response":"range_response_count:1 size:3763"} {"level":"warn","ts":"2026-05-06T05:49:04.244522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.919767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-06T05:49:04.244669Z","caller":"traceutil/trace.go:171","msg":"trace[644365554] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a4a899c0-67da-49cb-b082-85970fe3f6d4; range_end:; response_count:1; response_revision:24311; }","duration":"210.670404ms","start":"2026-05-06T05:49:04.033939Z","end":"2026-05-06T05:49:04.24461Z","steps":["trace[644365554] 'agreement among raft nodes before linearized reading' (duration: 210.336596ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:04.244723Z","caller":"traceutil/trace.go:171","msg":"trace[1496728490] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24311; }","duration":"245.155503ms","start":"2026-05-06T05:49:03.999555Z","end":"2026-05-06T05:49:04.244711Z","steps":["trace[1496728490] 'agreement among raft nodes before linearized reading' (duration: 244.731533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:04.523562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.792421ms","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-05-06T05:49:04.523688Z","caller":"traceutil/trace.go:171","msg":"trace[1026311827] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24312; }","duration":"149.986535ms","start":"2026-05-06T05:49:04.373669Z","end":"2026-05-06T05:49:04.523656Z","steps":["trace[1026311827] 'range keys from in-memory index tree' (duration: 149.669949ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:04.866036Z","caller":"traceutil/trace.go:171","msg":"trace[1893416027] transaction","detail":"{read_only:false; response_revision:24314; number_of_response:1; }","duration":"136.129253ms","start":"2026-05-06T05:49:04.729869Z","end":"2026-05-06T05:49:04.865998Z","steps":["trace[1893416027] 'process raft request' (duration: 135.891337ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:05.204659Z","caller":"traceutil/trace.go:171","msg":"trace[361925117] linearizableReadLoop","detail":"{readStateIndex:25097; appliedIndex:25094; }","duration":"435.689304ms","start":"2026-05-06T05:49:04.768937Z","end":"2026-05-06T05:49:05.204626Z","steps":["trace[361925117] 'read index received' (duration: 96.837628ms)","trace[361925117] 'applied index is now lower than readState.Index' (duration: 338.850276ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:05.204899Z","caller":"traceutil/trace.go:171","msg":"trace[821418372] transaction","detail":"{read_only:false; response_revision:24315; number_of_response:1; }","duration":"474.759693ms","start":"2026-05-06T05:49:04.730076Z","end":"2026-05-06T05:49:05.204836Z","steps":["trace[821418372] 'process raft request' (duration: 474.255711ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:05.204984Z","caller":"traceutil/trace.go:171","msg":"trace[645178693] transaction","detail":"{read_only:false; response_revision:24316; number_of_response:1; }","duration":"472.538099ms","start":"2026-05-06T05:49:04.732394Z","end":"2026-05-06T05:49:05.204932Z","steps":["trace[645178693] 'process raft request' (duration: 472.17263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:05.20504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.098283ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1854"} {"level":"info","ts":"2026-05-06T05:49:05.205111Z","caller":"traceutil/trace.go:171","msg":"trace[1311278290] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24316; }","duration":"436.215916ms","start":"2026-05-06T05:49:04.768874Z","end":"2026-05-06T05:49:05.20509Z","steps":["trace[1311278290] 'agreement among raft nodes before linearized reading' (duration: 435.98768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:05.205102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:04.730048Z","time spent":"474.899096ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:05.205143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:04.768849Z","time spent":"436.287838ms","remote":"127.0.0.1:44012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1876,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-06T05:49:05.205154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:04.732383Z","time spent":"472.681512ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:05.205423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.964992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:05.205524Z","caller":"traceutil/trace.go:171","msg":"trace[1329000287] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:24316; }","duration":"288.133626ms","start":"2026-05-06T05:49:04.917367Z","end":"2026-05-06T05:49:05.2055Z","steps":["trace[1329000287] 'agreement among raft nodes before linearized reading' (duration: 287.931302ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:05.431204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.374914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-06T05:49:05.431289Z","caller":"traceutil/trace.go:171","msg":"trace[1206942761] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24316; }","duration":"120.516077ms","start":"2026-05-06T05:49:05.310753Z","end":"2026-05-06T05:49:05.431269Z","steps":["trace[1206942761] 'range keys from in-memory index tree' (duration: 120.005645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:05.702976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.797185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:05.703252Z","caller":"traceutil/trace.go:171","msg":"trace[1240053982] transaction","detail":"{read_only:false; response_revision:24319; number_of_response:1; }","duration":"193.953332ms","start":"2026-05-06T05:49:05.509279Z","end":"2026-05-06T05:49:05.703233Z","steps":["trace[1240053982] 'process raft request' (duration: 193.821289ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:05.70339Z","caller":"traceutil/trace.go:171","msg":"trace[833246641] transaction","detail":"{read_only:false; response_revision:24318; number_of_response:1; }","duration":"267.309957ms","start":"2026-05-06T05:49:05.436043Z","end":"2026-05-06T05:49:05.703353Z","steps":["trace[833246641] 'process raft request' (duration: 126.006869ms)","trace[833246641] 'compare' (duration: 140.523189ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:06.015198Z","caller":"traceutil/trace.go:171","msg":"trace[640017802] linearizableReadLoop","detail":"{readStateIndex:25103; appliedIndex:25100; }","duration":"277.781677ms","start":"2026-05-06T05:49:05.737377Z","end":"2026-05-06T05:49:06.015159Z","steps":["trace[640017802] 'read index received' (duration: 263.956975ms)","trace[640017802] 'applied index is now lower than readState.Index' (duration: 13.823212ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:06.015246Z","caller":"traceutil/trace.go:171","msg":"trace[217389262] transaction","detail":"{read_only:false; response_revision:24321; number_of_response:1; }","duration":"397.682849ms","start":"2026-05-06T05:49:05.617513Z","end":"2026-05-06T05:49:06.015196Z","steps":["trace[217389262] 'process raft request' (duration: 397.566107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:06.015353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.534668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:06.015382Z","caller":"traceutil/trace.go:171","msg":"trace[270459336] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:24322; }","duration":"249.63737ms","start":"2026-05-06T05:49:05.765734Z","end":"2026-05-06T05:49:06.015371Z","steps":["trace[270459336] 'agreement among raft nodes before linearized reading' (duration: 249.567419ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:06.015364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.968392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:06.015389Z","caller":"traceutil/trace.go:171","msg":"trace[1771812153] transaction","detail":"{read_only:false; response_revision:24320; number_of_response:1; }","duration":"398.217672ms","start":"2026-05-06T05:49:05.617142Z","end":"2026-05-06T05:49:06.015359Z","steps":["trace[1771812153] 'process raft request' (duration: 384.318108ms)","trace[1771812153] 'compare' (duration: 13.27928ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:06.015248Z","caller":"traceutil/trace.go:171","msg":"trace[1323393961] transaction","detail":"{read_only:false; response_revision:24322; number_of_response:1; }","duration":"396.690705ms","start":"2026-05-06T05:49:05.618505Z","end":"2026-05-06T05:49:06.015196Z","steps":["trace[1323393961] 'process raft request' (duration: 396.621244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:06.015456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:05.617481Z","time spent":"397.865414ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:06.015494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:05.617115Z","time spent":"398.327095ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:06.015632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:05.618493Z","time spent":"397.016333ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:49:06.01543Z","caller":"traceutil/trace.go:171","msg":"trace[14283036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24322; }","duration":"278.090554ms","start":"2026-05-06T05:49:05.73732Z","end":"2026-05-06T05:49:06.015411Z","steps":["trace[14283036] 'agreement among raft nodes before linearized reading' (duration: 277.966761ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:06.418112Z","caller":"traceutil/trace.go:171","msg":"trace[2073668280] transaction","detail":"{read_only:false; response_revision:24323; number_of_response:1; }","duration":"184.816783ms","start":"2026-05-06T05:49:06.233272Z","end":"2026-05-06T05:49:06.418089Z","steps":["trace[2073668280] 'process raft request' (duration: 91.167622ms)","trace[2073668280] 'compare' (duration: 93.544688ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:06.462102Z","caller":"traceutil/trace.go:171","msg":"trace[1580052476] linearizableReadLoop","detail":"{readStateIndex:25106; appliedIndex:25103; }","duration":"207.453787ms","start":"2026-05-06T05:49:06.254609Z","end":"2026-05-06T05:49:06.462063Z","steps":["trace[1580052476] 'read index received' (duration: 69.819708ms)","trace[1580052476] 'applied index is now lower than readState.Index' (duration: 137.632569ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:06.462446Z","caller":"traceutil/trace.go:171","msg":"trace[929560597] transaction","detail":"{read_only:false; response_revision:24324; number_of_response:1; }","duration":"212.900378ms","start":"2026-05-06T05:49:06.249499Z","end":"2026-05-06T05:49:06.4624Z","steps":["trace[929560597] 'process raft request' (duration: 212.311064ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:06.462414Z","caller":"traceutil/trace.go:171","msg":"trace[1929838512] transaction","detail":"{read_only:false; response_revision:24325; number_of_response:1; }","duration":"212.689623ms","start":"2026-05-06T05:49:06.249638Z","end":"2026-05-06T05:49:06.462328Z","steps":["trace[1929838512] 'process raft request' (duration: 212.370955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:06.463297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.649065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4a899c0-67da-49cb-b082-85970fe3f6d4\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-05-06T05:49:06.463379Z","caller":"traceutil/trace.go:171","msg":"trace[475138999] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a4a899c0-67da-49cb-b082-85970fe3f6d4; range_end:; response_count:1; response_revision:24325; }","duration":"208.795789ms","start":"2026-05-06T05:49:06.254564Z","end":"2026-05-06T05:49:06.46336Z","steps":["trace[475138999] 'agreement among raft nodes before linearized reading' (duration: 208.474711ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:06.463853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.440758ms","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-05-06T05:49:06.463946Z","caller":"traceutil/trace.go:171","msg":"trace[703841451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24326; }","duration":"190.558311ms","start":"2026-05-06T05:49:06.273372Z","end":"2026-05-06T05:49:06.46393Z","steps":["trace[703841451] 'agreement among raft nodes before linearized reading' (duration: 190.256974ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:06.643848Z","caller":"traceutil/trace.go:171","msg":"trace[912074144] transaction","detail":"{read_only:false; response_revision:24327; number_of_response:1; }","duration":"174.146837ms","start":"2026-05-06T05:49:06.469663Z","end":"2026-05-06T05:49:06.64381Z","steps":["trace[912074144] 'process raft request' (duration: 151.41914ms)","trace[912074144] 'compare' (duration: 22.388888ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:08.93415Z","caller":"traceutil/trace.go:171","msg":"trace[2110703296] linearizableReadLoop","detail":"{readStateIndex:25126; appliedIndex:25125; }","duration":"279.061478ms","start":"2026-05-06T05:49:08.655058Z","end":"2026-05-06T05:49:08.934119Z","steps":["trace[2110703296] 'read index received' (duration: 278.865293ms)","trace[2110703296] 'applied index is now lower than readState.Index' (duration: 194.785µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:08.934258Z","caller":"traceutil/trace.go:171","msg":"trace[877931414] transaction","detail":"{read_only:false; response_revision:24344; number_of_response:1; }","duration":"282.346588ms","start":"2026-05-06T05:49:08.65185Z","end":"2026-05-06T05:49:08.934197Z","steps":["trace[877931414] 'process raft request' (duration: 282.080411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:08.934401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.305043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/orders/\" range_end:\"/registry/acme.cert-manager.io/orders0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:08.934516Z","caller":"traceutil/trace.go:171","msg":"trace[1627615993] range","detail":"{range_begin:/registry/acme.cert-manager.io/orders/; range_end:/registry/acme.cert-manager.io/orders0; response_count:0; response_revision:24344; }","duration":"279.493357ms","start":"2026-05-06T05:49:08.654996Z","end":"2026-05-06T05:49:08.93449Z","steps":["trace[1627615993] 'agreement among raft nodes before linearized reading' (duration: 279.266962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:08.934607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.722134ms","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-05-06T05:49:08.934727Z","caller":"traceutil/trace.go:171","msg":"trace[217279702] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24344; }","duration":"262.865457ms","start":"2026-05-06T05:49:08.671835Z","end":"2026-05-06T05:49:08.934701Z","steps":["trace[217279702] 'agreement among raft nodes before linearized reading' (duration: 262.518369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:08.934651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.869123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/passwords/\" range_end:\"/registry/secretgen.k14s.io/passwords0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-06T05:49:08.934884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.990956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a4a899c0-67da-49cb-b082-85970fe3f6d4\" ","response":"range_response_count:1 size:3949"} {"level":"info","ts":"2026-05-06T05:49:08.93493Z","caller":"traceutil/trace.go:171","msg":"trace[1888833494] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a4a899c0-67da-49cb-b082-85970fe3f6d4; range_end:; response_count:1; response_revision:24344; }","duration":"217.087647ms","start":"2026-05-06T05:49:08.717825Z","end":"2026-05-06T05:49:08.934913Z","steps":["trace[1888833494] 'agreement among raft nodes before linearized reading' (duration: 216.891563ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:08.934902Z","caller":"traceutil/trace.go:171","msg":"trace[426090342] range","detail":"{range_begin:/registry/secretgen.k14s.io/passwords/; range_end:/registry/secretgen.k14s.io/passwords0; response_count:0; response_revision:24344; }","duration":"113.17439ms","start":"2026-05-06T05:49:08.821689Z","end":"2026-05-06T05:49:08.934863Z","steps":["trace[426090342] 'agreement among raft nodes before linearized reading' (duration: 112.851413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:08.934883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.6064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:08.935353Z","caller":"traceutil/trace.go:171","msg":"trace[1553231305] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24344; }","duration":"198.130313ms","start":"2026-05-06T05:49:08.737182Z","end":"2026-05-06T05:49:08.935312Z","steps":["trace[1553231305] 'agreement among raft nodes before linearized reading' (duration: 197.522168ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:12.595445Z","caller":"traceutil/trace.go:171","msg":"trace[407782350] transaction","detail":"{read_only:false; response_revision:24386; number_of_response:1; }","duration":"154.765139ms","start":"2026-05-06T05:49:12.440651Z","end":"2026-05-06T05:49:12.595416Z","steps":["trace[407782350] 'process raft request' (duration: 154.545964ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:12.758072Z","caller":"traceutil/trace.go:171","msg":"trace[1232077355] linearizableReadLoop","detail":"{readStateIndex:25172; appliedIndex:25170; }","duration":"129.069303ms","start":"2026-05-06T05:49:12.628972Z","end":"2026-05-06T05:49:12.758042Z","steps":["trace[1232077355] 'read index received' (duration: 128.444618ms)","trace[1232077355] 'applied index is now lower than readState.Index' (duration: 623.585µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:12.758169Z","caller":"traceutil/trace.go:171","msg":"trace[1312610885] transaction","detail":"{read_only:false; response_revision:24387; number_of_response:1; }","duration":"272.48119ms","start":"2026-05-06T05:49:12.485659Z","end":"2026-05-06T05:49:12.75814Z","steps":["trace[1312610885] 'process raft request' (duration: 272.122892ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:12.75825Z","caller":"traceutil/trace.go:171","msg":"trace[11616187] transaction","detail":"{read_only:false; response_revision:24388; number_of_response:1; }","duration":"271.919997ms","start":"2026-05-06T05:49:12.486285Z","end":"2026-05-06T05:49:12.758205Z","steps":["trace[11616187] 'process raft request' (duration: 271.692441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:12.75836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.126144ms","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-05-06T05:49:12.758431Z","caller":"traceutil/trace.go:171","msg":"trace[1950262817] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24388; }","duration":"124.230256ms","start":"2026-05-06T05:49:12.63418Z","end":"2026-05-06T05:49:12.75841Z","steps":["trace[1950262817] 'agreement among raft nodes before linearized reading' (duration: 124.038832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:12.75836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.37266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10361"} {"level":"info","ts":"2026-05-06T05:49:12.758557Z","caller":"traceutil/trace.go:171","msg":"trace[1677088656] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24388; }","duration":"129.602995ms","start":"2026-05-06T05:49:12.628915Z","end":"2026-05-06T05:49:12.758518Z","steps":["trace[1677088656] 'agreement among raft nodes before linearized reading' (duration: 129.273707ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:12.95487Z","caller":"traceutil/trace.go:171","msg":"trace[1033386455] transaction","detail":"{read_only:false; response_revision:24389; number_of_response:1; }","duration":"185.235602ms","start":"2026-05-06T05:49:12.769606Z","end":"2026-05-06T05:49:12.954842Z","steps":["trace[1033386455] 'process raft request' (duration: 151.082002ms)","trace[1033386455] 'compare' (duration: 33.72421ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:38.844104Z","caller":"traceutil/trace.go:171","msg":"trace[1793505594] linearizableReadLoop","detail":"{readStateIndex:25395; appliedIndex:25394; }","duration":"103.090218ms","start":"2026-05-06T05:49:38.740993Z","end":"2026-05-06T05:49:38.844083Z","steps":["trace[1793505594] 'read index received' (duration: 102.918574ms)","trace[1793505594] 'applied index is now lower than readState.Index' (duration: 170.764µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:38.844164Z","caller":"traceutil/trace.go:171","msg":"trace[1604413293] transaction","detail":"{read_only:false; response_revision:24605; number_of_response:1; }","duration":"126.934081ms","start":"2026-05-06T05:49:38.717197Z","end":"2026-05-06T05:49:38.844132Z","steps":["trace[1604413293] 'process raft request' (duration: 126.739296ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:38.844282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.283663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:38.844308Z","caller":"traceutil/trace.go:171","msg":"trace[389874560] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24605; }","duration":"103.381165ms","start":"2026-05-06T05:49:38.74092Z","end":"2026-05-06T05:49:38.844301Z","steps":["trace[389874560] 'agreement among raft nodes before linearized reading' (duration: 103.287243ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:44.503425Z","caller":"traceutil/trace.go:171","msg":"trace[1345234739] transaction","detail":"{read_only:false; response_revision:24644; number_of_response:1; }","duration":"235.661235ms","start":"2026-05-06T05:49:44.267731Z","end":"2026-05-06T05:49:44.503392Z","steps":["trace[1345234739] 'process raft request' (duration: 235.435409ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:44.620905Z","caller":"traceutil/trace.go:171","msg":"trace[1788849932] transaction","detail":"{read_only:false; response_revision:24645; number_of_response:1; }","duration":"335.865314ms","start":"2026-05-06T05:49:44.284999Z","end":"2026-05-06T05:49:44.620865Z","steps":["trace[1788849932] 'process raft request' (duration: 315.274679ms)","trace[1788849932] 'compare' (duration: 20.348079ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:44.621304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:44.284965Z","time spent":"336.036008ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:49:44.621764Z","caller":"traceutil/trace.go:171","msg":"trace[888487109] linearizableReadLoop","detail":"{readStateIndex:25437; appliedIndex:25434; }","duration":"261.626479ms","start":"2026-05-06T05:49:44.360061Z","end":"2026-05-06T05:49:44.621687Z","steps":["trace[888487109] 'read index received' (duration: 143.09106ms)","trace[888487109] 'applied index is now lower than readState.Index' (duration: 118.528419ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:44.621744Z","caller":"traceutil/trace.go:171","msg":"trace[2015599237] transaction","detail":"{read_only:false; response_revision:24646; number_of_response:1; }","duration":"335.136376ms","start":"2026-05-06T05:49:44.286531Z","end":"2026-05-06T05:49:44.621667Z","steps":["trace[2015599237] 'process raft request' (duration: 334.272755ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:44.622005Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.955938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:44.622299Z","caller":"traceutil/trace.go:171","msg":"trace[1431985560] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:24646; }","duration":"262.119161ms","start":"2026-05-06T05:49:44.359958Z","end":"2026-05-06T05:49:44.622077Z","steps":["trace[1431985560] 'agreement among raft nodes before linearized reading' (duration: 261.896306ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:44.62244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:44.286505Z","time spent":"335.374633ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:49:45.129929Z","caller":"traceutil/trace.go:171","msg":"trace[30063339] transaction","detail":"{read_only:false; response_revision:24647; number_of_response:1; }","duration":"268.376902ms","start":"2026-05-06T05:49:44.86149Z","end":"2026-05-06T05:49:45.129867Z","steps":["trace[30063339] 'process raft request' (duration: 268.091515ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:45.215517Z","caller":"traceutil/trace.go:171","msg":"trace[141415304] transaction","detail":"{read_only:false; response_revision:24648; number_of_response:1; }","duration":"319.631053ms","start":"2026-05-06T05:49:44.895853Z","end":"2026-05-06T05:49:45.215484Z","steps":["trace[141415304] 'process raft request' (duration: 303.700591ms)","trace[141415304] 'compare' (duration: 15.124903ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:45.215682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:44.895826Z","time spent":"319.765947ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:49:45.217701Z","caller":"traceutil/trace.go:171","msg":"trace[1901106475] transaction","detail":"{read_only:false; response_revision:24649; number_of_response:1; }","duration":"301.618299ms","start":"2026-05-06T05:49:44.916065Z","end":"2026-05-06T05:49:45.217683Z","steps":["trace[1901106475] 'process raft request' (duration: 299.052317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:45.217772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:44.916042Z","time spent":"301.696632ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:45.491052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.224842ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:45.491162Z","caller":"traceutil/trace.go:171","msg":"trace[1330115155] transaction","detail":"{read_only:false; response_revision:24650; number_of_response:1; }","duration":"270.36783ms","start":"2026-05-06T05:49:45.220775Z","end":"2026-05-06T05:49:45.491143Z","steps":["trace[1330115155] 'process raft request' (duration: 114.961144ms)","trace[1330115155] 'compare' (duration: 154.977006ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:45.52286Z","caller":"traceutil/trace.go:171","msg":"trace[153857564] transaction","detail":"{read_only:false; response_revision:24651; number_of_response:1; }","duration":"298.562117ms","start":"2026-05-06T05:49:45.224268Z","end":"2026-05-06T05:49:45.52283Z","steps":["trace[153857564] 'process raft request' (duration: 298.332581ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:45.523299Z","caller":"traceutil/trace.go:171","msg":"trace[1099460083] transaction","detail":"{read_only:false; response_revision:24652; number_of_response:1; }","duration":"298.673179ms","start":"2026-05-06T05:49:45.224566Z","end":"2026-05-06T05:49:45.52324Z","steps":["trace[1099460083] 'process raft request' (duration: 298.217268ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:45.794558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.523209ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:45.79467Z","caller":"traceutil/trace.go:171","msg":"trace[1551849551] transaction","detail":"{read_only:false; response_revision:24653; number_of_response:1; }","duration":"264.57908ms","start":"2026-05-06T05:49:45.53007Z","end":"2026-05-06T05:49:45.794649Z","steps":["trace[1551849551] 'process raft request' (duration: 113.725863ms)","trace[1551849551] 'compare' (duration: 150.423676ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:45.794734Z","caller":"traceutil/trace.go:171","msg":"trace[2063085728] linearizableReadLoop","detail":"{readStateIndex:25444; appliedIndex:25443; }","duration":"263.288269ms","start":"2026-05-06T05:49:45.531432Z","end":"2026-05-06T05:49:45.79472Z","steps":["trace[2063085728] 'read index received' (duration: 112.364811ms)","trace[2063085728] 'applied index is now lower than readState.Index' (duration: 150.922538ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:45.794868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.433203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18571"} {"level":"info","ts":"2026-05-06T05:49:45.794898Z","caller":"traceutil/trace.go:171","msg":"trace[186768672] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24654; }","duration":"263.488554ms","start":"2026-05-06T05:49:45.531402Z","end":"2026-05-06T05:49:45.79489Z","steps":["trace[186768672] 'agreement among raft nodes before linearized reading' (duration: 263.379992ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:45.795147Z","caller":"traceutil/trace.go:171","msg":"trace[1509643231] transaction","detail":"{read_only:false; response_revision:24654; number_of_response:1; }","duration":"263.547645ms","start":"2026-05-06T05:49:45.531587Z","end":"2026-05-06T05:49:45.795134Z","steps":["trace[1509643231] 'process raft request' (duration: 263.071774ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:45.795199Z","caller":"traceutil/trace.go:171","msg":"trace[100247709] transaction","detail":"{read_only:false; response_revision:24655; number_of_response:1; }","duration":"154.065953ms","start":"2026-05-06T05:49:45.641124Z","end":"2026-05-06T05:49:45.79519Z","steps":["trace[100247709] 'process raft request' (duration: 153.93146ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:46.232414Z","caller":"traceutil/trace.go:171","msg":"trace[1874029860] linearizableReadLoop","detail":"{readStateIndex:25449; appliedIndex:25448; }","duration":"245.348037ms","start":"2026-05-06T05:49:45.987048Z","end":"2026-05-06T05:49:46.232396Z","steps":["trace[1874029860] 'read index received' (duration: 245.199944ms)","trace[1874029860] 'applied index is now lower than readState.Index' (duration: 147.513µs)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:46.23246Z","caller":"traceutil/trace.go:171","msg":"trace[1651079291] transaction","detail":"{read_only:false; response_revision:24658; number_of_response:1; }","duration":"254.349973ms","start":"2026-05-06T05:49:45.978094Z","end":"2026-05-06T05:49:46.232444Z","steps":["trace[1651079291] 'process raft request' (duration: 254.18863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:46.232566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.501651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-05-06T05:49:46.232586Z","caller":"traceutil/trace.go:171","msg":"trace[179214864] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24658; }","duration":"245.552563ms","start":"2026-05-06T05:49:45.987027Z","end":"2026-05-06T05:49:46.23258Z","steps":["trace[179214864] 'agreement among raft nodes before linearized reading' (duration: 245.44837ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:46.629742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.799209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:46.629817Z","caller":"traceutil/trace.go:171","msg":"trace[1987377848] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:24658; }","duration":"198.925822ms","start":"2026-05-06T05:49:46.430872Z","end":"2026-05-06T05:49:46.629798Z","steps":["trace[1987377848] 'count revisions from in-memory index tree' (duration: 198.711767ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:46.642975Z","caller":"traceutil/trace.go:171","msg":"trace[1818936366] transaction","detail":"{read_only:false; response_revision:24659; number_of_response:1; }","duration":"132.664798ms","start":"2026-05-06T05:49:46.510281Z","end":"2026-05-06T05:49:46.642946Z","steps":["trace[1818936366] 'process raft request' (duration: 132.465794ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:46.956396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.037648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.73\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-06T05:49:46.956499Z","caller":"traceutil/trace.go:171","msg":"trace[858017582] range","detail":"{range_begin:/registry/masterleases/199.19.213.73; range_end:; response_count:1; response_revision:24661; }","duration":"118.182251ms","start":"2026-05-06T05:49:46.838287Z","end":"2026-05-06T05:49:46.956469Z","steps":["trace[858017582] 'range keys from in-memory index tree' (duration: 117.828613ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:47.177027Z","caller":"traceutil/trace.go:171","msg":"trace[2138357595] transaction","detail":"{read_only:false; response_revision:24662; number_of_response:1; }","duration":"180.895769ms","start":"2026-05-06T05:49:46.996094Z","end":"2026-05-06T05:49:47.176989Z","steps":["trace[2138357595] 'process raft request' (duration: 158.688296ms)","trace[2138357595] 'compare' (duration: 21.592328ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:47.387089Z","caller":"traceutil/trace.go:171","msg":"trace[738200139] transaction","detail":"{read_only:false; response_revision:24663; number_of_response:1; }","duration":"249.383395ms","start":"2026-05-06T05:49:47.137674Z","end":"2026-05-06T05:49:47.387057Z","steps":["trace[738200139] 'process raft request' (duration: 249.115829ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:47.387311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.678858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-06T05:49:47.387373Z","caller":"traceutil/trace.go:171","msg":"trace[1350026738] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24663; }","duration":"206.795411ms","start":"2026-05-06T05:49:47.180566Z","end":"2026-05-06T05:49:47.387361Z","steps":["trace[1350026738] 'agreement among raft nodes before linearized reading' (duration: 206.578186ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:47.38708Z","caller":"traceutil/trace.go:171","msg":"trace[1912319752] linearizableReadLoop","detail":"{readStateIndex:25455; appliedIndex:25454; }","duration":"206.414322ms","start":"2026-05-06T05:49:47.1806Z","end":"2026-05-06T05:49:47.387014Z","steps":["trace[1912319752] 'read index received' (duration: 206.274769ms)","trace[1912319752] 'applied index is now lower than readState.Index' (duration: 138.413µs)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:47.642994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.579468ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:47.643255Z","caller":"traceutil/trace.go:171","msg":"trace[2011725766] linearizableReadLoop","detail":"{readStateIndex:25457; appliedIndex:25455; }","duration":"253.883924ms","start":"2026-05-06T05:49:47.38935Z","end":"2026-05-06T05:49:47.643234Z","steps":["trace[2011725766] 'read index received' (duration: 95.973808ms)","trace[2011725766] 'applied index is now lower than readState.Index' (duration: 157.908926ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:47.643373Z","caller":"traceutil/trace.go:171","msg":"trace[1905995577] transaction","detail":"{read_only:false; response_revision:24665; number_of_response:1; }","duration":"416.078562ms","start":"2026-05-06T05:49:47.227279Z","end":"2026-05-06T05:49:47.643357Z","steps":["trace[1905995577] 'process raft request' (duration: 415.837476ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:47.643424Z","caller":"traceutil/trace.go:171","msg":"trace[8474092] transaction","detail":"{read_only:false; response_revision:24664; number_of_response:1; }","duration":"416.260335ms","start":"2026-05-06T05:49:47.227116Z","end":"2026-05-06T05:49:47.643376Z","steps":["trace[8474092] 'process raft request' (duration: 258.182916ms)","trace[8474092] 'compare' (duration: 157.412653ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:47.643619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:47.227093Z","time spent":"416.419809ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":607,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:47.643617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.256972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-05-06T05:49:47.643672Z","caller":"traceutil/trace.go:171","msg":"trace[1291243521] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:24666; }","duration":"254.349015ms","start":"2026-05-06T05:49:47.389309Z","end":"2026-05-06T05:49:47.643658Z","steps":["trace[1291243521] 'agreement among raft nodes before linearized reading' (duration: 254.13202ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:47.643668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.339088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:533"} {"level":"warn","ts":"2026-05-06T05:49:47.643449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:47.227246Z","time spent":"416.166333ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:49:47.643761Z","caller":"traceutil/trace.go:171","msg":"trace[1903241770] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:24666; }","duration":"198.486482ms","start":"2026-05-06T05:49:47.445249Z","end":"2026-05-06T05:49:47.643736Z","steps":["trace[1903241770] 'agreement among raft nodes before linearized reading' (duration: 198.252526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:47.643738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.413961ms","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-05-06T05:49:47.643792Z","caller":"traceutil/trace.go:171","msg":"trace[420898164] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"240.102581ms","start":"2026-05-06T05:49:47.403676Z","end":"2026-05-06T05:49:47.643779Z","steps":["trace[420898164] 'process raft request' (duration: 239.516767ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:47.64384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.816513ms","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-05-06T05:49:47.643835Z","caller":"traceutil/trace.go:171","msg":"trace[964362504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24666; }","duration":"114.576084ms","start":"2026-05-06T05:49:47.529236Z","end":"2026-05-06T05:49:47.643812Z","steps":["trace[964362504] 'agreement among raft nodes before linearized reading' (duration: 114.275027ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:47.643866Z","caller":"traceutil/trace.go:171","msg":"trace[1722901957] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24666; }","duration":"110.859514ms","start":"2026-05-06T05:49:47.532997Z","end":"2026-05-06T05:49:47.643857Z","steps":["trace[1722901957] 'agreement among raft nodes before linearized reading' (duration: 110.784543ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:47.64401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.170502ms","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-05-06T05:49:47.644178Z","caller":"traceutil/trace.go:171","msg":"trace[1647942432] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24666; }","duration":"148.411477ms","start":"2026-05-06T05:49:47.495702Z","end":"2026-05-06T05:49:47.644113Z","steps":["trace[1647942432] 'agreement among raft nodes before linearized reading' (duration: 147.842044ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:48.086984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.972508ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:48.08714Z","caller":"traceutil/trace.go:171","msg":"trace[1096444288] transaction","detail":"{read_only:false; response_revision:24667; number_of_response:1; }","duration":"438.563271ms","start":"2026-05-06T05:49:47.648549Z","end":"2026-05-06T05:49:48.087112Z","steps":["trace[1096444288] 'process raft request' (duration: 203.317657ms)","trace[1096444288] 'compare' (duration: 234.488776ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:48.087208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:47.648523Z","time spent":"438.651265ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:48.396794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.136087ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:48.396968Z","caller":"traceutil/trace.go:171","msg":"trace[812657961] linearizableReadLoop","detail":"{readStateIndex:25462; appliedIndex:25458; }","duration":"655.920337ms","start":"2026-05-06T05:49:47.741033Z","end":"2026-05-06T05:49:48.396954Z","steps":["trace[812657961] 'read index received' (duration: 110.928576ms)","trace[812657961] 'applied index is now lower than readState.Index' (duration: 544.991171ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:48.397025Z","caller":"traceutil/trace.go:171","msg":"trace[1767463179] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"747.919288ms","start":"2026-05-06T05:49:47.649099Z","end":"2026-05-06T05:49:48.397018Z","steps":["trace[1767463179] 'process raft request' (duration: 548.504645ms)","trace[1767463179] 'compare' (duration: 198.983923ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:48.397063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:47.649068Z","time spent":"747.97529ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:48.397282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.92994ms","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-05-06T05:49:48.397305Z","caller":"traceutil/trace.go:171","msg":"trace[1613904754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24671; }","duration":"597.001131ms","start":"2026-05-06T05:49:47.800298Z","end":"2026-05-06T05:49:48.397299Z","steps":["trace[1613904754] 'agreement among raft nodes before linearized reading' (duration: 596.926699ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:48.397321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:47.800274Z","time spent":"597.043762ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-06T05:49:48.397419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.332027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T05:49:48.397515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.691903ms","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-05-06T05:49:48.397536Z","caller":"traceutil/trace.go:171","msg":"trace[487949422] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24671; }","duration":"596.726425ms","start":"2026-05-06T05:49:47.800804Z","end":"2026-05-06T05:49:48.39753Z","steps":["trace[487949422] 'agreement among raft nodes before linearized reading' (duration: 596.679503ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:48.397553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:47.800797Z","time spent":"596.749905ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-06T05:49:48.39756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.654189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-05-06T05:49:48.397555Z","caller":"traceutil/trace.go:171","msg":"trace[1284085652] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24671; }","duration":"656.545562ms","start":"2026-05-06T05:49:47.740973Z","end":"2026-05-06T05:49:48.397519Z","steps":["trace[1284085652] 'agreement among raft nodes before linearized reading' (duration: 656.249225ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:48.397598Z","caller":"traceutil/trace.go:171","msg":"trace[1498118706] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:24671; }","duration":"496.740221ms","start":"2026-05-06T05:49:47.900849Z","end":"2026-05-06T05:49:48.397589Z","steps":["trace[1498118706] 'agreement among raft nodes before linearized reading' (duration: 496.568637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:48.397626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:47.740948Z","time spent":"656.662614ms","remote":"127.0.0.1:57624","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-06T05:49:48.397648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:47.900824Z","time spent":"496.799892ms","remote":"127.0.0.1:44194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":181,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:49:48.397704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.862821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T05:49:48.397729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.151553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1991"} {"level":"info","ts":"2026-05-06T05:49:48.397785Z","caller":"traceutil/trace.go:171","msg":"trace[1671860369] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:24671; }","duration":"228.988524ms","start":"2026-05-06T05:49:48.168777Z","end":"2026-05-06T05:49:48.397765Z","steps":["trace[1671860369] 'agreement among raft nodes before linearized reading' (duration: 228.840431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:48.397798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.840609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-06T05:49:48.397825Z","caller":"traceutil/trace.go:171","msg":"trace[1784684988] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusrules/; range_end:/registry/monitoring.coreos.com/prometheusrules0; response_count:0; response_revision:24671; }","duration":"428.91229ms","start":"2026-05-06T05:49:47.968904Z","end":"2026-05-06T05:49:48.397816Z","steps":["trace[1784684988] 'agreement among raft nodes before linearized reading' (duration: 428.855379ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:48.397828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.146074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-06T05:49:48.397823Z","caller":"traceutil/trace.go:171","msg":"trace[1471560065] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:24671; }","duration":"198.256995ms","start":"2026-05-06T05:49:48.199544Z","end":"2026-05-06T05:49:48.397801Z","steps":["trace[1471560065] 'agreement among raft nodes before linearized reading' (duration: 197.994889ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:48.397848Z","caller":"traceutil/trace.go:171","msg":"trace[667134854] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24671; }","duration":"161.189174ms","start":"2026-05-06T05:49:48.236654Z","end":"2026-05-06T05:49:48.397843Z","steps":["trace[667134854] 'agreement among raft nodes before linearized reading' (duration: 161.143823ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:48.397846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:47.968884Z","time spent":"428.956181ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":29,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/prometheusrules/\" range_end:\"/registry/monitoring.coreos.com/prometheusrules0\" count_only:true "} {"level":"info","ts":"2026-05-06T05:49:48.398018Z","caller":"traceutil/trace.go:171","msg":"trace[1661123712] transaction","detail":"{read_only:false; response_revision:24670; number_of_response:1; }","duration":"745.386788ms","start":"2026-05-06T05:49:47.652623Z","end":"2026-05-06T05:49:48.39801Z","steps":["trace[1661123712] 'process raft request' (duration: 744.283312ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:48.398026Z","caller":"traceutil/trace.go:171","msg":"trace[64389360] transaction","detail":"{read_only:false; response_revision:24671; number_of_response:1; }","duration":"595.721889ms","start":"2026-05-06T05:49:47.802292Z","end":"2026-05-06T05:49:48.398014Z","steps":["trace[64389360] 'process raft request' (duration: 594.635433ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:48.398072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:47.652604Z","time spent":"745.440539ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:48.398103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:47.802278Z","time spent":"595.770681ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:49:48.398075Z","caller":"traceutil/trace.go:171","msg":"trace[1506061146] transaction","detail":"{read_only:false; response_revision:24669; number_of_response:1; }","duration":"747.819736ms","start":"2026-05-06T05:49:47.65022Z","end":"2026-05-06T05:49:48.39804Z","steps":["trace[1506061146] 'process raft request' (duration: 746.645998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:48.398336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:47.650188Z","time spent":"747.964469ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-06T05:49:48.581599Z","caller":"traceutil/trace.go:171","msg":"trace[234917265] transaction","detail":"{read_only:false; response_revision:24672; number_of_response:1; }","duration":"178.507822ms","start":"2026-05-06T05:49:48.403058Z","end":"2026-05-06T05:49:48.581566Z","steps":["trace[234917265] 'process raft request' (duration: 171.892293ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:48.92102Z","caller":"traceutil/trace.go:171","msg":"trace[1092602809] linearizableReadLoop","detail":"{readStateIndex:25466; appliedIndex:25463; }","duration":"514.19306ms","start":"2026-05-06T05:49:48.406804Z","end":"2026-05-06T05:49:48.920997Z","steps":["trace[1092602809] 'read index received' (duration: 168.168703ms)","trace[1092602809] 'applied index is now lower than readState.Index' (duration: 346.023337ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:48.921138Z","caller":"traceutil/trace.go:171","msg":"trace[1512537536] transaction","detail":"{read_only:false; response_revision:24674; number_of_response:1; }","duration":"514.669882ms","start":"2026-05-06T05:49:48.40645Z","end":"2026-05-06T05:49:48.92112Z","steps":["trace[1512537536] 'process raft request' (duration: 514.496298ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:48.92115Z","caller":"traceutil/trace.go:171","msg":"trace[198993420] transaction","detail":"{read_only:false; response_revision:24673; number_of_response:1; }","duration":"517.871919ms","start":"2026-05-06T05:49:48.403239Z","end":"2026-05-06T05:49:48.921111Z","steps":["trace[198993420] 'process raft request' (duration: 462.013426ms)","trace[198993420] 'compare' (duration: 55.551055ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:48.921277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:48.406437Z","time spent":"514.743814ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:48.921322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:48.4032Z","time spent":"518.014112ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:48.921301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"514.464976ms","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-05-06T05:49:48.92139Z","caller":"traceutil/trace.go:171","msg":"trace[451130625] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24674; }","duration":"514.61091ms","start":"2026-05-06T05:49:48.406755Z","end":"2026-05-06T05:49:48.921366Z","steps":["trace[451130625] 'agreement among raft nodes before linearized reading' (duration: 514.435115ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:48.921452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:48.406729Z","time spent":"514.711262ms","remote":"127.0.0.1:44044","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-05-06T05:49:49.420081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.077788ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:49.420367Z","caller":"traceutil/trace.go:171","msg":"trace[1921473563] linearizableReadLoop","detail":"{readStateIndex:25469; appliedIndex:25466; }","duration":"499.260331ms","start":"2026-05-06T05:49:48.921087Z","end":"2026-05-06T05:49:49.420347Z","steps":["trace[1921473563] 'read index received' (duration: 167.770683ms)","trace[1921473563] 'applied index is now lower than readState.Index' (duration: 331.488358ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:49.42045Z","caller":"traceutil/trace.go:171","msg":"trace[1291561092] transaction","detail":"{read_only:false; response_revision:24675; number_of_response:1; }","duration":"770.286256ms","start":"2026-05-06T05:49:48.650147Z","end":"2026-05-06T05:49:49.420433Z","steps":["trace[1291561092] 'process raft request' (duration: 438.735757ms)","trace[1291561092] 'compare' (duration: 330.71735ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:49.42094Z","caller":"traceutil/trace.go:171","msg":"trace[1781628403] transaction","detail":"{read_only:false; response_revision:24677; number_of_response:1; }","duration":"746.191928ms","start":"2026-05-06T05:49:48.67473Z","end":"2026-05-06T05:49:49.420922Z","steps":["trace[1781628403] 'process raft request' (duration: 745.566332ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:49.420955Z","caller":"traceutil/trace.go:171","msg":"trace[1430732221] transaction","detail":"{read_only:false; response_revision:24676; number_of_response:1; }","duration":"747.147851ms","start":"2026-05-06T05:49:48.673761Z","end":"2026-05-06T05:49:49.420909Z","steps":["trace[1430732221] 'process raft request' (duration: 746.427833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:49.421037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:48.674718Z","time spent":"746.262139ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:49.421051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:48.650117Z","time spent":"770.853551ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:49.420869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.631816ms","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-05-06T05:49:49.421046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.741322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/glance-api\" ","response":"range_response_count:1 size:11837"} {"level":"info","ts":"2026-05-06T05:49:49.421156Z","caller":"traceutil/trace.go:171","msg":"trace[1750500023] range","detail":"{range_begin:/registry/deployments/openstack/glance-api; range_end:; response_count:1; response_revision:24677; }","duration":"677.892446ms","start":"2026-05-06T05:49:48.743242Z","end":"2026-05-06T05:49:49.421134Z","steps":["trace[1750500023] 'agreement among raft nodes before linearized reading' (duration: 677.532087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:49.421169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:48.673729Z","time spent":"747.317925ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:49.421204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:48.74323Z","time spent":"677.964947ms","remote":"127.0.0.1:44316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":11859,"request content":"key:\"/registry/deployments/openstack/glance-api\" "} {"level":"warn","ts":"2026-05-06T05:49:49.420882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.922578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:49:49.421277Z","caller":"traceutil/trace.go:171","msg":"trace[2110020273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24677; }","duration":"681.334638ms","start":"2026-05-06T05:49:48.739931Z","end":"2026-05-06T05:49:49.421266Z","steps":["trace[2110020273] 'agreement among raft nodes before linearized reading' (duration: 680.877717ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:49.421312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:48.739853Z","time spent":"681.452741ms","remote":"127.0.0.1:57618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-06T05:49:49.421171Z","caller":"traceutil/trace.go:171","msg":"trace[766745234] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0; range_end:; response_count:1; response_revision:24677; }","duration":"634.904562ms","start":"2026-05-06T05:49:48.786207Z","end":"2026-05-06T05:49:49.421111Z","steps":["trace[766745234] 'agreement among raft nodes before linearized reading' (duration: 634.466822ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:49.421436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:48.786195Z","time spent":"635.22586ms","remote":"127.0.0.1:44012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":1,"response size":1880,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-cinder-server-0\" "} {"level":"warn","ts":"2026-05-06T05:49:49.47197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.986991ms","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-05-06T05:49:49.472107Z","caller":"traceutil/trace.go:171","msg":"trace[357264340] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24678; }","duration":"545.181305ms","start":"2026-05-06T05:49:48.926902Z","end":"2026-05-06T05:49:49.472083Z","steps":["trace[357264340] 'agreement among raft nodes before linearized reading' (duration: 544.94821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:49.472163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:48.92688Z","time spent":"545.272618ms","remote":"127.0.0.1:44134","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" "} {"level":"info","ts":"2026-05-06T05:49:49.885545Z","caller":"traceutil/trace.go:171","msg":"trace[1612457455] transaction","detail":"{read_only:false; response_revision:24679; number_of_response:1; }","duration":"235.06665ms","start":"2026-05-06T05:49:49.650448Z","end":"2026-05-06T05:49:49.885515Z","steps":["trace[1612457455] 'process raft request' (duration: 138.345565ms)","trace[1612457455] 'compare' (duration: 96.597072ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:49.888854Z","caller":"traceutil/trace.go:171","msg":"trace[1707787234] transaction","detail":"{read_only:false; response_revision:24680; number_of_response:1; }","duration":"237.92503ms","start":"2026-05-06T05:49:49.650915Z","end":"2026-05-06T05:49:49.888841Z","steps":["trace[1707787234] 'process raft request' (duration: 237.857578ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:56.790984Z","caller":"traceutil/trace.go:171","msg":"trace[306843251] linearizableReadLoop","detail":"{readStateIndex:25520; appliedIndex:25519; }","duration":"157.858747ms","start":"2026-05-06T05:49:56.633104Z","end":"2026-05-06T05:49:56.790963Z","steps":["trace[306843251] 'read index received' (duration: 99.586416ms)","trace[306843251] 'applied index is now lower than readState.Index' (duration: 58.271441ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:56.791112Z","caller":"traceutil/trace.go:171","msg":"trace[325696208] transaction","detail":"{read_only:false; response_revision:24727; number_of_response:1; }","duration":"269.671826ms","start":"2026-05-06T05:49:56.521392Z","end":"2026-05-06T05:49:56.791064Z","steps":["trace[325696208] 'process raft request' (duration: 211.393004ms)","trace[325696208] 'compare' (duration: 58.020595ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:56.791437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.305908ms","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-05-06T05:49:56.791548Z","caller":"traceutil/trace.go:171","msg":"trace[1470757281] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24727; }","duration":"158.475222ms","start":"2026-05-06T05:49:56.633046Z","end":"2026-05-06T05:49:56.791521Z","steps":["trace[1470757281] 'agreement among raft nodes before linearized reading' (duration: 158.213176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:57.221936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.187125ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:57.222096Z","caller":"traceutil/trace.go:171","msg":"trace[141248214] linearizableReadLoop","detail":"{readStateIndex:25523; appliedIndex:25522; }","duration":"261.94454ms","start":"2026-05-06T05:49:56.960131Z","end":"2026-05-06T05:49:57.222075Z","steps":["trace[141248214] 'read index received' (duration: 120.525839ms)","trace[141248214] 'applied index is now lower than readState.Index' (duration: 141.417401ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:57.222209Z","caller":"traceutil/trace.go:171","msg":"trace[1467300654] transaction","detail":"{read_only:false; response_revision:24729; number_of_response:1; }","duration":"268.269092ms","start":"2026-05-06T05:49:56.953926Z","end":"2026-05-06T05:49:57.222195Z","steps":["trace[1467300654] 'process raft request' (duration: 126.705017ms)","trace[1467300654] 'compare' (duration: 140.707724ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:57.222528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.383381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-06T05:49:57.222594Z","caller":"traceutil/trace.go:171","msg":"trace[784550519] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24729; }","duration":"262.507585ms","start":"2026-05-06T05:49:56.960066Z","end":"2026-05-06T05:49:57.222574Z","steps":["trace[784550519] 'agreement among raft nodes before linearized reading' (duration: 262.284348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:57.222625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.086152ms","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-05-06T05:49:57.222734Z","caller":"traceutil/trace.go:171","msg":"trace[891637435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24729; }","duration":"254.237825ms","start":"2026-05-06T05:49:56.968471Z","end":"2026-05-06T05:49:57.222709Z","steps":["trace[891637435] 'agreement among raft nodes before linearized reading' (duration: 253.911346ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:57.377879Z","caller":"traceutil/trace.go:171","msg":"trace[1527504029] linearizableReadLoop","detail":"{readStateIndex:25525; appliedIndex:25523; }","duration":"149.101165ms","start":"2026-05-06T05:49:57.228736Z","end":"2026-05-06T05:49:57.377838Z","steps":["trace[1527504029] 'read index received' (duration: 88.035486ms)","trace[1527504029] 'applied index is now lower than readState.Index' (duration: 61.063279ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:57.377911Z","caller":"traceutil/trace.go:171","msg":"trace[1476620996] transaction","detail":"{read_only:false; response_revision:24731; number_of_response:1; }","duration":"149.333181ms","start":"2026-05-06T05:49:57.22854Z","end":"2026-05-06T05:49:57.377874Z","steps":["trace[1476620996] 'process raft request' (duration: 137.609199ms)","trace[1476620996] 'compare' (duration: 11.312873ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:57.378136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.361922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-06T05:49:57.378227Z","caller":"traceutil/trace.go:171","msg":"trace[569413272] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:24731; }","duration":"149.527996ms","start":"2026-05-06T05:49:57.228676Z","end":"2026-05-06T05:49:57.378204Z","steps":["trace[569413272] 'agreement among raft nodes before linearized reading' (duration: 149.26897ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:57.720887Z","caller":"traceutil/trace.go:171","msg":"trace[1346575205] transaction","detail":"{read_only:false; response_revision:24735; number_of_response:1; }","duration":"219.309964ms","start":"2026-05-06T05:49:57.501547Z","end":"2026-05-06T05:49:57.720857Z","steps":["trace[1346575205] 'process raft request' (duration: 219.250493ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:57.721088Z","caller":"traceutil/trace.go:171","msg":"trace[1070206276] transaction","detail":"{read_only:false; response_revision:24734; number_of_response:1; }","duration":"267.848323ms","start":"2026-05-06T05:49:57.453226Z","end":"2026-05-06T05:49:57.721074Z","steps":["trace[1070206276] 'process raft request' (duration: 267.529175ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:49:57.721117Z","caller":"traceutil/trace.go:171","msg":"trace[1644104019] transaction","detail":"{read_only:false; response_revision:24733; number_of_response:1; }","duration":"273.930478ms","start":"2026-05-06T05:49:57.447141Z","end":"2026-05-06T05:49:57.721071Z","steps":["trace[1644104019] 'process raft request' (duration: 273.457386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:58.00394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.676892ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:49:58.004302Z","caller":"traceutil/trace.go:171","msg":"trace[606143181] transaction","detail":"{read_only:false; response_revision:24736; number_of_response:1; }","duration":"340.632913ms","start":"2026-05-06T05:49:57.663627Z","end":"2026-05-06T05:49:58.00426Z","steps":["trace[606143181] 'process raft request' (duration: 187.50901ms)","trace[606143181] 'compare' (duration: 152.484267ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:49:58.004376Z","caller":"traceutil/trace.go:171","msg":"trace[1140804451] linearizableReadLoop","detail":"{readStateIndex:25530; appliedIndex:25526; }","duration":"297.771502ms","start":"2026-05-06T05:49:57.70657Z","end":"2026-05-06T05:49:58.004342Z","steps":["trace[1140804451] 'read index received' (duration: 14.036697ms)","trace[1140804451] 'applied index is now lower than readState.Index' (duration: 283.731905ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:49:58.00449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:49:57.663598Z","time spent":"340.788427ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:49:58.004797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.206713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0\" ","response":"range_response_count:1 size:1858"} {"level":"info","ts":"2026-05-06T05:49:58.004841Z","caller":"traceutil/trace.go:171","msg":"trace[1607057841] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-glance-server-0; range_end:; response_count:1; response_revision:24736; }","duration":"298.300214ms","start":"2026-05-06T05:49:57.706526Z","end":"2026-05-06T05:49:58.004826Z","steps":["trace[1607057841] 'agreement among raft nodes before linearized reading' (duration: 297.922795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:49:58.292668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.553883ms","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-05-06T05:49:58.292754Z","caller":"traceutil/trace.go:171","msg":"trace[1244892011] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24738; }","duration":"165.691466ms","start":"2026-05-06T05:49:58.127042Z","end":"2026-05-06T05:49:58.292734Z","steps":["trace[1244892011] 'range keys from in-memory index tree' (duration: 165.347207ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:00.762248Z","caller":"traceutil/trace.go:171","msg":"trace[335234699] transaction","detail":"{read_only:false; response_revision:24765; number_of_response:1; }","duration":"132.959737ms","start":"2026-05-06T05:50:00.62924Z","end":"2026-05-06T05:50:00.7622Z","steps":["trace[335234699] 'process raft request' (duration: 75.577008ms)","trace[335234699] 'compare' (duration: 57.104083ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:00.937357Z","caller":"traceutil/trace.go:171","msg":"trace[474797424] linearizableReadLoop","detail":"{readStateIndex:25560; appliedIndex:25558; }","duration":"245.537626ms","start":"2026-05-06T05:50:00.691783Z","end":"2026-05-06T05:50:00.93732Z","steps":["trace[474797424] 'read index received' (duration: 13.166557ms)","trace[474797424] 'applied index is now lower than readState.Index' (duration: 232.370429ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:00.93743Z","caller":"traceutil/trace.go:171","msg":"trace[635477478] transaction","detail":"{read_only:false; response_revision:24766; number_of_response:1; }","duration":"267.886613ms","start":"2026-05-06T05:50:00.669475Z","end":"2026-05-06T05:50:00.937362Z","steps":["trace[635477478] 'process raft request' (duration: 222.109271ms)","trace[635477478] 'compare' (duration: 45.625518ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:00.937542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.727131ms","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-05-06T05:50:00.937628Z","caller":"traceutil/trace.go:171","msg":"trace[886937646] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:24766; }","duration":"245.864074ms","start":"2026-05-06T05:50:00.691742Z","end":"2026-05-06T05:50:00.937606Z","steps":["trace[886937646] 'agreement among raft nodes before linearized reading' (duration: 245.70071ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:01.012949Z","caller":"traceutil/trace.go:171","msg":"trace[1669898563] transaction","detail":"{read_only:false; response_revision:24768; number_of_response:1; }","duration":"195.639686ms","start":"2026-05-06T05:50:00.817266Z","end":"2026-05-06T05:50:01.012906Z","steps":["trace[1669898563] 'process raft request' (duration: 195.476692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:01.013038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.481988ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-05-06T05:50:01.013088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.72817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:01.013059Z","caller":"traceutil/trace.go:171","msg":"trace[223211698] transaction","detail":"{read_only:false; response_revision:24767; number_of_response:1; }","duration":"242.883732ms","start":"2026-05-06T05:50:00.770109Z","end":"2026-05-06T05:50:01.012993Z","steps":["trace[223211698] 'process raft request' (duration: 242.506562ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:01.013147Z","caller":"traceutil/trace.go:171","msg":"trace[1963191625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24768; }","duration":"272.856003ms","start":"2026-05-06T05:50:00.740276Z","end":"2026-05-06T05:50:01.013132Z","steps":["trace[1963191625] 'agreement among raft nodes before linearized reading' (duration: 272.72641ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:01.013458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.717261ms","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-05-06T05:50:01.013465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.13588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:18571"} {"level":"info","ts":"2026-05-06T05:50:01.01349Z","caller":"traceutil/trace.go:171","msg":"trace[814340686] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24768; }","duration":"208.781082ms","start":"2026-05-06T05:50:00.804699Z","end":"2026-05-06T05:50:01.01348Z","steps":["trace[814340686] 'agreement among raft nodes before linearized reading' (duration: 208.639029ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:01.013503Z","caller":"traceutil/trace.go:171","msg":"trace[1270864695] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:24768; }","duration":"246.225953ms","start":"2026-05-06T05:50:00.767267Z","end":"2026-05-06T05:50:01.013493Z","steps":["trace[1270864695] 'agreement among raft nodes before linearized reading' (duration: 246.10164ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:01.013121Z","caller":"traceutil/trace.go:171","msg":"trace[1719832758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24768; }","duration":"189.623381ms","start":"2026-05-06T05:50:00.823475Z","end":"2026-05-06T05:50:01.013098Z","steps":["trace[1719832758] 'agreement among raft nodes before linearized reading' (duration: 189.376626ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:01.148433Z","caller":"traceutil/trace.go:171","msg":"trace[902309008] transaction","detail":"{read_only:false; response_revision:24769; number_of_response:1; }","duration":"129.192977ms","start":"2026-05-06T05:50:01.019205Z","end":"2026-05-06T05:50:01.148398Z","steps":["trace[902309008] 'process raft request' (duration: 49.609273ms)","trace[902309008] 'compare' (duration: 79.427391ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:01.148817Z","caller":"traceutil/trace.go:171","msg":"trace[1414963857] linearizableReadLoop","detail":"{readStateIndex:25564; appliedIndex:25562; }","duration":"129.050644ms","start":"2026-05-06T05:50:01.019743Z","end":"2026-05-06T05:50:01.148793Z","steps":["trace[1414963857] 'read index received' (duration: 49.06657ms)","trace[1414963857] 'applied index is now lower than readState.Index' (duration: 79.983284ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:01.148872Z","caller":"traceutil/trace.go:171","msg":"trace[1145940259] transaction","detail":"{read_only:false; response_revision:24770; number_of_response:1; }","duration":"129.609527ms","start":"2026-05-06T05:50:01.01925Z","end":"2026-05-06T05:50:01.14886Z","steps":["trace[1145940259] 'process raft request' (duration: 129.380012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:01.149006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.241869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/kube-system/kube-prometheus-stack-kubelet\" ","response":"range_response_count:1 size:1137"} {"level":"info","ts":"2026-05-06T05:50:01.149039Z","caller":"traceutil/trace.go:171","msg":"trace[1430733978] range","detail":"{range_begin:/registry/services/specs/kube-system/kube-prometheus-stack-kubelet; range_end:; response_count:1; response_revision:24771; }","duration":"129.30299ms","start":"2026-05-06T05:50:01.019723Z","end":"2026-05-06T05:50:01.149026Z","steps":["trace[1430733978] 'agreement among raft nodes before linearized reading' (duration: 129.158567ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:01.149163Z","caller":"traceutil/trace.go:171","msg":"trace[1304681558] transaction","detail":"{read_only:false; response_revision:24771; number_of_response:1; }","duration":"129.363601ms","start":"2026-05-06T05:50:01.019781Z","end":"2026-05-06T05:50:01.149144Z","steps":["trace[1304681558] 'process raft request' (duration: 128.974012ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:01.439774Z","caller":"traceutil/trace.go:171","msg":"trace[896890633] transaction","detail":"{read_only:false; response_revision:24772; number_of_response:1; }","duration":"135.879298ms","start":"2026-05-06T05:50:01.303859Z","end":"2026-05-06T05:50:01.439738Z","steps":["trace[896890633] 'process raft request' (duration: 135.5231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:01.439854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.226011ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-06T05:50:01.439907Z","caller":"traceutil/trace.go:171","msg":"trace[1563652846] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24772; }","duration":"100.354494ms","start":"2026-05-06T05:50:01.339537Z","end":"2026-05-06T05:50:01.439892Z","steps":["trace[1563652846] 'agreement among raft nodes before linearized reading' (duration: 100.116398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:01.65521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.214314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:50:01.65539Z","caller":"traceutil/trace.go:171","msg":"trace[1183399949] linearizableReadLoop","detail":"{readStateIndex:25568; appliedIndex:25566; }","duration":"208.088064ms","start":"2026-05-06T05:50:01.447283Z","end":"2026-05-06T05:50:01.655371Z","steps":["trace[1183399949] 'read index received' (duration: 64.559043ms)","trace[1183399949] 'applied index is now lower than readState.Index' (duration: 143.528091ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:01.65548Z","caller":"traceutil/trace.go:171","msg":"trace[918056522] transaction","detail":"{read_only:false; response_revision:24774; number_of_response:1; }","duration":"209.619322ms","start":"2026-05-06T05:50:01.44585Z","end":"2026-05-06T05:50:01.65547Z","steps":["trace[918056522] 'process raft request' (duration: 209.467518ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:01.655499Z","caller":"traceutil/trace.go:171","msg":"trace[2113016430] transaction","detail":"{read_only:false; response_revision:24773; number_of_response:1; }","duration":"210.066143ms","start":"2026-05-06T05:50:01.445402Z","end":"2026-05-06T05:50:01.655468Z","steps":["trace[2113016430] 'process raft request' (duration: 66.49127ms)","trace[2113016430] 'compare' (duration: 143.093201ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:01.655847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.556155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-05-06T05:50:01.65589Z","caller":"traceutil/trace.go:171","msg":"trace[267100043] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24774; }","duration":"208.609456ms","start":"2026-05-06T05:50:01.44727Z","end":"2026-05-06T05:50:01.65588Z","steps":["trace[267100043] 'agreement among raft nodes before linearized reading' (duration: 208.470133ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:01.659158Z","caller":"traceutil/trace.go:171","msg":"trace[327753008] transaction","detail":"{read_only:false; response_revision:24775; number_of_response:1; }","duration":"109.525464ms","start":"2026-05-06T05:50:01.549617Z","end":"2026-05-06T05:50:01.659143Z","steps":["trace[327753008] 'process raft request' (duration: 109.068923ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:01.659637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.2873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-06T05:50:01.659687Z","caller":"traceutil/trace.go:171","msg":"trace[1945008481] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:24775; }","duration":"117.389052ms","start":"2026-05-06T05:50:01.542287Z","end":"2026-05-06T05:50:01.659676Z","steps":["trace[1945008481] 'agreement among raft nodes before linearized reading' (duration: 117.28741ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:01.932398Z","caller":"traceutil/trace.go:171","msg":"trace[1171811996] transaction","detail":"{read_only:false; response_revision:24776; number_of_response:1; }","duration":"198.186287ms","start":"2026-05-06T05:50:01.734184Z","end":"2026-05-06T05:50:01.93237Z","steps":["trace[1171811996] 'process raft request' (duration: 135.628242ms)","trace[1171811996] 'compare' (duration: 61.868898ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:01.932782Z","caller":"traceutil/trace.go:171","msg":"trace[2080020668] transaction","detail":"{read_only:false; response_revision:24777; number_of_response:1; }","duration":"196.67364ms","start":"2026-05-06T05:50:01.736082Z","end":"2026-05-06T05:50:01.932756Z","steps":["trace[2080020668] 'process raft request' (duration: 196.195119ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:01.932783Z","caller":"traceutil/trace.go:171","msg":"trace[1467809580] linearizableReadLoop","detail":"{readStateIndex:25572; appliedIndex:25569; }","duration":"175.530022ms","start":"2026-05-06T05:50:01.75722Z","end":"2026-05-06T05:50:01.93275Z","steps":["trace[1467809580] 'read index received' (duration: 112.572627ms)","trace[1467809580] 'applied index is now lower than readState.Index' (duration: 62.955655ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:01.932833Z","caller":"traceutil/trace.go:171","msg":"trace[1806316946] transaction","detail":"{read_only:false; response_revision:24778; number_of_response:1; }","duration":"196.201349ms","start":"2026-05-06T05:50:01.736593Z","end":"2026-05-06T05:50:01.932794Z","steps":["trace[1806316946] 'process raft request' (duration: 196.040785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:01.933017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.759548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-05-06T05:50:01.933081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.54301ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-05-06T05:50:01.933132Z","caller":"traceutil/trace.go:171","msg":"trace[881810109] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-magnum-server-0; range_end:; response_count:1; response_revision:24778; }","duration":"130.656162ms","start":"2026-05-06T05:50:01.80246Z","end":"2026-05-06T05:50:01.933116Z","steps":["trace[881810109] 'agreement among raft nodes before linearized reading' (duration: 130.458558ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:01.933142Z","caller":"traceutil/trace.go:171","msg":"trace[1592689257] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24778; }","duration":"175.960102ms","start":"2026-05-06T05:50:01.757153Z","end":"2026-05-06T05:50:01.933113Z","steps":["trace[1592689257] 'agreement among raft nodes before linearized reading' (duration: 175.731567ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:02.453077Z","caller":"traceutil/trace.go:171","msg":"trace[225762639] transaction","detail":"{read_only:false; response_revision:24783; number_of_response:1; }","duration":"129.237728ms","start":"2026-05-06T05:50:02.323811Z","end":"2026-05-06T05:50:02.453049Z","steps":["trace[225762639] 'process raft request' (duration: 128.968662ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:04.59279Z","caller":"traceutil/trace.go:171","msg":"trace[306984174] transaction","detail":"{read_only:false; response_revision:24799; number_of_response:1; }","duration":"121.157543ms","start":"2026-05-06T05:50:04.471586Z","end":"2026-05-06T05:50:04.592744Z","steps":["trace[306984174] 'process raft request' (duration: 120.733083ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:05.020062Z","caller":"traceutil/trace.go:171","msg":"trace[1446640410] transaction","detail":"{read_only:false; response_revision:24800; number_of_response:1; }","duration":"344.69612ms","start":"2026-05-06T05:50:04.675285Z","end":"2026-05-06T05:50:05.019982Z","steps":["trace[1446640410] 'process raft request' (duration: 332.401815ms)","trace[1446640410] 'compare' (duration: 11.770743ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:05.020227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:04.675258Z","time spent":"344.888775ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:05.663734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13171795239406872986,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-06T05:50:05.81585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.2654ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-05-06T05:50:05.816009Z","caller":"traceutil/trace.go:171","msg":"trace[175889277] linearizableReadLoop","detail":"{readStateIndex:25596; appliedIndex:25595; }","duration":"653.058137ms","start":"2026-05-06T05:50:05.162923Z","end":"2026-05-06T05:50:05.815981Z","steps":["trace[175889277] 'read index received' (duration: 540.543031ms)","trace[175889277] 'applied index is now lower than readState.Index' (duration: 112.512096ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:50:05.816124Z","caller":"traceutil/trace.go:171","msg":"trace[1398770402] transaction","detail":"{read_only:false; response_revision:24801; number_of_response:1; }","duration":"791.765082ms","start":"2026-05-06T05:50:05.024293Z","end":"2026-05-06T05:50:05.816058Z","steps":["trace[1398770402] 'process raft request' (duration: 679.150174ms)","trace[1398770402] 'compare' (duration: 111.933652ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:05.816328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.398885ms","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-05-06T05:50:05.816391Z","caller":"traceutil/trace.go:171","msg":"trace[601729941] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24801; }","duration":"653.505227ms","start":"2026-05-06T05:50:05.162864Z","end":"2026-05-06T05:50:05.816369Z","steps":["trace[601729941] 'agreement among raft nodes before linearized reading' (duration: 653.274642ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:05.816419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:05.024275Z","time spent":"791.992388ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-06T05:50:05.816452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:05.162835Z","time spent":"653.60444ms","remote":"127.0.0.1:44118","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":"info","ts":"2026-05-06T05:50:05.821019Z","caller":"traceutil/trace.go:171","msg":"trace[1053402043] transaction","detail":"{read_only:false; response_revision:24802; number_of_response:1; }","duration":"144.869255ms","start":"2026-05-06T05:50:05.676121Z","end":"2026-05-06T05:50:05.820991Z","steps":["trace[1053402043] 'process raft request' (duration: 144.762322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:05.821487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.391181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-06T05:50:05.821592Z","caller":"traceutil/trace.go:171","msg":"trace[920549252] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24802; }","duration":"146.504833ms","start":"2026-05-06T05:50:05.675058Z","end":"2026-05-06T05:50:05.821563Z","steps":["trace[920549252] 'agreement among raft nodes before linearized reading' (duration: 146.209106ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:05.821625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.269412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-06T05:50:05.82167Z","caller":"traceutil/trace.go:171","msg":"trace[2133652038] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:24802; }","duration":"342.365235ms","start":"2026-05-06T05:50:05.479293Z","end":"2026-05-06T05:50:05.821658Z","steps":["trace[2133652038] 'agreement among raft nodes before linearized reading' (duration: 342.280903ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:05.821707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:05.479273Z","time spent":"342.424255ms","remote":"127.0.0.1:44136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":4,"response size":30,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-06T05:50:05.821795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.734899ms","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-05-06T05:50:05.821874Z","caller":"traceutil/trace.go:171","msg":"trace[167673794] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24802; }","duration":"151.854792ms","start":"2026-05-06T05:50:05.669999Z","end":"2026-05-06T05:50:05.821854Z","steps":["trace[167673794] 'agreement among raft nodes before linearized reading' (duration: 151.680898ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:05.822099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.960406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-05-06T05:50:05.822203Z","caller":"traceutil/trace.go:171","msg":"trace[2068649986] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:24802; }","duration":"577.10741ms","start":"2026-05-06T05:50:05.245062Z","end":"2026-05-06T05:50:05.822169Z","steps":["trace[2068649986] 'agreement among raft nodes before linearized reading' (duration: 576.897685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:05.822246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"652.679448ms","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-05-06T05:50:05.822295Z","caller":"traceutil/trace.go:171","msg":"trace[984439032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24802; }","duration":"652.742799ms","start":"2026-05-06T05:50:05.169538Z","end":"2026-05-06T05:50:05.822281Z","steps":["trace[984439032] 'agreement among raft nodes before linearized reading' (duration: 651.711815ms)"],"step_count":1} {"level":"warn","ts":"2026-05-06T05:50:05.822324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:05.16953Z","time spent":"652.78889ms","remote":"127.0.0.1:44118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-06T05:50:05.822289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-06T05:50:05.244977Z","time spent":"577.295864ms","remote":"127.0.0.1:44012","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":17,"response size":30,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-05-06T05:50:06.235479Z","caller":"traceutil/trace.go:171","msg":"trace[457672549] linearizableReadLoop","detail":"{readStateIndex:25607; appliedIndex:25606; }","duration":"147.741364ms","start":"2026-05-06T05:50:06.087697Z","end":"2026-05-06T05:50:06.235438Z","steps":["trace[457672549] 'read index received' (duration: 127.827525ms)","trace[457672549] 'applied index is now lower than readState.Index' (duration: 19.911739ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T05:50:06.235731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.00676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-05-06T05:50:06.235787Z","caller":"traceutil/trace.go:171","msg":"trace[1572519794] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24812; }","duration":"148.141803ms","start":"2026-05-06T05:50:06.087629Z","end":"2026-05-06T05:50:06.235771Z","steps":["trace[1572519794] 'agreement among raft nodes before linearized reading' (duration: 147.985649ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T05:50:06.235869Z","caller":"traceutil/trace.go:171","msg":"trace[100254920] transaction","detail":"{read_only:false; response_revision:24812; number_of_response:1; }","duration":"166.923985ms","start":"2026-05-06T05:50:06.068894Z","end":"2026-05-06T05:50:06.235818Z","steps":["trace[100254920] 'process raft request' (duration: 146.677278ms)","trace[100254920] 'compare' (duration: 19.475889ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T05:53:03.319248Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23657} {"level":"info","ts":"2026-05-06T05:53:03.456505Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23657,"took":"130.604831ms","hash":2585519316,"current-db-size-bytes":59203584,"current-db-size":"59 MB","current-db-size-in-use-bytes":37203968,"current-db-size-in-use":"37 MB"} {"level":"info","ts":"2026-05-06T05:53:03.456611Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2585519316,"revision":23657,"compact-revision":20417} {"level":"info","ts":"2026-05-06T05:53:31.818479Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006c86.wal"} {"level":"info","ts":"2026-05-06T05:58:03.324956Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26631} {"level":"info","ts":"2026-05-06T05:58:03.471649Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26631,"took":"143.643855ms","hash":2145412133,"current-db-size-bytes":59203584,"current-db-size":"59 MB","current-db-size-in-use-bytes":31830016,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-05-06T05:58:03.471725Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2145412133,"revision":26631,"compact-revision":23657} {"level":"info","ts":"2026-05-06T05:58:16.959385Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"34a8f480fa9ab6cb","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-05-06T05:58:16.965323Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-05-06T05:58:16.96548Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-05-06T06:03:03.331394Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28992} {"level":"info","ts":"2026-05-06T06:03:03.462599Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28992,"took":"126.990567ms","hash":1753975831,"current-db-size-bytes":59203584,"current-db-size":"59 MB","current-db-size-in-use-bytes":30908416,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-06T06:03:03.462651Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1753975831,"revision":28992,"compact-revision":26631} {"level":"info","ts":"2026-05-06T06:07:19.966404Z","caller":"traceutil/trace.go:171","msg":"trace[189833636] linearizableReadLoop","detail":"{readStateIndex:34345; appliedIndex:34344; }","duration":"101.578233ms","start":"2026-05-06T06:07:19.864784Z","end":"2026-05-06T06:07:19.966363Z","steps":["trace[189833636] 'read index received' (duration: 62.838091ms)","trace[189833636] 'applied index is now lower than readState.Index' (duration: 38.738552ms)"],"step_count":2} {"level":"info","ts":"2026-05-06T06:07:19.966656Z","caller":"traceutil/trace.go:171","msg":"trace[2088163888] transaction","detail":"{read_only:false; response_revision:33319; number_of_response:1; }","duration":"126.350208ms","start":"2026-05-06T06:07:19.84028Z","end":"2026-05-06T06:07:19.96663Z","steps":["trace[2088163888] 'process raft request' (duration: 87.381101ms)","trace[2088163888] 'compare' (duration: 38.527017ms)"],"step_count":2} {"level":"warn","ts":"2026-05-06T06:07:19.966944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.134796ms","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-05-06T06:07:19.967051Z","caller":"traceutil/trace.go:171","msg":"trace[1092276904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:33319; }","duration":"102.29664ms","start":"2026-05-06T06:07:19.86473Z","end":"2026-05-06T06:07:19.967027Z","steps":["trace[1092276904] 'agreement among raft nodes before linearized reading' (duration: 101.962652ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T06:07:30.285977Z","caller":"traceutil/trace.go:171","msg":"trace[901188202] transaction","detail":"{read_only:false; response_revision:33401; number_of_response:1; }","duration":"180.909831ms","start":"2026-05-06T06:07:30.104993Z","end":"2026-05-06T06:07:30.285902Z","steps":["trace[901188202] 'process raft request' (duration: 180.150793ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T06:07:30.28741Z","caller":"traceutil/trace.go:171","msg":"trace[1210641056] transaction","detail":"{read_only:false; response_revision:33402; number_of_response:1; }","duration":"148.219155ms","start":"2026-05-06T06:07:30.139155Z","end":"2026-05-06T06:07:30.287374Z","steps":["trace[1210641056] 'process raft request' (duration: 147.767214ms)"],"step_count":1} {"level":"info","ts":"2026-05-06T06:08:03.342396Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31279} {"level":"info","ts":"2026-05-06T06:08:03.483771Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31279,"took":"136.117092ms","hash":2397683354,"current-db-size-bytes":59203584,"current-db-size":"59 MB","current-db-size-in-use-bytes":31113216,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-05-06T06:08:03.483827Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2397683354,"revision":31279,"compact-revision":28992} {"level":"info","ts":"2026-05-06T06:13:03.351757Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33642} {"level":"info","ts":"2026-05-06T06:13:03.505795Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33642,"took":"148.708466ms","hash":2888499035,"current-db-size-bytes":60243968,"current-db-size":"60 MB","current-db-size-in-use-bytes":32669696,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-05-06T06:13:03.505849Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2888499035,"revision":33642,"compact-revision":31279} {"level":"info","ts":"2026-05-06T06:18:03.358463Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35871} {"level":"info","ts":"2026-05-06T06:18:03.509916Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35871,"took":"146.216715ms","hash":1734970935,"current-db-size-bytes":61546496,"current-db-size":"62 MB","current-db-size-in-use-bytes":30478336,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-06T06:18:03.509987Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1734970935,"revision":35871,"compact-revision":33642}